builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0042 starttime: 1473199934.25 results: success (0) buildid: 20160906131016 builduid: f7da259f18a34f608193a063c3eb4a00 revision: 4ee5ddeeee281b9b6f564fc56efb1713da8d7eac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:14.254666) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:14.255110) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:14.255419) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:14.407463) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:14.407857) ========= 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.c06f1oHtUi/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.vUqSBDPvxe/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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-09-06 15:12:14-- 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% 173M=0s 2016-09-06 15:12:14 (173 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.576975 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:15.010208) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:15.010570) ========= 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.c06f1oHtUi/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.vUqSBDPvxe/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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.026969 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:15.059945) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 15:12:15.060318) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4ee5ddeeee281b9b6f564fc56efb1713da8d7eac --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 4ee5ddeeee281b9b6f564fc56efb1713da8d7eac --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c06f1oHtUi/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.vUqSBDPvxe/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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-09-06 15:12:15,144 truncating revision to first 12 chars 2016-09-06 15:12:15,144 Setting DEBUG logging. 2016-09-06 15:12:15,145 attempt 1/10 2016-09-06 15:12:15,145 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4ee5ddeeee28?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 15:12:16,349 unpacking tar archive at: mozilla-central-4ee5ddeeee28/testing/mozharness/ program finished with exit code 0 elapsedTime=1.456316 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 15:12:16.592455) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:16.592775) ========= script_repo_revision: 4ee5ddeeee281b9b6f564fc56efb1713da8d7eac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:16.593208) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:16.593515) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 15:12:16.615056) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-06 15:12:16.615380) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c06f1oHtUi/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.vUqSBDPvxe/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 15:12:16 INFO - MultiFileLogger online at 20160906 15:12:16 in /builds/slave/test 15:12:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:12:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:12:16 INFO - {'append_to_log': False, 15:12:16 INFO - 'base_work_dir': '/builds/slave/test', 15:12:16 INFO - 'blob_upload_branch': 'Firefox', 15:12:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:12:16 INFO - 'branch': 'Firefox', 15:12:16 INFO - 'buildbot_json_path': 'buildprops.json', 15:12:16 INFO - 'config_files': ('talos/mac_config.py',), 15:12:16 INFO - 'default_actions': ('clobber', 15:12:16 INFO - 'read-buildbot-config', 15:12:16 INFO - 'download-and-extract', 15:12:16 INFO - 'populate-webroot', 15:12:16 INFO - 'create-virtualenv', 15:12:16 INFO - 'install', 15:12:16 INFO - 'run-tests'), 15:12:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:12:16 INFO - 'download_minidump_stackwalk': True, 15:12:16 INFO - 'download_symbols': 'ondemand', 15:12:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:12:16 INFO - 'tooltool.py': '/tools/tooltool.py', 15:12:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:12:16 INFO - '/tools/misc-python/virtualenv.py')}, 15:12:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:12:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:12:16 INFO - 'installer_path': 'installer.exe', 15:12:16 INFO - 'log_level': 'info', 15:12:16 INFO - 'log_name': 'talos', 15:12:16 INFO - 'log_to_console': True, 15:12:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:12:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:12:16 INFO - 'opt_config_files': (), 15:12:16 INFO - 'pip_index': False, 15:12:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:12:16 INFO - 'cmd': ('bash', 15:12:16 INFO - '-c', 15:12:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:12:16 INFO - 'enabled': True, 15:12:16 INFO - 'halt_on_failure': False, 15:12:16 INFO - 'name': 'check_screen_resolution'},), 15:12:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:12:16 INFO - 'cmd': ('bash', 15:12:16 INFO - '-c', 15:12:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:12:16 INFO - 'enabled': True, 15:12:16 INFO - 'halt_on_failure': False, 15:12:16 INFO - 'name': 'check_screen_resolution'},), 15:12:16 INFO - 'run_cmd_checks_enabled': True, 15:12:16 INFO - 'sps_profile': False, 15:12:16 INFO - 'sps_profile_interval': 0, 15:12:16 INFO - 'suite': 'dromaeojs-e10s', 15:12:16 INFO - 'system_bits': '32', 15:12:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:12:16 INFO - 'title': 't-yosemite-r7-0042', 15:12:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:12:16 INFO - 'use_talos_json': True, 15:12:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:12:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:12:16 INFO - 'work_dir': 'build'} 15:12:16 INFO - [mozharness: 2016-09-06 22:12:16.812080Z] Running clobber step. 15:12:16 INFO - Running pre-action listener: _resource_record_pre_action 15:12:16 INFO - Running main action method: clobber 15:12:16 INFO - rmtree: /builds/slave/test/build 15:12:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:12:18 INFO - Running post-action listener: _resource_record_post_action 15:12:18 INFO - [mozharness: 2016-09-06 22:12:18.946531Z] Finished clobber step (success) 15:12:18 INFO - [mozharness: 2016-09-06 22:12:18.946777Z] Running read-buildbot-config step. 15:12:18 INFO - Running pre-action listener: _resource_record_pre_action 15:12:18 INFO - Running main action method: read_buildbot_config 15:12:18 INFO - Using buildbot properties: 15:12:18 INFO - { 15:12:18 INFO - "project": "", 15:12:18 INFO - "product": "firefox", 15:12:18 INFO - "script_repo_revision": "production", 15:12:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:12:18 INFO - "repository": "", 15:12:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:12:18 INFO - "buildid": "20160906131016", 15:12:18 INFO - "pgo_build": "False", 15:12:18 INFO - "basedir": "/builds/slave/test", 15:12:18 INFO - "buildnumber": 76, 15:12:18 INFO - "slavename": "t-yosemite-r7-0042", 15:12:18 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:12:18 INFO - "platform": "yosemite_r7", 15:12:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:12:18 INFO - "branch": "mozilla-central", 15:12:18 INFO - "repo_path": "mozilla-central", 15:12:18 INFO - "revision": "4ee5ddeeee281b9b6f564fc56efb1713da8d7eac", 15:12:18 INFO - "stage_platform": "macosx64", 15:12:18 INFO - "builduid": "f7da259f18a34f608193a063c3eb4a00", 15:12:18 INFO - "slavebuilddir": "test" 15:12:18 INFO - } 15:12:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:12:18 INFO - Running post-action listener: _resource_record_post_action 15:12:18 INFO - [mozharness: 2016-09-06 22:12:18.952790Z] Finished read-buildbot-config step (success) 15:12:18 INFO - [mozharness: 2016-09-06 22:12:18.953040Z] Running download-and-extract step. 15:12:18 INFO - Running pre-action listener: _resource_record_pre_action 15:12:18 INFO - Running main action method: download_and_extract 15:12:18 INFO - mkdir: /builds/slave/test/build/tests 15:12:18 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:18 INFO - trying https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:12:18 INFO - Downloading https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:12:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 15:12:20 INFO - Downloaded 1199 bytes. 15:12:20 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:12:20 INFO - Using the following test package requirements: 15:12:20 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:12:20 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:12:20 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:12:20 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:12:20 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:12:20 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:12:20 INFO - u'jsshell-mac.zip'], 15:12:20 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:12:20 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:12:20 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:12:20 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:12:20 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:12:20 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:12:20 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:12:20 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:12:20 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:12:20 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:12:20 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:12:20 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:12:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:12:20 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:12:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:12:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:12:28 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:12:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:12:36 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:36 INFO - trying https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:12:36 INFO - Downloading https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:12:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 15:12:39 INFO - Downloaded 111516873 bytes. 15:12:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:12:39 INFO - mkdir: /builds/slave/test/properties 15:12:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:12:39 INFO - Writing to file /builds/slave/test/properties/build_url 15:12:39 INFO - Contents: 15:12:39 INFO - build_url:https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:12:40 INFO - Running post-action listener: _resource_record_post_action 15:12:40 INFO - Running post-action listener: set_extra_try_arguments 15:12:40 INFO - [mozharness: 2016-09-06 22:12:40.205711Z] Finished download-and-extract step (success) 15:12:40 INFO - [mozharness: 2016-09-06 22:12:40.205851Z] Running populate-webroot step. 15:12:40 INFO - Running pre-action listener: _resource_record_pre_action 15:12:40 INFO - Running main action method: populate_webroot 15:12:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:12:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:12:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:12:40 INFO - '--tppagecycles', 15:12:40 INFO - '7'], 15:12:40 INFO - 'tests': ['tsvgm']}}, 15:12:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:12:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:12:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:12:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:12:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:12:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:12:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:40 INFO - 'talos_options': ['--disable-e10s'], 15:12:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:12:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:12:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:40 INFO - 'talos_options': ['--disable-e10s'], 15:12:40 INFO - 'tests': ['damp', 'tps']}, 15:12:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:40 INFO - 'tests': ['damp', 'tps']}, 15:12:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:12:40 INFO - 'tests': ['dromaeo_dom']}, 15:12:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:12:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:12:40 INFO - 'tests': ['basic_compositor_video']}, 15:12:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:12:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:12:40 INFO - 'tests': ['a11yr', 15:12:40 INFO - 'ts_paint', 15:12:40 INFO - 'tpaint', 15:12:40 INFO - 'sessionrestore', 15:12:40 INFO - 'sessionrestore_no_auto_restore', 15:12:40 INFO - 'tabpaint']}, 15:12:40 INFO - 'other-e10s': {'tests': ['a11yr', 15:12:40 INFO - 'ts_paint', 15:12:40 INFO - 'tpaint', 15:12:40 INFO - 'sessionrestore', 15:12:40 INFO - 'sessionrestore_no_auto_restore', 15:12:40 INFO - 'tabpaint']}, 15:12:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:12:40 INFO - 'ts_paint', 15:12:40 INFO - 'tpaint', 15:12:40 INFO - 'sessionrestore', 15:12:40 INFO - 'sessionrestore_no_auto_restore', 15:12:40 INFO - 'tabpaint']}, 15:12:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:12:40 INFO - 'ts_paint', 15:12:40 INFO - 'tpaint', 15:12:40 INFO - 'sessionrestore', 15:12:40 INFO - 'sessionrestore_no_auto_restore', 15:12:40 INFO - 'tabpaint']}, 15:12:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:12:40 INFO - 'tests': ['a11yr', 15:12:40 INFO - 'ts_paint', 15:12:40 INFO - 'tpaint', 15:12:40 INFO - 'sessionrestore', 15:12:40 INFO - 'sessionrestore_no_auto_restore', 15:12:40 INFO - 'tabpaint']}, 15:12:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:12:40 INFO - 'tests': ['a11yr', 15:12:40 INFO - 'ts_paint', 15:12:40 INFO - 'tpaint', 15:12:40 INFO - 'sessionrestore', 15:12:40 INFO - 'sessionrestore_no_auto_restore', 15:12:40 INFO - 'tabpaint']}, 15:12:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:12:40 INFO - 'tests': ['tsvgx', 15:12:40 INFO - 'tsvgr_opacity', 15:12:40 INFO - 'tart', 15:12:40 INFO - 'tscrollx', 15:12:40 INFO - 'cart']}, 15:12:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:12:40 INFO - 'tsvgr_opacity', 15:12:40 INFO - 'tart', 15:12:40 INFO - 'tscrollx', 15:12:40 INFO - 'cart']}, 15:12:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:40 INFO - 'talos_options': ['--disable-e10s'], 15:12:40 INFO - 'tests': ['tp5o']}, 15:12:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:40 INFO - 'tests': ['tp5o']}, 15:12:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:40 INFO - 'talos_options': ['--disable-e10s', 15:12:40 INFO - '--xperf_path', 15:12:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:12:40 INFO - 'tests': ['tp5n']}, 15:12:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:12:40 INFO - 'talos_options': ['--xperf_path', 15:12:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:12:40 INFO - 'tests': ['tp5n']}}, 15:12:40 INFO - 'talos.zip': {'path': '', 15:12:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:12:40 INFO - Running post-action listener: _resource_record_post_action 15:12:40 INFO - [mozharness: 2016-09-06 22:12:40.224581Z] Finished populate-webroot step (success) 15:12:40 INFO - [mozharness: 2016-09-06 22:12:40.224694Z] Running create-virtualenv step. 15:12:40 INFO - Running pre-action listener: _resource_record_pre_action 15:12:40 INFO - Running main action method: create_virtualenv 15:12:40 INFO - Creating virtualenv /builds/slave/test/build/venv 15:12:40 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 15:12:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:12:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:12:40 INFO - Using real prefix '/tools/python27' 15:12:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:12:41 INFO - Installing distribute.............................................................................................................................................................................................done. 15:12:43 INFO - Installing pip.................done. 15:12:43 INFO - Return code: 0 15:12:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:12:43 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:43 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:43 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 0x10707aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10710f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbda1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fded30>, '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 0x106d43db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6b20>, '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.c06f1oHtUi/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.vUqSBDPvxe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 15:12:43 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 15:12:43 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 15:12:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c06f1oHtUi/Render', 15:12:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:43 INFO - 'HOME': '/Users/cltbld', 15:12:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:43 INFO - 'LOGNAME': 'cltbld', 15:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:43 INFO - 'MOZ_NO_REMOTE': '1', 15:12:43 INFO - 'NO_EM_RESTART': '1', 15:12:43 INFO - 'PAGER': '/bin/cat', 15:12:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:43 INFO - 'PWD': '/builds/slave/test', 15:12:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:43 INFO - 'SHELL': '/bin/bash', 15:12:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vUqSBDPvxe/Listeners', 15:12:43 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 15:12:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:43 INFO - 'USER': 'cltbld', 15:12:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:43 INFO - 'XPC_FLAGS': '0x0', 15:12:43 INFO - 'XPC_SERVICE_NAME': '0', 15:12:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:44 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 15:12:44 INFO - Cleaning up... 15:12:44 INFO - Return code: 0 15:12:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:44 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 0x10707aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10710f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbda1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fded30>, '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 0x106d43db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6b20>, '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.c06f1oHtUi/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.vUqSBDPvxe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 15:12:44 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 15:12:44 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 15:12:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c06f1oHtUi/Render', 15:12:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:44 INFO - 'HOME': '/Users/cltbld', 15:12:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:44 INFO - 'LOGNAME': 'cltbld', 15:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:44 INFO - 'MOZ_NO_REMOTE': '1', 15:12:44 INFO - 'NO_EM_RESTART': '1', 15:12:44 INFO - 'PAGER': '/bin/cat', 15:12:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:44 INFO - 'PWD': '/builds/slave/test', 15:12:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:44 INFO - 'SHELL': '/bin/bash', 15:12:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vUqSBDPvxe/Listeners', 15:12:44 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 15:12:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:44 INFO - 'USER': 'cltbld', 15:12:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:44 INFO - 'XPC_FLAGS': '0x0', 15:12:44 INFO - 'XPC_SERVICE_NAME': '0', 15:12:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:44 INFO - Downloading/unpacking psutil>=3.1.1 15:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:12:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:12:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:12:47 INFO - Installing collected packages: psutil 15:12:47 INFO - Running setup.py install for psutil 15:12:48 INFO - building 'psutil._psutil_osx' extension 15:12:48 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 15:12:48 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 15:12:48 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 15:12:48 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 15:12:48 INFO - building 'psutil._psutil_posix' extension 15:12:48 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 15:12:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:12:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:12:48 INFO - ^ 15:12:48 INFO - 1 warning generated. 15:12:48 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 15:12:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:12:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:12:49 INFO - Successfully installed psutil 15:12:49 INFO - Cleaning up... 15:12:49 INFO - Return code: 0 15:12:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:49 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 0x10707aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10710f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbda1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fded30>, '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 0x106d43db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6b20>, '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.c06f1oHtUi/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.vUqSBDPvxe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 15:12:49 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 15:12:49 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 15:12:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c06f1oHtUi/Render', 15:12:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:49 INFO - 'HOME': '/Users/cltbld', 15:12:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:49 INFO - 'LOGNAME': 'cltbld', 15:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:49 INFO - 'MOZ_NO_REMOTE': '1', 15:12:49 INFO - 'NO_EM_RESTART': '1', 15:12:49 INFO - 'PAGER': '/bin/cat', 15:12:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:49 INFO - 'PWD': '/builds/slave/test', 15:12:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:49 INFO - 'SHELL': '/bin/bash', 15:12:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vUqSBDPvxe/Listeners', 15:12:49 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 15:12:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:49 INFO - 'USER': 'cltbld', 15:12:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:49 INFO - 'XPC_FLAGS': '0x0', 15:12:49 INFO - 'XPC_SERVICE_NAME': '0', 15:12:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:12:49 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 15:12:49 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 15:12:49 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 15:12:49 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 15:12:49 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 15:12:49 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 15:12:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:12:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:12:52 INFO - Installing collected packages: mozsystemmonitor 15:12:52 INFO - Running setup.py install for mozsystemmonitor 15:12:52 INFO - Successfully installed mozsystemmonitor 15:12:52 INFO - Cleaning up... 15:12:52 INFO - Return code: 0 15:12:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:12: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:52 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 0x10707aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10710f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbda1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fded30>, '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 0x106d43db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6b20>, '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.c06f1oHtUi/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.vUqSBDPvxe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 15:12:52 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 15:12:52 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 15:12:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c06f1oHtUi/Render', 15:12:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:52 INFO - 'HOME': '/Users/cltbld', 15:12:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:52 INFO - 'LOGNAME': 'cltbld', 15:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:52 INFO - 'MOZ_NO_REMOTE': '1', 15:12:52 INFO - 'NO_EM_RESTART': '1', 15:12:52 INFO - 'PAGER': '/bin/cat', 15:12:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:52 INFO - 'PWD': '/builds/slave/test', 15:12:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:52 INFO - 'SHELL': '/bin/bash', 15:12:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vUqSBDPvxe/Listeners', 15:12:52 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 15:12:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:52 INFO - 'USER': 'cltbld', 15:12:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:52 INFO - 'XPC_FLAGS': '0x0', 15:12:52 INFO - 'XPC_SERVICE_NAME': '0', 15:12:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:52 INFO - Downloading/unpacking jsonschema==2.5.1 15:12:52 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 15:12:52 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 15:12:52 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 15:12:52 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 15:12:52 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 15:12:52 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 15:12:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:12:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:12:54 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 15:12:54 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 15:12:54 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 15:12:54 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 15:12:54 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 15:12:54 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 15:12:55 INFO - Downloading functools32-3.2.3-2.tar.gz 15:12:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:12:55 INFO - warning: no files found matching '*.txt' 15:12:55 INFO - no previously-included directories found matching 'build' 15:12:55 INFO - no previously-included directories found matching 'dist' 15:12:55 INFO - no previously-included directories found matching '.git*' 15:12:55 INFO - Installing collected packages: jsonschema, functools32 15:12:55 INFO - Running setup.py install for functools32 15:12:55 INFO - warning: no files found matching '*.txt' 15:12:55 INFO - no previously-included directories found matching 'build' 15:12:55 INFO - no previously-included directories found matching 'dist' 15:12:55 INFO - no previously-included directories found matching '.git*' 15:12:55 INFO - Successfully installed jsonschema functools32 15:12:55 INFO - Cleaning up... 15:12:55 INFO - Return code: 0 15:12:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:12:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10707aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10710f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbda1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fded30>, '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 0x106d43db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6b20>, '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.c06f1oHtUi/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.vUqSBDPvxe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 15:12:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:12:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c06f1oHtUi/Render', 15:12:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:55 INFO - 'HOME': '/Users/cltbld', 15:12:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:55 INFO - 'LOGNAME': 'cltbld', 15:12:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:55 INFO - 'MOZ_NO_REMOTE': '1', 15:12:55 INFO - 'NO_EM_RESTART': '1', 15:12:55 INFO - 'PAGER': '/bin/cat', 15:12:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:55 INFO - 'PWD': '/builds/slave/test', 15:12:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:55 INFO - 'SHELL': '/bin/bash', 15:12:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vUqSBDPvxe/Listeners', 15:12:55 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 15:12:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:55 INFO - 'USER': 'cltbld', 15:12:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:55 INFO - 'XPC_FLAGS': '0x0', 15:12:55 INFO - 'XPC_SERVICE_NAME': '0', 15:12:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:12:56 INFO - Cleaning up... 15:12:56 INFO - Return code: 0 15:12:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:12:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:56 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 0x10707aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10710f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbda1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fded30>, '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 0x106d43db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6b20>, '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.c06f1oHtUi/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.vUqSBDPvxe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 15:12:56 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 15:12:56 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 15:12:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c06f1oHtUi/Render', 15:12:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:56 INFO - 'HOME': '/Users/cltbld', 15:12:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:56 INFO - 'LOGNAME': 'cltbld', 15:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:56 INFO - 'MOZ_NO_REMOTE': '1', 15:12:56 INFO - 'NO_EM_RESTART': '1', 15:12:56 INFO - 'PAGER': '/bin/cat', 15:12:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:56 INFO - 'PWD': '/builds/slave/test', 15:12:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:56 INFO - 'SHELL': '/bin/bash', 15:12:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vUqSBDPvxe/Listeners', 15:12:56 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 15:12:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:56 INFO - 'USER': 'cltbld', 15:12:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:56 INFO - 'XPC_FLAGS': '0x0', 15:12:56 INFO - 'XPC_SERVICE_NAME': '0', 15:12:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:56 INFO - Downloading/unpacking blobuploader==1.2.4 15:12:56 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 15:12:56 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 15:12:56 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 15:12:56 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 15:12:56 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 15:12:56 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 15:12:58 INFO - Downloading blobuploader-1.2.4.tar.gz 15:12:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:12:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:12:58 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 15:12:58 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 15:12:58 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 15:12:58 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 15:12:58 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 15:12:58 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 15:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:12:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:59 INFO - Downloading docopt-0.6.1.tar.gz 15:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:13:00 INFO - Installing collected packages: blobuploader, requests, docopt 15:13:00 INFO - Running setup.py install for blobuploader 15:13:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:13:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:13:00 INFO - Running setup.py install for requests 15:13:00 INFO - Running setup.py install for docopt 15:13:00 INFO - Successfully installed blobuploader requests docopt 15:13:00 INFO - Cleaning up... 15:13:00 INFO - Return code: 0 15:13:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:13: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:00 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 0x10707aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10710f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbda1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fded30>, '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 0x106d43db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6b20>, '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.c06f1oHtUi/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.vUqSBDPvxe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 15:13:00 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 15:13:00 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 15:13:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c06f1oHtUi/Render', 15:13:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:00 INFO - 'HOME': '/Users/cltbld', 15:13:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:00 INFO - 'LOGNAME': 'cltbld', 15:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:00 INFO - 'MOZ_NO_REMOTE': '1', 15:13:00 INFO - 'NO_EM_RESTART': '1', 15:13:00 INFO - 'PAGER': '/bin/cat', 15:13:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:00 INFO - 'PWD': '/builds/slave/test', 15:13:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:00 INFO - 'SHELL': '/bin/bash', 15:13:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vUqSBDPvxe/Listeners', 15:13:00 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 15:13:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:00 INFO - 'USER': 'cltbld', 15:13:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:00 INFO - 'XPC_FLAGS': '0x0', 15:13:00 INFO - 'XPC_SERVICE_NAME': '0', 15:13:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:13:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-7NuhjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:13:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-6MoDLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:13:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-owzO9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:13:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-KP8ExS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:13:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-zk839A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:13:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-Xa6gpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:13:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-kxA0Hd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:13:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-hpJ0oN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:13:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-s2zTAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:13:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-uStF_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:13:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-iQqbRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:13:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-hzQSQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:13:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-b5Hw77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:13:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-VHz4aD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-wATC89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:13:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-qvv1f4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:13:02 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-ndH07d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:13:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:13:02 INFO - Running setup.py install for manifestparser 15:13:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:13:03 INFO - Running setup.py install for mozcrash 15:13:03 INFO - Running setup.py install for mozdebug 15:13:03 INFO - Running setup.py install for mozdevice 15:13:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:13:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:13:03 INFO - Running setup.py install for mozfile 15:13:03 INFO - Running setup.py install for mozhttpd 15:13:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:13:03 INFO - Running setup.py install for mozinfo 15:13:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:13:03 INFO - Running setup.py install for mozInstall 15:13:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:13:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:13:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:13:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:13:04 INFO - Running setup.py install for mozleak 15:13:04 INFO - Running setup.py install for mozlog 15:13:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:13:04 INFO - Running setup.py install for moznetwork 15:13:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:13:04 INFO - Running setup.py install for mozprocess 15:13:04 INFO - Running setup.py install for mozprofile 15:13:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:13:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:13:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:13:04 INFO - Running setup.py install for mozrunner 15:13:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:13:05 INFO - Running setup.py install for mozscreenshot 15:13:05 INFO - Running setup.py install for moztest 15:13:05 INFO - Running setup.py install for mozversion 15:13:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:13:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:13:05 INFO - Cleaning up... 15:13:05 INFO - Return code: 0 15:13:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:13:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:05 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 0x10707aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10710f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbda1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fded30>, '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 0x106d43db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6b20>, '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.c06f1oHtUi/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.vUqSBDPvxe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 15:13:05 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 15:13:05 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 15:13:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c06f1oHtUi/Render', 15:13:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:05 INFO - 'HOME': '/Users/cltbld', 15:13:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:05 INFO - 'LOGNAME': 'cltbld', 15:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:05 INFO - 'MOZ_NO_REMOTE': '1', 15:13:05 INFO - 'NO_EM_RESTART': '1', 15:13:05 INFO - 'PAGER': '/bin/cat', 15:13:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:05 INFO - 'PWD': '/builds/slave/test', 15:13:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:05 INFO - 'SHELL': '/bin/bash', 15:13:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vUqSBDPvxe/Listeners', 15:13:05 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 15:13:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:05 INFO - 'USER': 'cltbld', 15:13:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:05 INFO - 'XPC_FLAGS': '0x0', 15:13:05 INFO - 'XPC_SERVICE_NAME': '0', 15:13:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:13:05 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-EnS0qk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:13:05 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)) 15:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:13:05 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-48N04A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:13:06 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)) 15:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:13:06 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-QNXjpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:13:06 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)) 15:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:13:06 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-9NFb65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:13:06 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)) 15:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:13:06 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-kBEImn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:13:06 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)) 15:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:13:06 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-jmPW7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:13:06 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)) 15:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:13:06 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-zomBJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:13:06 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)) 15:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:13:06 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-qQcHy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:13:06 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)) 15:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:13:06 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-5WDOMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:13:06 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)) 15:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:13:06 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-wFqE5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:13:06 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)) 15:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:13:06 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-Wn75hN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:13:06 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)) 15:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:13:06 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-RqrISx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:13:07 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)) 15:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:13:07 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-p9O99N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:13:07 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)) 15:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:13:07 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-S81dSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:13:07 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)) 15:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:07 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-39oUiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:07 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)) 15:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:13:07 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-V3WuXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:13:07 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)) 15:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:13:07 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-p5kUXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:13:07 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)) 15:13:07 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)) 15:13:07 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)) 15:13:07 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)) 15:13:07 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)) 15:13:07 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)) 15:13:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:13:07 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 15:13:07 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 15:13:07 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 15:13:07 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 15:13:07 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 15:13:07 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 15:13:10 INFO - Downloading blessings-1.6.tar.gz 15:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:13:10 INFO - Installing collected packages: blessings 15:13:10 INFO - Running setup.py install for blessings 15:13:10 INFO - Successfully installed blessings 15:13:10 INFO - Cleaning up... 15:13:10 INFO - Return code: 0 15:13:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:13:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:13:10 INFO - Reading from file tmpfile_stdout 15:13:10 INFO - Current package versions: 15:13:10 INFO - blessings == 1.6 15:13:10 INFO - blobuploader == 1.2.4 15:13:10 INFO - docopt == 0.6.1 15:13:10 INFO - functools32 == 3.2.3-2 15:13:10 INFO - jsonschema == 2.5.1 15:13:10 INFO - manifestparser == 1.1 15:13:10 INFO - mozInstall == 1.12 15:13:10 INFO - mozcrash == 0.17 15:13:10 INFO - mozdebug == 0.1 15:13:10 INFO - mozdevice == 0.48 15:13:10 INFO - mozfile == 1.2 15:13:10 INFO - mozhttpd == 0.7 15:13:10 INFO - mozinfo == 0.9 15:13:10 INFO - mozleak == 0.1 15:13:10 INFO - mozlog == 3.3 15:13:10 INFO - moznetwork == 0.27 15:13:10 INFO - mozprocess == 0.23 15:13:10 INFO - mozprofile == 0.28 15:13:10 INFO - mozrunner == 6.12 15:13:10 INFO - mozscreenshot == 0.1 15:13:10 INFO - mozsystemmonitor == 0.3 15:13:10 INFO - moztest == 0.7 15:13:10 INFO - mozversion == 1.4 15:13:10 INFO - psutil == 3.1.1 15:13:10 INFO - requests == 1.2.3 15:13:10 INFO - wsgiref == 0.1.2 15:13:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:13:10 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:10 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:10 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 0x10707aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10710f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbda1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fded30>, '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 0x106d43db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6b20>, '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.c06f1oHtUi/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.vUqSBDPvxe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 15:13:10 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 15:13:10 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 15:13:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c06f1oHtUi/Render', 15:13:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:10 INFO - 'HOME': '/Users/cltbld', 15:13:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:10 INFO - 'LOGNAME': 'cltbld', 15:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:10 INFO - 'MOZ_NO_REMOTE': '1', 15:13:10 INFO - 'NO_EM_RESTART': '1', 15:13:10 INFO - 'PAGER': '/bin/cat', 15:13:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:10 INFO - 'PWD': '/builds/slave/test', 15:13:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:10 INFO - 'SHELL': '/bin/bash', 15:13:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vUqSBDPvxe/Listeners', 15:13:10 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 15:13:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:10 INFO - 'USER': 'cltbld', 15:13:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:10 INFO - 'XPC_FLAGS': '0x0', 15:13:10 INFO - 'XPC_SERVICE_NAME': '0', 15:13:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:11 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)) 15:13:11 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)) 15:13:11 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)) 15:13:11 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)) 15:13:11 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)) 15:13:11 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)) 15:13:11 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)) 15:13:11 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)) 15:13:11 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)) 15:13:11 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)) 15:13:11 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)) 15:13:11 INFO - Cleaning up... 15:13:11 INFO - Return code: 0 15:13:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:13: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10707aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10710f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbda1210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fded30>, '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 0x106d43db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6b20>, '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.c06f1oHtUi/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.vUqSBDPvxe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 15:13: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', 'jsonschema'] in /builds/slave/test/build 15:13: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 jsonschema 15:13:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c06f1oHtUi/Render', 15:13:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:11 INFO - 'HOME': '/Users/cltbld', 15:13:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:11 INFO - 'LOGNAME': 'cltbld', 15:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:11 INFO - 'MOZ_NO_REMOTE': '1', 15:13:11 INFO - 'NO_EM_RESTART': '1', 15:13:11 INFO - 'PAGER': '/bin/cat', 15:13:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:11 INFO - 'PWD': '/builds/slave/test', 15:13:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:11 INFO - 'SHELL': '/bin/bash', 15:13:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vUqSBDPvxe/Listeners', 15:13:11 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 15:13:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:11 INFO - 'USER': 'cltbld', 15:13:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:11 INFO - 'XPC_FLAGS': '0x0', 15:13:11 INFO - 'XPC_SERVICE_NAME': '0', 15:13:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:13:11 INFO - Cleaning up... 15:13:11 INFO - Return code: 0 15:13:11 INFO - Running post-action listener: _resource_record_post_action 15:13:11 INFO - Running post-action listener: _start_resource_monitoring 15:13:11 INFO - Starting resource monitoring. 15:13:11 INFO - [mozharness: 2016-09-06 22:13:11.319622Z] Finished create-virtualenv step (success) 15:13:11 INFO - [mozharness: 2016-09-06 22:13:11.320661Z] Running install step. 15:13:11 INFO - Running pre-action listener: _resource_record_pre_action 15:13:11 INFO - Running main action method: install 15:13:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:13:11 INFO - Reading from file tmpfile_stdout 15:13:11 INFO - Detecting whether we're running mozinstall >=1.0... 15:13:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:13:11 INFO - Reading from file tmpfile_stdout 15:13:11 INFO - Output received: 15:13:11 INFO - Usage: mozinstall [options] installer 15:13:11 INFO - Options: 15:13:11 INFO - -h, --help show this help message and exit 15:13:11 INFO - -d DEST, --destination=DEST 15:13:11 INFO - Directory to install application into. [default: 15:13:11 INFO - "/builds/slave/test"] 15:13:11 INFO - --app=APP Application being installed. [default: firefox] 15:13:11 INFO - mkdir: /builds/slave/test/build/application 15:13:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:13:45 INFO - Reading from file tmpfile_stdout 15:13:45 INFO - Output received: 15:13:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:13:45 INFO - Running post-action listener: _resource_record_post_action 15:13:45 INFO - [mozharness: 2016-09-06 22:13:45.745986Z] Finished install step (success) 15:13:45 INFO - [mozharness: 2016-09-06 22:13:45.746157Z] Running run-tests step. 15:13:45 INFO - Running pre-action listener: _resource_record_pre_action 15:13:45 INFO - Running main action method: run_tests 15:13:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:13:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:13:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:13:45 INFO - 2016-09-06 15:13:45.760 screenresolution[1645:8291] starting screenresolution argv=screenresolution get 15:13:45 INFO - 2016-09-06 15:13:45.763 screenresolution[1645:8291] Display 0: 1600x1200x32@60 15:13:45 INFO - 2016-09-06 15:13:45.769 screenresolution[1646:8293] starting screenresolution argv=screenresolution list 15:13:45 INFO - Available Modes on Display 0 15:13:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:13:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:13:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:13:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:13:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:13:45 INFO - Intel Iris: 15:13:45 INFO - Chipset Model: Intel Iris 15:13:45 INFO - Type: GPU 15:13:45 INFO - Bus: Built-In 15:13:45 INFO - VRAM (Dynamic, Max): 1536 MB 15:13:45 INFO - Vendor: Intel (0x8086) 15:13:45 INFO - Device ID: 0x0a2e 15:13:45 INFO - Revision ID: 0x0009 15:13:45 INFO - Displays: 15:13:45 INFO - Display: 15:13:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:13:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:13:45 INFO - Display Serial Number: 1600x1200 60 15:13:45 INFO - Main Display: Yes 15:13:46 INFO - Mirror: Off 15:13:46 INFO - Online: Yes 15:13:46 INFO - Rotation: Supported 15:13:46 INFO - Return code: 0 15:13:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:13:46 INFO - Python 2.7.3 15:13:46 INFO - Return code: 0 15:13:46 INFO - grabbing minidump binary from tooltool 15:13:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:46 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 0x106fded30>, '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 0x106d43db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:13:46 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 15:13:46 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 15:13:46 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 15:13:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:13:46 INFO - Return code: 0 15:13:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:13:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:13:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:13:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:13:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:13:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:13:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c06f1oHtUi/Render', 15:13:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:46 INFO - 'HOME': '/Users/cltbld', 15:13:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:46 INFO - 'LOGNAME': 'cltbld', 15:13:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:13:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:46 INFO - 'MOZ_NO_REMOTE': '1', 15:13:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:13:46 INFO - 'NO_EM_RESTART': '1', 15:13:46 INFO - 'PAGER': '/bin/cat', 15:13:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:46 INFO - 'PWD': '/builds/slave/test', 15:13:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:13:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:46 INFO - 'SHELL': '/bin/bash', 15:13:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vUqSBDPvxe/Listeners', 15:13:46 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 15:13:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:46 INFO - 'USER': 'cltbld', 15:13:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:46 INFO - 'XPC_FLAGS': '0x0', 15:13:46 INFO - 'XPC_SERVICE_NAME': '0', 15:13:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:13:46 INFO - mozversion application_buildid: 20160906131016 15:13:46 INFO - mozversion application_changeset: 4ee5ddeeee281b9b6f564fc56efb1713da8d7eac 15:13:46 INFO - mozversion application_display_name: Nightly 15:13:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:13:46 INFO - mozversion application_name: Firefox 15:13:46 INFO - mozversion application_remotingname: firefox 15:13:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:13:46 INFO - mozversion application_vendor: Mozilla 15:13:46 INFO - mozversion application_version: 51.0a1 15:13:46 INFO - mozversion platform_buildid: 20160906131016 15:13:46 INFO - mozversion platform_changeset: 4ee5ddeeee281b9b6f564fc56efb1713da8d7eac 15:13:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:13:46 INFO - mozversion platform_version: 51.0a1 15:13:46 INFO - using testdate: 1473200026 15:13:46 INFO - actual date: 1473200026 15:13:46 INFO - starting webserver on 'localhost:49279' 15:13:46 INFO - SUITE-START | Running 2 tests 15:13:46 INFO - TEST-START | dromaeo_css 15:13:46 INFO - operating with platform_type : mac_ 15:13:46 INFO - Initialising browser for dromaeo_css test... 15:13:46 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpcsH2B0/profile http://localhost:49279/getInfo.html) 15:13:49 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 15:13:49 INFO - PROCESS | 1656 | colorDepth:24 15:13:49 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 15:13:49 INFO - PROCESS | 1656 | __metrics 15:13:49 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 15:13:49 INFO - TEST-INFO | 1656: exit 0 15:13:49 INFO - Browser initialized. 15:13:49 INFO - Running cycle 1/1 for dromaeo_css test... 15:13:49 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpcsH2B0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:14:01 INFO - PROCESS | 1660 | RSS: Main: 244191232 15:14:01 INFO - PROCESS | 1660 | 15:14:51 INFO - PROCESS | 1660 | 1473200091452 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] 15:17:03 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 15:17:05 INFO - PROCESS | 1660 | RSS: Main: 267034624 15:17:05 INFO - PROCESS | 1660 | 15:20:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 15:20:08 INFO - PROCESS | 1660 | RSS: Main: 276918272 15:20:08 INFO - PROCESS | 1660 | 15:23:10 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 15:23:10 INFO - PROCESS | 1660 | RSS: Main: 277245952 15:23:10 INFO - PROCESS | 1660 | 15:26:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 15:26:13 INFO - PROCESS | 1660 | RSS: Main: 272437248 15:26:13 INFO - PROCESS | 1660 | 15:29:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 15:29:16 INFO - PROCESS | 1660 | RSS: Main: 277819392 15:29:16 INFO - PROCESS | 1660 | 15:32:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 15:32:19 INFO - PROCESS | 1660 | RSS: Main: 280801280 15:32:19 INFO - PROCESS | 1660 | 15:32:19 INFO - PROCESS | 1660 | __start_tp_report 15:32:19 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 15:32:19 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 15:32:19 INFO - PROCESS | 1660 | |i|pagename|runs| 15:32:19 INFO - PROCESS | 1660 | |0;dojo.html;3431.485277208418;3435.3472840160366;3452.5856897172343;3481.8955431337054;3483.19886426122;5810.477057064863;5940.316863560692;5946.167536544887;6006.369331220225;6016.51867850572;5909.084091965741;5971.223740913675;5976.850578735533;5986.161937328778;6028.577999540034;4411.691181617288;5267.789288428458;5364.973175134119;5436.700981446019;5522.144067669517;5488.615796894219;5529.585281103913;5556.416576259495;5564.638298510604;5609.691466969315;5300.946990530084;5327.413984461706;5384.757685904144;5407.675539467606;5429.212764149196;5350.785968561253;5353.598480113968;5356.625036247464;5416.350037995427;5442.074847275933;5293.967676303122;5371.221172929942;5387.649802762847;5392.245085688007;5429.945700542983;5828.5337173026055;5907.202527658754;5977.970110149465;5991.730372133265;6041.214642096499;6180;6191.566590338678;6216.564840461169;6227.968860155715;6242.15730876335;6307.463865571432;6346.333634968308;6349.238091429058;6373.171487706569;6373.203349581302;5875.471207591338;5970.582059255854;6042.462165890337;6083;6126.846828829315;6481.837954051187;6543.411093001652;6546.836329091716;6553.049807777941;6557.573757705829;5822.184894114827;6215.595986260879;6222.719977600928;6236.532260080512;6245.531585131042;6148.600340977911;6178.227721534809;6266.811995640138;6273.498120150377;6292.244930608356;6107.236595425572;6144.986077297285;6164.106204600397;6193.81418557444;6221.688915554204;4715.363425937455;4773.355596994431;5061.772220250097;6063.969125248718;6224.284207316126;4983.825566105242;5013.323201367842;5021.271915572222;5023.39719233687;5035.496450355008;3849.615038496184;4535.523770004102;4549.27211646135;4568.200564901195;4582.77086145698;4834.371531700856;5945.227120474311;6025.849353766103;6062.42406971337;6075.969620151959;6196.721147548371;6287.434130928238;6319.146915166394;6330.556861019776;6342.619442833353;4972.627052971042;5317.494837990384;5866.914620795166;6064.848378790565;6076.696165191723;5574.247476590689;5592.664440133523;5626.409227031185;5626.859328516738;5628.409017053233;6387.808365749035;6519.119918810899;6528.249251336157;6637.7676781313385;6646.534742567974;6368.363163683594;6414.069959855701;6429.58207716497;6443.259025212161;6449;6587.47300215972;6638.369354911276;6644.767433139817;6656.7337306509135;6657.3342665733035;6159.322474527708;6177.004152985708;6203.2245969253845;6303.558750887394;6321.936780632319;6326.082718005955;6347.016212487072;6348.301686814354;6352.491800656029;6367.9044814328745;6163.414475624809;6172.969135154295;6228.532860035425;6232.532560057924;6267.2479302484;6096.817095487142;6105.9694701526205;6128.930927367261;6208.895552223888;6218.657973811303;5275.841724748264;6127.189528148541;6150.616111374905;6189.81430557084;6193.84515387119;6215.192025036717;6217.129601855653;6228.937710622836;6230.127782110418;6230.937690623217;4983.925241121458;6067.089936509559;6098.159935307505;6191.752329906753;6198.0702894566175 15:32:19 INFO - PROCESS | 1660 | |1;ext.html;422.1978241341451;483.1086645139716;487.2545006140604;504.97222652754084;509.86233716896425;37759;40476.98807529813;40592.79703601482;40594.18811623766;40599.594004060025;41293;41810.372844407284;41816;41851.162976740445;41940.79029604855;8185.099639039701;9860.161886239679;9860.359076659994;9871;9890.554925028355;9742.659006934759;9845.803083938352;9852;9853.852192217122;9856.457894815781;9828.312018158731;9837.852432213522;9843.753906152331;9865.852012219822;9867.802643947116;8684.175003374701;9278.211352035085;9680.370775899544;9868.80262394748;9901.85147222785;9744.171745401649;9828.459434731087;9829.754256143653;9846.310758246926;9855.704328870146;39306.803465982775;39507;39581.60418395808;39890.60109398898;40064.39903401451;3224.1133688235923;3250.821204833733;3416.8974930752115;3451.3442445935193;3530.6292839251764;23304.417389565227;30186;30225.69774302251;30717.692823071706;31069.067927962198;3176.9493895283467;3200;3201.9679803201902;3239.2873567815045;3314.038928710671;490588.547057266;492077;497730;498234;498255;57852;58310.4168958305;58620.413795861496;58653.706731466926;58664;34009.659903401145;35205;35379.469307960404;60748.69625651844;61001;16796.496105116865;16826.747598786027;16959;16990.490285291213;17027.829721702874;12908.870911290767;12966.870331296755;13021.414036368196;13038.348082596021;13158.934205328913;16570.337186512403;17917.014564198835;18914.919358870513;19036.238550458103;19054.2378304869;7846.254605812439;7921.148386580826;7926.722564710208;7978.920210797934;7981.401394895406;831.6507067031861;907.9875938328715;920.8158368326361;1053.8313869780798;1134.6822889590928;29716.40567188644;30008.399832003237;30033.399332013676;30263;30613.540796888065;999.7300728803183;1053.2942928238099;1060.2472244706191;1064;1183.556166437586;12698;14818.96267261303;15047.473338433103;15666.530004099895;15675.981061230983;569773;652914;653819.3090345464;663341;664510;56342.436575634536;56573.58566035882;56871.71564142236;56971.43028569661;57305.14042289369;1256.58532684214;1309.7118633900527;1314.8750868667653;1320.4124164746595;1342.4697244588333;25532.872335638946;26069.696515174546;29592;30061;30468.84765576158;49644.75177624089;49905;50129.49870501394;50225.497745023546;50246;498971.010289907;500508;501294;501347;501434.79912812967;21277.659457459977;26034.218973430827;26693.332666683487;26955.730442695323;27752.02867899614;44930.3260451087;45331.773341133085;46452.767736161106;47323.76338118287;47537.049259015315;37128;37308.81345593255;37399.878003659935;37934.430983534716;38206.235875282895;36712.449313260855;36927.630723693495;37485;37686;37988.24023519569;30457.847710761303;37278.881633551035;37815.810920945216;38885.222295553365;38897.80551097226;29367.85316073406;31587.92156551767;32860.83569582137;33917.982460526226;33972.66027339695;25064.12275570346;25391.74608253894;25763.742362576133;25816.8709156453;26113.89585073844 15:32:19 INFO - PROCESS | 1660 | |2;jquery.html;371.45025362463554;372.4506353129135;379.73798079325263;384.3581219363662;404.1532988389325;18804.15381307843;20382.490437739063;20475.795242047592;20678.48303792406;20768.376948691526;19938.70091948622;19938.90641482645;19955.900220498876;20060.996950152472;20399.388018359437;5133.512316329943;5675.148727690838;5826.92201942642;5909.586328956974;5943.078822782476;4114.506259248894;5817.331006934214;6243.531735119862;6515.543911926167;6549.639769812658;6276.278228003793;6521.217453905514;6541.28045914949;6554.147960765118;6563.638999855006;6430.742770289183;6505.577137486096;6518.348165183491;6521.250056243522;6544.3782840630065;5179.326687530634;5446.482584154499;5457.508824205841;5666.716664166775;5671.63134396267;13172.934135329358;13296.135751176143;13395.799063014063;15718.921405392899;25196.370090747696;2033.3493282149718;2266.0935625749667;2619.742523588669;2788.10780550224;2827.39211069619;9868.161206297473;9881.752956176082;9901.108900199017;10354.585816567329;10440.321379110334;1823.9270429182816;1867.1224524473475;1870.6445775302784;1878.4646375782972;1895.5166432559608;110289;115138;116773;123947.38026309901;129259;11499.770004600028;11587.420628968517;11590.13074019452;11813.40932953349;12072.698182545331;14361.281935903373;14407.78388324176;14417.78373324401;14430.98983071175;14452.205128717811;7107.786766397016;8260.256576908107;8614.353923455767;8649.740507784776;8745.387822852466;6988.755393561201;7039.507234493639;8449.112843151504;8544.658213671508;8571.271441927505;4025.0943537703783;4241.130568233505;4514.45826500822;4515.322701594787;4621.19129152397;1608.565687264432;1847.2795609712223;1921.6156768646047;1964.9607007859763;1967.124629539841;676.9221539523018;718.5401343140394;859.8821532008365;930.2790337488366;965.35803690545;20240.595188096155;20539.486512837;20901.85039822794;21118.78881211168;21187.47031324229;904.5617468225553;937.7889974755761;957.4542510768795;971.6542588514958;972.7568107973007;10751.784964300827;11060.502277397536;11073.335599864035;11083.889161108285;11125.054370378608;469193;472357;473047;526853;556075;118294.8170518301;123413.38293308477;123446;130231.34884325518;131376;961.7355227312545;977.5112443778111;982.3663736889666;998.6904059741358;1000.1698590170141;18040.909795450938;18586.44240672782;18691.906540467753;18709.71935420997;18801.529961751065;37646.811765941;54232.72883635556;56362.15456768234;56473.023444179256;56802.431975681364;62501.687491562254;81106;81559.59220204098;82269.17730822615;82433.58783206045;7797.103333116537;7880.487768295043;7952.6421311042295;8147.63335649909;8201.753947381589;8825.470471771714;9338.673146439842;11938.880611193777;12073.517059317528;12417.689557760767;10868.565257389615;10901.783973727928;11008.119350452105;11008.944955275492;11127.387993660426;10503.369797812156;10755.569777208824;10934.945325273322;10990;11106.500207490844;10955.780884382108;10988.945055274673;11014.339139651647;11350.94324528372;11356.488957997075;7587.886181707168;7654.043244594426;7797.688092476237;7810.453268271166;7940.166282540481;9479.715608531755;10412.375257484575;10775.245732798629;10817.350958942488;10935 15:32:19 INFO - PROCESS | 1660 | |3;mootools.html;1504.8946573739836;1569.2075501871552;1581.7390130628446;1743.4856717268412;1869.38310357582;7993.1607181246;8400.621972011259;8659.91340086599;9017.233535149511;9030.23243024342;8640.567971601411;8815.206631403173;8831.028586855457;8864.645414183426;8883.955580222106;2353.9411514712097;2391.234804862445;2399.8200134989856;2400.567897778399;2407.434252950555;2357.49313897512;2377.5363804058215;2402.7477114902945;2407.3861165402886;2415.541047201035;2354.458474550854;2367.95264094718;2385.272491889973;2389.9761002390014;2402.5555272274596;2373.537160253751;2374.489484760776;2386.689730335063;2390.067873529325;2403.134871446278;2366.491204391047;2379.785819276256;2389.199618127929;2389.665446837444;2391.8325717199627;8088.959555202246;8670.479771213748;8802.735917922473;8827.823443531157;8853.822923541558;4980.9252861207115;5368.892622147574;5421.105517589593;5444.564434845203;5444.5916556258435;3599.5500562429697;3619.3578687557965;3635.6727894489622;3686.557613086447;3693.0028892199225;1065.6217042949718;1066.205676770803;1071.4801875837836;1073.0932362153967;1089.3790539392548;11514;11560.421978901106;11655.300681959025;11662.358570278631;11719.824202636968;4570.908581828345;4676.719396836201;4787.832425865078;4833;4839.7096174229655;9143.817123657622;9852.556634951445;9926.305158638826;9932.950335248277;9937.751556211153;4966.205407134841;5315.468453154654;5336.252924590561;5400.081986062379;5414.431484694129;3759.5112635357227;4772.928406073912;4868.85581888256;4907.116718990616;4912.926306105412;3889.3193691103943;3928.371460566296;3999.460072890123;4017.4576432181866;4073.2668119738137;1304.5434098065791;1309.967250818737;1315.1977293850744;1330.8669133086614;1334.5395838435725;703.7325816189815;851.7359618518279;901.085398320705;905.8143080668447;939.6711151097198;5616.943830561642;5794.739236734357;5885.646861188342;5926.7311230672085;5998.130271110665;877.8332116897769;892.5225004622496;936.4007035497287;980.1570649241646;994.2841154368842;3950.2099580083523;3955.980220098939;3973.940390894139;4049.6152865477734;4059.512858457004;10470;10734.087602553871;10748.462576871125;10774.946125269324;10787.352758834495;9049.728508144766;10652.360858348524;10738.140948724084;10738.248322617343;10758.56965721378;7939.523628582304;7970;8199.22107399796;8250.422470427133;8311.584420778936;5698.629589076697;5766.913496297475;5796.21751063601;5879.676617786063;5910.970445147746;9904.702858914245;9924.851127233242;10011.299209055296;10058.899411005796;10097.697069087939;9890.159336456481;10067.899321006696;10207.795844083224;10294.639687610897;10303.587856485656;6530.118434011348;6866.485013624098;7000.264972177848;7035.929640703732;7107;8295.253427191581;8388.119247478928;8479.236868681848;8479.406441549032;8489.617967191367;7389.778306650809;7441.665125069493;7504.812379690551;7610.96194519046;7647.273509016634;7303.890441643273;7335.633218339169;7339.59632220233;7455.962720186364;7484.550926944401;6784.38940495338;6928.930710692828;6935.4104901081955;6945.201301850352;6947.305269473214;4906.852794416173;4949.331840201527;4997.275395067768;5082.161443361804;5083.92374114396;4684.4378674559275;4924.433690125538;4955.925661115014;5037.874053148701;5171.612129090258 15:32:19 INFO - PROCESS | 1660 | |4;prototype.html;303.9908802735918;306.564678157017;311.1257366799294;312.4469688651088;315.51725859435044;4785.401824771903;5618.651523634318;5706.543476521879;5765.567582431318;5813.186153938452;4612.469565999911;5594.356648985368;5603.635763675368;5610.410906854782;5630.436956304377;3505.036115068364;4063.207674503473;4206.107251736716;4229.661627069842;4266.890608441797;3471.704905083071;3512.578490581134;3527.6119626841028;4071.796410179494;4151.97924010381;3398.983005084971;3556.1643013891717;3711.4618380334837;3865.8260378282903;4183.786701856458;4076.385062520789;4162.83348666053;4249.68127390447;4311.698181127323;4328.4156638853665;3072.9231769205726;3392.405569382386;3639.7270204734486;3649.3431182387158;3682.1346983458866;4600.608948239404;4674.532546745332;4820.915294058844;5032.018756342514;5505.862353441156;3824.6749026332795;4391.890202744925;4859.955109651407;4936.284238785356;4937;5326.866828329284;5495.807646732345;5505.871296384232;5844.415558444164;5964.642121472725;3644.5808731995894;3705.906757506542;3973.8674477773984;3987.980060099681;4004.0990777075217;5579;5748.942510574924;5841.73553306089;5949.494292985101;6300.716467758962;3622.710183185339;3994.900127496836;4004.139110091286;4045.6763458923215;4075.8777236682945;3199.4560924642865;3534.717222622184;4115.938260926088;4133.524644665842;4179.26862799009;4025.657819085352;4767.95232047682;4813.15769740294;4878.902421951541;4899.412070551557;4958.9504104959215;5304.336957880265;5740.225069615632;5899.758091925041;6007.849803754941;1139.9658010259539;1188.8811118888043;1224.9816252756216;1400.4538230090272;1403.7613605687059;727.8071311102563;793.4525177627419;825.5913322905201;846.5047946951103;853.6884037326331;5151.347056266026;5239.187925871496;5309.65487243328;5689.800856969986;5702.429757024347;5976.073708575178;6257.124002639634;6289.842753931097;6307.432331090132;6308.369163083747;4740.265258884879;4755.904881902343;5258.526732594085;5285.788568457218;5489.792245706019;3106.891258805931;3277.9346712318593;3299.9175020624675;3331.233816222234;3334.949975750317;6028.432607522076;6184.628922264588;6559.868802623921;6562.343765623399;6606.900896486556;5623.297087864017;6342.39747224013;6368.688282093378;6378.330275321117;6391.808245752635;6074.969625151934;6269.216347956505;6514.7621951829;6534.248561415308;6534.37923397286;5863.560232982629;6158.445739883432;6171.753129874754;6237.376262373727;6260.7495700171485;5422.972885135549;6102.511799055976;6115.7247923844425;6189.907151392823;6228.501719862491;5156.226566015128;5509.650135716775;5759.827205183851;6489.513286503437;6491.350864913471;3943.861964831217;4037.313656678313;4051.6961227907436;4067.9593204067583;4070.6946978976102;6225.937740622536;6274.341194174546;6291.245050593775;6306.905396418965;6316.273628532777;5082.466341034138;5927.525797936241;5973;6020.217371741821;6086.60437071589;5058.418281897481;6015.12780646795;6037.516998640186;6045.883940620747;6187.690615469299;4362.498312694081;5895.4399332063385;5944.286685597583;5965.522758179422;5969.76120955157;4940.580050695731;5332.053800362762;5575.721213939368;6050.30421501533;6104.664243466652;5896.528277737856;5981.641101533922;6003.529135361864;6013.9699301503215;6016.7292471838 15:32:19 INFO - PROCESS | 1660 | |5;yui.html;711.4130842055306;746.5669911451358;752.19139425118;755.2258934592044;772.2586317135549;14700.779488307684;15421.768673469878;15435.536933891974;15456;15493.07041577506;10479;11612.767744645123;11904.7619047619;13828.930855345734;13977.440902363918;657.8421178917073;674.9493787965897;825.5789547330875;840.3403328387217;843.3759018326456;837.6063250272363;850.2602735620019;851.2466467176531;851.3784936995996;852.21170417364;749.4229443328661;896.1307531694246;900.9863903109002;903.525649034259;904.9728508144766;885.6280362247871;889.7464222696533;900.0279697926223;900.9594568244445;903.408267584733;670.306233048797;671.1241829412614;671.5903298987614;671.6238906212487;673.4208580620663;14465.349059292357;14468.565943021726;14530;14539.78190327146;14580.854191458162;12755.808662870064;13404.932975335158;14189.503367382194;14244.92877535616;14255.215963122024;10596.417197054236;10598.78802423955;10633.043026127685;10689.518971646294;10930.890691093067;3734.2158146789207;3738.682212011955;3745.962540374616;3749.268892565951;3876.476675648808;119813;121570;121904;124214.37892810568;124587.75412245851;10889.292196007234;10928.945355273332;10931.070858976916;10947.397893115955;11178.161637877029;17444.825551744572;22515.211967581385;22763.203287884844;22927.426814329774;22948;7718.151003389623;7765.805854853561;7843.803904902423;7908.169642187488;8030.357571394275;4194.517630472535;4219.873403797829;4231.576842315744;4263.978680106579;4275.166342563232;3556.893293201208;3561.554805649294;3612.9638703612627;3665.211979424383;3833.3291673956946;639.3255115852727;701.7228194863001;715.506300652548;721.5382155420535;727.298157278229;461.9561141691534;511.3148381169251;636.7484843486797;643.2148795714545;664.5114942528797;7774.611269436506;8725.258353039935;9038.86441703375;9304.695813786146;9516.857247141299;578.3435800366614;596.680775784953;638.2851206648584;642.881067002606;657.1915748238966;3816.38937769961;4288.549702281278;4389.582989615981;4679.298105284167;4695.319178719066;150891;157424;158003.20998394935;159393;164118;128017;128509;129432.35283823709;129577.40845183043;133981;64615.67692161603;64713.67643161754;100369;102044;102285;9414.482203478874;9955.506673998958;10004.122441478738;10046.8492972604;10112.292139550162;45113.32330014987;48886.75556622194;57185.71407142938;63877.68061159665;64408.35591644023;74775;75559.73320800385;97344.51327743316;99705.53386360662;104937.47531262295;12987.675308117372;13080.607581772561;13267.601971940858;13376.598702038955;13458.461661533429;14065.296735162996;14092.929535352258;14143.222122783345;14237.288135593386;14461.566153015427;9576.521173941135;11057.392467082676;13466.057375983588;13586.252756098114;13859.653508662364;10848.403337816495;12203.75592488163;12557.121001529806;12810.231386116862;13040.347982601023;12918.160319579198;13275.80086298687;13309.401076951366;13344.599662010156;13360.532381366605;10597;10839.67480975572;10847.78304433891;10850.077743391585;11279.09767218604;9301.606613943835;10116.595336186765;10199.847002294822;10988.340699558052;11216.55133794639 15:32:19 INFO - PROCESS | 1660 | __end_tp_report 15:32:19 INFO - PROCESS | 1660 | __start_cc_report 15:32:19 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,376 15:32:19 INFO - PROCESS | 1660 | __end_cc_report 15:32:19 INFO - PROCESS | 1660 | __startTimestamp1473201139978__endTimestamp 15:32:19 INFO - PROCESS | 1660 | 15:32:19 INFO - PROCESS | 1660 | ------- Summary: start ------- 15:32:19 INFO - PROCESS | 1660 | Number of tests: 6 15:32:19 INFO - PROCESS | 1660 | 15:32:19 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5839.08 Median:6096.82 stddev:679.77 (11.1%) stddev-sans-first:655.09 15:32:19 INFO - PROCESS | 1660 | Values: 3431.5 3435.3 3452.6 3481.9 3483.2 5810.5 5940.3 5946.2 6006.4 6016.5 5909.1 5971.2 5976.9 5986.2 6028.6 4411.7 5267.8 5365.0 5436.7 5522.1 5488.6 5529.6 5556.4 5564.6 5609.7 5300.9 5327.4 5384.8 5407.7 5429.2 5350.8 5353.6 5356.6 5416.4 5442.1 5294.0 5371.2 5387.6 5392.2 5429.9 5828.5 5907.2 5978.0 5991.7 6041.2 6180.0 6191.6 6216.6 6228.0 6242.2 6307.5 6346.3 6349.2 6373.2 6373.2 5875.5 5970.6 6042.5 6083.0 6126.8 6481.8 6543.4 6546.8 6553.0 6557.6 5822.2 6215.6 6222.7 6236.5 6245.5 6148.6 6178.2 6266.8 6273.5 6292.2 6107.2 6145.0 6164.1 6193.8 6221.7 4715.4 4773.4 5061.8 6064.0 6224.3 4983.8 5013.3 5021.3 5023.4 5035.5 3849.6 4535.5 4549.3 4568.2 4582.8 4834.4 5945.2 6025.8 6062.4 6076.0 6196.7 6287.4 6319.1 6330.6 6342.6 4972.6 5317.5 5866.9 6064.8 6076.7 5574.2 5592.7 5626.4 5626.9 5628.4 6387.8 6519.1 6528.2 6637.8 6646.5 6368.4 6414.1 6429.6 6443.3 6449.0 6587.5 6638.4 6644.8 6656.7 6657.3 6159.3 6177.0 6203.2 6303.6 6321.9 6326.1 6347.0 6348.3 6352.5 6367.9 6163.4 6173.0 6228.5 6232.5 6267.2 6096.8 6106.0 6128.9 6208.9 6218.7 5275.8 6127.2 6150.6 6189.8 6193.8 6215.2 6217.1 6228.9 6230.1 6230.9 4983.9 6067.1 6098.2 6191.8 6198.1 15:32:19 INFO - PROCESS | 1660 | 15:32:19 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:67445.32 Median:39406.90 stddev:146986.99 (373.0%) stddev-sans-first:147313.18 15:32:19 INFO - PROCESS | 1660 | Values: 422.2 483.1 487.3 505.0 509.9 37759.0 40477.0 40592.8 40594.2 40599.6 41293.0 41810.4 41816.0 41851.2 41940.8 8185.1 9860.2 9860.4 9871.0 9890.6 9742.7 9845.8 9852.0 9853.9 9856.5 9828.3 9837.9 9843.8 9865.9 9867.8 8684.2 9278.2 9680.4 9868.8 9901.9 9744.2 9828.5 9829.8 9846.3 9855.7 39306.8 39507.0 39581.6 39890.6 40064.4 3224.1 3250.8 3416.9 3451.3 3530.6 23304.4 30186.0 30225.7 30717.7 31069.1 3176.9 3200.0 3202.0 3239.3 3314.0 490588.5 492077.0 497730.0 498234.0 498255.0 57852.0 58310.4 58620.4 58653.7 58664.0 34009.7 35205.0 35379.5 60748.7 61001.0 16796.5 16826.7 16959.0 16990.5 17027.8 12908.9 12966.9 13021.4 13038.3 13158.9 16570.3 17917.0 18914.9 19036.2 19054.2 7846.3 7921.1 7926.7 7978.9 7981.4 831.7 908.0 920.8 1053.8 1134.7 29716.4 30008.4 30033.4 30263.0 30613.5 999.7 1053.3 1060.2 1064.0 1183.6 12698.0 14819.0 15047.5 15666.5 15676.0 569773.0 652914.0 653819.3 663341.0 664510.0 56342.4 56573.6 56871.7 56971.4 57305.1 1256.6 1309.7 1314.9 1320.4 1342.5 25532.9 26069.7 29592.0 30061.0 30468.8 49644.8 49905.0 50129.5 50225.5 50246.0 498971.0 500508.0 501294.0 501347.0 501434.8 21277.7 26034.2 26693.3 26955.7 27752.0 44930.3 45331.8 46452.8 47323.8 47537.0 37128.0 37308.8 37399.9 37934.4 38206.2 36712.4 36927.6 37485.0 37686.0 37988.2 30457.8 37278.9 37815.8 38885.2 38897.8 29367.9 31587.9 32860.8 33918.0 33972.7 25064.1 25391.7 25763.7 25816.9 26113.9 15:32:19 INFO - PROCESS | 1660 | 15:32:19 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:31871.30 Median:371.95 stddev:84661.36 (22761.5%) stddev-sans-first:84865.82 15:32:19 INFO - PROCESS | 1660 | Values: 371.5 372.5 379.7 384.4 404.2 18804.2 20382.5 20475.8 20678.5 20768.4 19938.7 19938.9 19955.9 20061.0 20399.4 5133.5 5675.1 5826.9 5909.6 5943.1 4114.5 5817.3 6243.5 6515.5 6549.6 6276.3 6521.2 6541.3 6554.1 6563.6 6430.7 6505.6 6518.3 6521.3 6544.4 5179.3 5446.5 5457.5 5666.7 5671.6 13172.9 13296.1 13395.8 15718.9 25196.4 2033.3 2266.1 2619.7 2788.1 2827.4 9868.2 9881.8 9901.1 10354.6 10440.3 1823.9 1867.1 1870.6 1878.5 1895.5 110289.0 115138.0 116773.0 123947.4 129259.0 11499.8 11587.4 11590.1 11813.4 12072.7 14361.3 14407.8 14417.8 14431.0 14452.2 7107.8 8260.3 8614.4 8649.7 8745.4 6988.8 7039.5 8449.1 8544.7 8571.3 4025.1 4241.1 4514.5 4515.3 4621.2 1608.6 1847.3 1921.6 1965.0 1967.1 676.9 718.5 859.9 930.3 965.4 20240.6 20539.5 20901.9 21118.8 21187.5 904.6 937.8 957.5 971.7 972.8 10751.8 11060.5 11073.3 11083.9 11125.1 469193.0 472357.0 473047.0 526853.0 556075.0 118294.8 123413.4 123446.0 130231.3 131376.0 961.7 977.5 982.4 998.7 1000.2 18040.9 18586.4 18691.9 18709.7 18801.5 37646.8 54232.7 56362.2 56473.0 56802.4 62501.7 81106.0 81559.6 82269.2 82433.6 7797.1 7880.5 7952.6 8147.6 8201.8 8825.5 9338.7 11938.9 12073.5 12417.7 10868.6 10901.8 11008.1 11008.9 11127.4 10503.4 10755.6 10934.9 10990.0 11106.5 10955.8 10988.9 11014.3 11350.9 11356.5 7587.9 7654.0 7797.7 7810.5 7940.2 9479.7 10412.4 10775.2 10817.4 10935.0 15:32:19 INFO - PROCESS | 1660 | 15:32:19 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5694.39 Median:4961.07 stddev:3166.29 (63.8%) stddev-sans-first:3159.52 15:32:20 INFO - PROCESS | 1660 | Values: 1504.9 1569.2 1581.7 1743.5 1869.4 7993.2 8400.6 8659.9 9017.2 9030.2 8640.6 8815.2 8831.0 8864.6 8884.0 2353.9 2391.2 2399.8 2400.6 2407.4 2357.5 2377.5 2402.7 2407.4 2415.5 2354.5 2368.0 2385.3 2390.0 2402.6 2373.5 2374.5 2386.7 2390.1 2403.1 2366.5 2379.8 2389.2 2389.7 2391.8 8089.0 8670.5 8802.7 8827.8 8853.8 4980.9 5368.9 5421.1 5444.6 5444.6 3599.6 3619.4 3635.7 3686.6 3693.0 1065.6 1066.2 1071.5 1073.1 1089.4 11514.0 11560.4 11655.3 11662.4 11719.8 4570.9 4676.7 4787.8 4833.0 4839.7 9143.8 9852.6 9926.3 9933.0 9937.8 4966.2 5315.5 5336.3 5400.1 5414.4 3759.5 4772.9 4868.9 4907.1 4912.9 3889.3 3928.4 3999.5 4017.5 4073.3 1304.5 1310.0 1315.2 1330.9 1334.5 703.7 851.7 901.1 905.8 939.7 5616.9 5794.7 5885.6 5926.7 5998.1 877.8 892.5 936.4 980.2 994.3 3950.2 3956.0 3973.9 4049.6 4059.5 10470.0 10734.1 10748.5 10774.9 10787.4 9049.7 10652.4 10738.1 10738.2 10758.6 7939.5 7970.0 8199.2 8250.4 8311.6 5698.6 5766.9 5796.2 5879.7 5911.0 9904.7 9924.9 10011.3 10058.9 10097.7 9890.2 10067.9 10207.8 10294.6 10303.6 6530.1 6866.5 7000.3 7035.9 7107.0 8295.3 8388.1 8479.2 8479.4 8489.6 7389.8 7441.7 7504.8 7611.0 7647.3 7303.9 7335.6 7339.6 7456.0 7484.6 6784.4 6928.9 6935.4 6945.2 6947.3 4906.9 4949.3 4997.3 5082.2 5083.9 4684.4 4924.4 4955.9 5037.9 5171.6 15:32:20 INFO - PROCESS | 1660 | 15:32:20 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4772.01 Median:5329.46 stddev:1554.60 (29.2%) stddev-sans-first:1522.36 15:32:20 INFO - PROCESS | 1660 | Values: 304.0 306.6 311.1 312.4 315.5 4785.4 5618.7 5706.5 5765.6 5813.2 4612.5 5594.4 5603.6 5610.4 5630.4 3505.0 4063.2 4206.1 4229.7 4266.9 3471.7 3512.6 3527.6 4071.8 4152.0 3399.0 3556.2 3711.5 3865.8 4183.8 4076.4 4162.8 4249.7 4311.7 4328.4 3072.9 3392.4 3639.7 3649.3 3682.1 4600.6 4674.5 4820.9 5032.0 5505.9 3824.7 4391.9 4860.0 4936.3 4937.0 5326.9 5495.8 5505.9 5844.4 5964.6 3644.6 3705.9 3973.9 3988.0 4004.1 5579.0 5748.9 5841.7 5949.5 6300.7 3622.7 3994.9 4004.1 4045.7 4075.9 3199.5 3534.7 4115.9 4133.5 4179.3 4025.7 4768.0 4813.2 4878.9 4899.4 4959.0 5304.3 5740.2 5899.8 6007.8 1140.0 1188.9 1225.0 1400.5 1403.8 727.8 793.5 825.6 846.5 853.7 5151.3 5239.2 5309.7 5689.8 5702.4 5976.1 6257.1 6289.8 6307.4 6308.4 4740.3 4755.9 5258.5 5285.8 5489.8 3106.9 3277.9 3299.9 3331.2 3334.9 6028.4 6184.6 6559.9 6562.3 6606.9 5623.3 6342.4 6368.7 6378.3 6391.8 6075.0 6269.2 6514.8 6534.2 6534.4 5863.6 6158.4 6171.8 6237.4 6260.7 5423.0 6102.5 6115.7 6189.9 6228.5 5156.2 5509.7 5759.8 6489.5 6491.4 3943.9 4037.3 4051.7 4068.0 4070.7 6225.9 6274.3 6291.2 6306.9 6316.3 5082.5 5927.5 5973.0 6020.2 6086.6 5058.4 6015.1 6037.5 6045.9 6187.7 4362.5 5895.4 5944.3 5965.5 5969.8 4940.6 5332.1 5575.7 6050.3 6104.7 5896.5 5981.6 6003.5 6014.0 6016.7 15:32:20 INFO - PROCESS | 1660 | 15:32:20 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:24407.70 Median:3559.22 stddev:40139.66 (1127.8%) stddev-sans-first:40212.83 15:32:20 INFO - PROCESS | 1660 | Values: 711.4 746.6 752.2 755.2 772.3 14700.8 15421.8 15435.5 15456.0 15493.1 10479.0 11612.8 11904.8 13828.9 13977.4 657.8 674.9 825.6 840.3 843.4 837.6 850.3 851.2 851.4 852.2 749.4 896.1 901.0 903.5 905.0 885.6 889.7 900.0 901.0 903.4 670.3 671.1 671.6 671.6 673.4 14465.3 14468.6 14530.0 14539.8 14580.9 12755.8 13404.9 14189.5 14244.9 14255.2 10596.4 10598.8 10633.0 10689.5 10930.9 3734.2 3738.7 3746.0 3749.3 3876.5 119813.0 121570.0 121904.0 124214.4 124587.8 10889.3 10928.9 10931.1 10947.4 11178.2 17444.8 22515.2 22763.2 22927.4 22948.0 7718.2 7765.8 7843.8 7908.2 8030.4 4194.5 4219.9 4231.6 4264.0 4275.2 3556.9 3561.6 3613.0 3665.2 3833.3 639.3 701.7 715.5 721.5 727.3 462.0 511.3 636.7 643.2 664.5 7774.6 8725.3 9038.9 9304.7 9516.9 578.3 596.7 638.3 642.9 657.2 3816.4 4288.5 4389.6 4679.3 4695.3 150891.0 157424.0 158003.2 159393.0 164118.0 128017.0 128509.0 129432.4 129577.4 133981.0 64615.7 64713.7 100369.0 102044.0 102285.0 9414.5 9955.5 10004.1 10046.8 10112.3 45113.3 48886.8 57185.7 63877.7 64408.4 74775.0 75559.7 97344.5 99705.5 104937.5 12987.7 13080.6 13267.6 13376.6 13458.5 14065.3 14092.9 14143.2 14237.3 14461.6 9576.5 11057.4 13466.1 13586.3 13859.7 10848.4 12203.8 12557.1 12810.2 13040.3 12918.2 13275.8 13309.4 13344.6 13360.5 10597.0 10839.7 10847.8 10850.1 11279.1 9301.6 10116.6 10199.8 10988.3 11216.6 15:32:20 INFO - PROCESS | 1660 | -------- Summary: end -------- 15:32:20 INFO - PROCESS | 1660 | 15:32:20 INFO - TEST-INFO | 1660: exit 0 15:32:20 INFO - TEST-OK | dromaeo_css | took 1114251ms 15:32:20 INFO - TEST-START | kraken 15:32:20 INFO - operating with platform_type : mac_ 15:32:20 INFO - Initialising browser for kraken test... 15:32:20 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmp6wbfPb/profile http://localhost:49279/getInfo.html) 15:32:23 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 15:32:23 INFO - PROCESS | 1669 | colorDepth:24 15:32:23 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 15:32:23 INFO - PROCESS | 1669 | __metrics 15:32:23 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 15:32:24 INFO - TEST-INFO | 1669: exit 0 15:32:24 INFO - Browser initialized. 15:32:24 INFO - Running cycle 1/1 for kraken test... 15:32:24 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmp6wbfPb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:32:36 INFO - PROCESS | 1671 | RSS: Main: 244060160 15:32:36 INFO - PROCESS | 1671 | 15:32:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 15:32:40 INFO - PROCESS | 1671 | RSS: Main: 256761856 15:32:40 INFO - PROCESS | 1671 | 15:32:42 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 15:32:43 INFO - PROCESS | 1671 | RSS: Main: 265408512 15:32:43 INFO - PROCESS | 1671 | 15:32:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 15:32:47 INFO - PROCESS | 1671 | RSS: Main: 264044544 15:32:47 INFO - PROCESS | 1671 | 15:32:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 15:32:50 INFO - PROCESS | 1671 | RSS: Main: 266366976 15:32:50 INFO - PROCESS | 1671 | 15:32:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:32:53 INFO - PROCESS | 1671 | RSS: Main: 266760192 15:32:53 INFO - PROCESS | 1671 | 15:32:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 15:32:56 INFO - PROCESS | 1671 | RSS: Main: 267345920 15:32:56 INFO - PROCESS | 1671 | 15:32:59 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 15:33:00 INFO - PROCESS | 1671 | RSS: Main: 267632640 15:33:00 INFO - PROCESS | 1671 | 15:33:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 15:33:04 INFO - PROCESS | 1671 | RSS: Main: 267792384 15:33:04 INFO - PROCESS | 1671 | 15:33:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:33:06 INFO - PROCESS | 1671 | RSS: Main: 267640832 15:33:06 INFO - PROCESS | 1671 | 15:33:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:33:09 INFO - PROCESS | 1671 | RSS: Main: 267599872 15:33:09 INFO - PROCESS | 1671 | 15:33:11 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:33:12 INFO - PROCESS | 1671 | RSS: Main: 267710464 15:33:12 INFO - PROCESS | 1671 | 15:33:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:33:16 INFO - PROCESS | 1671 | RSS: Main: 267833344 15:33:16 INFO - PROCESS | 1671 | 15:33:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:33:19 INFO - PROCESS | 1671 | RSS: Main: 267825152 15:33:19 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 15:33:21 INFO - PROCESS | 1671 | RSS: Main: 270815232 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | __start_tp_report 15:33:21 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 15:33:21 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 15:33:21 INFO - PROCESS | 1671 | |i|pagename|runs| 15:33:21 INFO - PROCESS | 1671 | |0;ai-astar;111;116;116;117;118;118;126;116;118;132 15:33:21 INFO - PROCESS | 1671 | |1;audio-beat-detection;142;109;142;195;96;166;160;142;177;110 15:33:21 INFO - PROCESS | 1671 | |2;audio-dft;199;273;256;266;195;196;225;278;196;197 15:33:21 INFO - PROCESS | 1671 | |3;audio-fft;77;85;76;142;60;61;58;62;136;60 15:33:21 INFO - PROCESS | 1671 | |4;audio-oscillator;69;71;121;68;71;82;111;69;75;121 15:33:21 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;107;86;112;105;89;87;116;114;87;76 15:33:21 INFO - PROCESS | 1671 | |6;imaging-darkroom;91;153;153;109;150;145;97;100;136;138 15:33:21 INFO - PROCESS | 1671 | |7;imaging-desaturate;145;149;87;153;150;117;90;126;88;144 15:33:21 INFO - PROCESS | 1671 | |8;json-parse-financial;55;68;89;87;88;47;51;82;89;87 15:33:21 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;66;70;69;81;71;110;101;103;109;101 15:33:21 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;92;94;103;89;89;90;85;86;85;95 15:33:21 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;159;155;154;154;131;165;151;148;186;170 15:33:21 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;186;172;230;179;147;151;183;240;236;181 15:33:21 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;69;67;70;67;70;69;67;63;76;83 15:33:21 INFO - PROCESS | 1671 | __end_tp_report 15:33:21 INFO - PROCESS | 1671 | __start_cc_report 15:33:21 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,952 15:33:21 INFO - PROCESS | 1671 | __end_cc_report 15:33:21 INFO - PROCESS | 1671 | __startTimestamp1473201201838__endTimestamp 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | ------- Summary: start ------- 15:33:21 INFO - PROCESS | 1671 | Number of tests: 14 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:118.80 Median:118.00 stddev:5.92 (5.0%) stddev-sans-first:5.57 15:33:21 INFO - PROCESS | 1671 | Values: 111.0 116.0 116.0 117.0 118.0 118.0 126.0 116.0 118.0 132.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:143.90 Median:163.00 stddev:31.84 (19.5%) stddev-sans-first:33.77 15:33:21 INFO - PROCESS | 1671 | Values: 142.0 109.0 142.0 195.0 96.0 166.0 160.0 142.0 177.0 110.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:228.10 Median:240.50 stddev:36.06 (15.0%) stddev-sans-first:36.67 15:33:21 INFO - PROCESS | 1671 | Values: 199.0 273.0 256.0 266.0 195.0 196.0 225.0 278.0 196.0 197.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:81.70 Median:61.50 stddev:31.57 (51.3%) stddev-sans-first:33.44 15:33:21 INFO - PROCESS | 1671 | Values: 77.0 85.0 76.0 142.0 60.0 61.0 58.0 62.0 136.0 60.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:85.80 Median:70.00 stddev:22.52 (32.2%) stddev-sans-first:23.05 15:33:21 INFO - PROCESS | 1671 | Values: 69.0 71.0 121.0 68.0 71.0 82.0 111.0 69.0 75.0 121.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:97.90 Median:81.00 stddev:14.36 (17.7%) stddev-sans-first:14.85 15:33:21 INFO - PROCESS | 1671 | Values: 107.0 86.0 112.0 105.0 89.0 87.0 116.0 114.0 87.0 76.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:127.20 Median:151.50 stddev:25.07 (16.5%) stddev-sans-first:22.91 15:33:21 INFO - PROCESS | 1671 | Values: 91.0 153.0 153.0 109.0 150.0 145.0 97.0 100.0 136.0 138.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:124.90 Median:151.50 stddev:27.59 (18.2%) stddev-sans-first:28.28 15:33:21 INFO - PROCESS | 1671 | Values: 145.0 149.0 87.0 153.0 150.0 117.0 90.0 126.0 88.0 144.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:74.30 Median:87.00 stddev:17.33 (19.9%) stddev-sans-first:16.91 15:33:21 INFO - PROCESS | 1671 | Values: 55.0 68.0 89.0 87.0 88.0 47.0 51.0 82.0 89.0 87.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:88.10 Median:67.50 stddev:18.24 (27.0%) stddev-sans-first:17.51 15:33:21 INFO - PROCESS | 1671 | Values: 66.0 70.0 69.0 81.0 71.0 110.0 101.0 103.0 109.0 101.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:89.50 stddev:5.53 (6.2%) stddev-sans-first:5.85 15:33:21 INFO - PROCESS | 1671 | Values: 92.0 94.0 103.0 89.0 89.0 90.0 85.0 86.0 85.0 95.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:157.30 Median:157.00 stddev:14.50 (9.2%) stddev-sans-first:15.37 15:33:21 INFO - PROCESS | 1671 | Values: 159.0 155.0 154.0 154.0 131.0 165.0 151.0 148.0 186.0 170.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.50 Median:184.50 stddev:33.62 (18.2%) stddev-sans-first:35.62 15:33:21 INFO - PROCESS | 1671 | Values: 186.0 172.0 230.0 179.0 147.0 151.0 183.0 240.0 236.0 181.0 15:33:21 INFO - PROCESS | 1671 | 15:33:21 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:69.50 stddev:5.61 (8.1%) stddev-sans-first:5.93 15:33:21 INFO - PROCESS | 1671 | Values: 69.0 67.0 70.0 67.0 70.0 69.0 67.0 63.0 76.0 83.0 15:33:21 INFO - PROCESS | 1671 | -------- Summary: end -------- 15:33:21 INFO - PROCESS | 1671 | 15:33:22 INFO - TEST-INFO | 1671: exit 0 15:33:22 INFO - TEST-OK | kraken | took 62061ms 15:33:22 INFO - SUITE-END | took 1176s 15:33:22 INFO - Completed test suite (00:19:36) 15:33:22 INFO - javascript benchmark 15:33:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7344.851322614353, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3431.485277208418, 3435.3472840160366, 3452.5856897172343, 3481.8955431337054, 3483.19886426122, 5810.477057064863, 5940.316863560692, 5946.167536544887, 6006.369331220225, 6016.51867850572, 5909.084091965741, 5971.223740913675, 5976.850578735533, 5986.161937328778, 6028.577999540034, 4411.691181617288, 5267.789288428458, 5364.973175134119, 5436.700981446019, 5522.144067669517, 5488.615796894219, 5529.585281103913, 5556.416576259495, 5564.638298510604, 5609.691466969315, 5300.946990530084, 5327.413984461706, 5384.757685904144, 5407.675539467606, 5429.212764149196, 5350.785968561253, 5353.598480113968, 5356.625036247464, 5416.350037995427, 5442.074847275933, 5293.967676303122, 5371.221172929942, 5387.649802762847, 5392.245085688007, 5429.945700542983, 5828.5337173026055, 5907.202527658754, 5977.970110149465, 5991.730372133265, 6041.214642096499, 6180.0, 6191.566590338678, 6216.564840461169, 6227.968860155715, 6242.15730876335, 6307.463865571432, 6346.333634968308, 6349.238091429058, 6373.171487706569, 6373.203349581302, 5875.471207591338, 5970.582059255854, 6042.462165890337, 6083.0, 6126.846828829315, 6481.837954051187, 6543.411093001652, 6546.836329091716, 6553.049807777941, 6557.573757705829, 5822.184894114827, 6215.595986260879, 6222.719977600928, 6236.532260080512, 6245.531585131042, 6148.600340977911, 6178.227721534809, 6266.811995640138, 6273.498120150377, 6292.244930608356, 6107.236595425572, 6144.986077297285, 6164.106204600397, 6193.81418557444, 6221.688915554204, 4715.363425937455, 4773.355596994431, 5061.772220250097, 6063.969125248718, 6224.284207316126, 4983.825566105242, 5013.323201367842, 5021.271915572222, 5023.39719233687, 5035.496450355008, 3849.615038496184, 4535.523770004102, 4549.27211646135, 4568.200564901195, 4582.77086145698, 4834.371531700856, 5945.227120474311, 6025.849353766103, 6062.42406971337, 6075.969620151959, 6196.721147548371, 6287.434130928238, 6319.146915166394, 6330.556861019776, 6342.619442833353, 4972.627052971042, 5317.494837990384, 5866.914620795166, 6064.848378790565, 6076.696165191723, 5574.247476590689, 5592.664440133523, 5626.409227031185, 5626.859328516738, 5628.409017053233, 6387.808365749035, 6519.119918810899, 6528.249251336157, 6637.7676781313385, 6646.534742567974, 6368.363163683594, 6414.069959855701, 6429.58207716497, 6443.259025212161, 6449.0, 6587.47300215972, 6638.369354911276, 6644.767433139817, 6656.7337306509135, 6657.3342665733035, 6159.322474527708, 6177.004152985708, 6203.2245969253845, 6303.558750887394, 6321.936780632319, 6326.082718005955, 6347.016212487072, 6348.301686814354, 6352.491800656029, 6367.9044814328745, 6163.414475624809, 6172.969135154295, 6228.532860035425, 6232.532560057924, 6267.2479302484, 6096.817095487142, 6105.9694701526205, 6128.930927367261, 6208.895552223888, 6218.657973811303, 5275.841724748264, 6127.189528148541, 6150.616111374905, 6189.81430557084, 6193.84515387119, 6215.192025036717, 6217.129601855653, 6228.937710622836, 6230.127782110418, 6230.937690623217, 4983.925241121458, 6067.089936509559, 6098.159935307505, 6191.752329906753, 6198.0702894566175], "value": 5797.137320964002, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.1978241341451, 483.1086645139716, 487.2545006140604, 504.97222652754084, 509.86233716896425, 37759.0, 40476.98807529813, 40592.79703601482, 40594.18811623766, 40599.594004060025, 41293.0, 41810.372844407284, 41816.0, 41851.162976740445, 41940.79029604855, 8185.099639039701, 9860.161886239679, 9860.359076659994, 9871.0, 9890.554925028355, 9742.659006934759, 9845.803083938352, 9852.0, 9853.852192217122, 9856.457894815781, 9828.312018158731, 9837.852432213522, 9843.753906152331, 9865.852012219822, 9867.802643947116, 8684.175003374701, 9278.211352035085, 9680.370775899544, 9868.80262394748, 9901.85147222785, 9744.171745401649, 9828.459434731087, 9829.754256143653, 9846.310758246926, 9855.704328870146, 39306.803465982775, 39507.0, 39581.60418395808, 39890.60109398898, 40064.39903401451, 3224.1133688235923, 3250.821204833733, 3416.8974930752115, 3451.3442445935193, 3530.6292839251764, 23304.417389565227, 30186.0, 30225.69774302251, 30717.692823071706, 31069.067927962198, 3176.9493895283467, 3200.0, 3201.9679803201902, 3239.2873567815045, 3314.038928710671, 490588.547057266, 492077.0, 497730.0, 498234.0, 498255.0, 57852.0, 58310.4168958305, 58620.413795861496, 58653.706731466926, 58664.0, 34009.659903401145, 35205.0, 35379.469307960404, 60748.69625651844, 61001.0, 16796.496105116865, 16826.747598786027, 16959.0, 16990.490285291213, 17027.829721702874, 12908.870911290767, 12966.870331296755, 13021.414036368196, 13038.348082596021, 13158.934205328913, 16570.337186512403, 17917.014564198835, 18914.919358870513, 19036.238550458103, 19054.2378304869, 7846.254605812439, 7921.148386580826, 7926.722564710208, 7978.920210797934, 7981.401394895406, 831.6507067031861, 907.9875938328715, 920.8158368326361, 1053.8313869780798, 1134.6822889590928, 29716.40567188644, 30008.399832003237, 30033.399332013676, 30263.0, 30613.540796888065, 999.7300728803183, 1053.2942928238099, 1060.2472244706191, 1064.0, 1183.556166437586, 12698.0, 14818.96267261303, 15047.473338433103, 15666.530004099895, 15675.981061230983, 569773.0, 652914.0, 653819.3090345464, 663341.0, 664510.0, 56342.436575634536, 56573.58566035882, 56871.71564142236, 56971.43028569661, 57305.14042289369, 1256.58532684214, 1309.7118633900527, 1314.8750868667653, 1320.4124164746595, 1342.4697244588333, 25532.872335638946, 26069.696515174546, 29592.0, 30061.0, 30468.84765576158, 49644.75177624089, 49905.0, 50129.49870501394, 50225.497745023546, 50246.0, 498971.010289907, 500508.0, 501294.0, 501347.0, 501434.79912812967, 21277.659457459977, 26034.218973430827, 26693.332666683487, 26955.730442695323, 27752.02867899614, 44930.3260451087, 45331.773341133085, 46452.767736161106, 47323.76338118287, 47537.049259015315, 37128.0, 37308.81345593255, 37399.878003659935, 37934.430983534716, 38206.235875282895, 36712.449313260855, 36927.630723693495, 37485.0, 37686.0, 37988.24023519569, 30457.847710761303, 37278.881633551035, 37815.810920945216, 38885.222295553365, 38897.80551097226, 29367.85316073406, 31587.92156551767, 32860.83569582137, 33917.982460526226, 33972.66027339695, 25064.12275570346, 25391.74608253894, 25763.742362576133, 25816.8709156453, 26113.89585073844], "value": 19529.303345742497, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.45025362463554, 372.4506353129135, 379.73798079325263, 384.3581219363662, 404.1532988389325, 18804.15381307843, 20382.490437739063, 20475.795242047592, 20678.48303792406, 20768.376948691526, 19938.70091948622, 19938.90641482645, 19955.900220498876, 20060.996950152472, 20399.388018359437, 5133.512316329943, 5675.148727690838, 5826.92201942642, 5909.586328956974, 5943.078822782476, 4114.506259248894, 5817.331006934214, 6243.531735119862, 6515.543911926167, 6549.639769812658, 6276.278228003793, 6521.217453905514, 6541.28045914949, 6554.147960765118, 6563.638999855006, 6430.742770289183, 6505.577137486096, 6518.348165183491, 6521.250056243522, 6544.3782840630065, 5179.326687530634, 5446.482584154499, 5457.508824205841, 5666.716664166775, 5671.63134396267, 13172.934135329358, 13296.135751176143, 13395.799063014063, 15718.921405392899, 25196.370090747696, 2033.3493282149718, 2266.0935625749667, 2619.742523588669, 2788.10780550224, 2827.39211069619, 9868.161206297473, 9881.752956176082, 9901.108900199017, 10354.585816567329, 10440.321379110334, 1823.9270429182816, 1867.1224524473475, 1870.6445775302784, 1878.4646375782972, 1895.5166432559608, 110289.0, 115138.0, 116773.0, 123947.38026309901, 129259.0, 11499.770004600028, 11587.420628968517, 11590.13074019452, 11813.40932953349, 12072.698182545331, 14361.281935903373, 14407.78388324176, 14417.78373324401, 14430.98983071175, 14452.205128717811, 7107.786766397016, 8260.256576908107, 8614.353923455767, 8649.740507784776, 8745.387822852466, 6988.755393561201, 7039.507234493639, 8449.112843151504, 8544.658213671508, 8571.271441927505, 4025.0943537703783, 4241.130568233505, 4514.45826500822, 4515.322701594787, 4621.19129152397, 1608.565687264432, 1847.2795609712223, 1921.6156768646047, 1964.9607007859763, 1967.124629539841, 676.9221539523018, 718.5401343140394, 859.8821532008365, 930.2790337488366, 965.35803690545, 20240.595188096155, 20539.486512837, 20901.85039822794, 21118.78881211168, 21187.47031324229, 904.5617468225553, 937.7889974755761, 957.4542510768795, 971.6542588514958, 972.7568107973007, 10751.784964300827, 11060.502277397536, 11073.335599864035, 11083.889161108285, 11125.054370378608, 469193.0, 472357.0, 473047.0, 526853.0, 556075.0, 118294.8170518301, 123413.38293308477, 123446.0, 130231.34884325518, 131376.0, 961.7355227312545, 977.5112443778111, 982.3663736889666, 998.6904059741358, 1000.1698590170141, 18040.909795450938, 18586.44240672782, 18691.906540467753, 18709.71935420997, 18801.529961751065, 37646.811765941, 54232.72883635556, 56362.15456768234, 56473.023444179256, 56802.431975681364, 62501.687491562254, 81106.0, 81559.59220204098, 82269.17730822615, 82433.58783206045, 7797.103333116537, 7880.487768295043, 7952.6421311042295, 8147.63335649909, 8201.753947381589, 8825.470471771714, 9338.673146439842, 11938.880611193777, 12073.517059317528, 12417.689557760767, 10868.565257389615, 10901.783973727928, 11008.119350452105, 11008.944955275492, 11127.387993660426, 10503.369797812156, 10755.569777208824, 10934.945325273322, 10990.0, 11106.500207490844, 10955.780884382108, 10988.945055274673, 11014.339139651647, 11350.94324528372, 11356.488957997075, 7587.886181707168, 7654.043244594426, 7797.688092476237, 7810.453268271166, 7940.166282540481, 9479.715608531755, 10412.375257484575, 10775.245732798629, 10817.350958942488, 10935.0], "value": 9317.383340471537, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1504.8946573739836, 1569.2075501871552, 1581.7390130628446, 1743.4856717268412, 1869.38310357582, 7993.1607181246, 8400.621972011259, 8659.91340086599, 9017.233535149511, 9030.23243024342, 8640.567971601411, 8815.206631403173, 8831.028586855457, 8864.645414183426, 8883.955580222106, 2353.9411514712097, 2391.234804862445, 2399.8200134989856, 2400.567897778399, 2407.434252950555, 2357.49313897512, 2377.5363804058215, 2402.7477114902945, 2407.3861165402886, 2415.541047201035, 2354.458474550854, 2367.95264094718, 2385.272491889973, 2389.9761002390014, 2402.5555272274596, 2373.537160253751, 2374.489484760776, 2386.689730335063, 2390.067873529325, 2403.134871446278, 2366.491204391047, 2379.785819276256, 2389.199618127929, 2389.665446837444, 2391.8325717199627, 8088.959555202246, 8670.479771213748, 8802.735917922473, 8827.823443531157, 8853.822923541558, 4980.9252861207115, 5368.892622147574, 5421.105517589593, 5444.564434845203, 5444.5916556258435, 3599.5500562429697, 3619.3578687557965, 3635.6727894489622, 3686.557613086447, 3693.0028892199225, 1065.6217042949718, 1066.205676770803, 1071.4801875837836, 1073.0932362153967, 1089.3790539392548, 11514.0, 11560.421978901106, 11655.300681959025, 11662.358570278631, 11719.824202636968, 4570.908581828345, 4676.719396836201, 4787.832425865078, 4833.0, 4839.7096174229655, 9143.817123657622, 9852.556634951445, 9926.305158638826, 9932.950335248277, 9937.751556211153, 4966.205407134841, 5315.468453154654, 5336.252924590561, 5400.081986062379, 5414.431484694129, 3759.5112635357227, 4772.928406073912, 4868.85581888256, 4907.116718990616, 4912.926306105412, 3889.3193691103943, 3928.371460566296, 3999.460072890123, 4017.4576432181866, 4073.2668119738137, 1304.5434098065791, 1309.967250818737, 1315.1977293850744, 1330.8669133086614, 1334.5395838435725, 703.7325816189815, 851.7359618518279, 901.085398320705, 905.8143080668447, 939.6711151097198, 5616.943830561642, 5794.739236734357, 5885.646861188342, 5926.7311230672085, 5998.130271110665, 877.8332116897769, 892.5225004622496, 936.4007035497287, 980.1570649241646, 994.2841154368842, 3950.2099580083523, 3955.980220098939, 3973.940390894139, 4049.6152865477734, 4059.512858457004, 10470.0, 10734.087602553871, 10748.462576871125, 10774.946125269324, 10787.352758834495, 9049.728508144766, 10652.360858348524, 10738.140948724084, 10738.248322617343, 10758.56965721378, 7939.523628582304, 7970.0, 8199.22107399796, 8250.422470427133, 8311.584420778936, 5698.629589076697, 5766.913496297475, 5796.21751063601, 5879.676617786063, 5910.970445147746, 9904.702858914245, 9924.851127233242, 10011.299209055296, 10058.899411005796, 10097.697069087939, 9890.159336456481, 10067.899321006696, 10207.795844083224, 10294.639687610897, 10303.587856485656, 6530.118434011348, 6866.485013624098, 7000.264972177848, 7035.929640703732, 7107.0, 8295.253427191581, 8388.119247478928, 8479.236868681848, 8479.406441549032, 8489.617967191367, 7389.778306650809, 7441.665125069493, 7504.812379690551, 7610.96194519046, 7647.273509016634, 7303.890441643273, 7335.633218339169, 7339.59632220233, 7455.962720186364, 7484.550926944401, 6784.38940495338, 6928.930710692828, 6935.4104901081955, 6945.201301850352, 6947.305269473214, 4906.852794416173, 4949.331840201527, 4997.275395067768, 5082.161443361804, 5083.92374114396, 4684.4378674559275, 4924.433690125538, 4955.925661115014, 5037.874053148701, 5171.612129090258], "value": 4591.698851675262, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.9908802735918, 306.564678157017, 311.1257366799294, 312.4469688651088, 315.51725859435044, 4785.401824771903, 5618.651523634318, 5706.543476521879, 5765.567582431318, 5813.186153938452, 4612.469565999911, 5594.356648985368, 5603.635763675368, 5610.410906854782, 5630.436956304377, 3505.036115068364, 4063.207674503473, 4206.107251736716, 4229.661627069842, 4266.890608441797, 3471.704905083071, 3512.578490581134, 3527.6119626841028, 4071.796410179494, 4151.97924010381, 3398.983005084971, 3556.1643013891717, 3711.4618380334837, 3865.8260378282903, 4183.786701856458, 4076.385062520789, 4162.83348666053, 4249.68127390447, 4311.698181127323, 4328.4156638853665, 3072.9231769205726, 3392.405569382386, 3639.7270204734486, 3649.3431182387158, 3682.1346983458866, 4600.608948239404, 4674.532546745332, 4820.915294058844, 5032.018756342514, 5505.862353441156, 3824.6749026332795, 4391.890202744925, 4859.955109651407, 4936.284238785356, 4937.0, 5326.866828329284, 5495.807646732345, 5505.871296384232, 5844.415558444164, 5964.642121472725, 3644.5808731995894, 3705.906757506542, 3973.8674477773984, 3987.980060099681, 4004.0990777075217, 5579.0, 5748.942510574924, 5841.73553306089, 5949.494292985101, 6300.716467758962, 3622.710183185339, 3994.900127496836, 4004.139110091286, 4045.6763458923215, 4075.8777236682945, 3199.4560924642865, 3534.717222622184, 4115.938260926088, 4133.524644665842, 4179.26862799009, 4025.657819085352, 4767.95232047682, 4813.15769740294, 4878.902421951541, 4899.412070551557, 4958.9504104959215, 5304.336957880265, 5740.225069615632, 5899.758091925041, 6007.849803754941, 1139.9658010259539, 1188.8811118888043, 1224.9816252756216, 1400.4538230090272, 1403.7613605687059, 727.8071311102563, 793.4525177627419, 825.5913322905201, 846.5047946951103, 853.6884037326331, 5151.347056266026, 5239.187925871496, 5309.65487243328, 5689.800856969986, 5702.429757024347, 5976.073708575178, 6257.124002639634, 6289.842753931097, 6307.432331090132, 6308.369163083747, 4740.265258884879, 4755.904881902343, 5258.526732594085, 5285.788568457218, 5489.792245706019, 3106.891258805931, 3277.9346712318593, 3299.9175020624675, 3331.233816222234, 3334.949975750317, 6028.432607522076, 6184.628922264588, 6559.868802623921, 6562.343765623399, 6606.900896486556, 5623.297087864017, 6342.39747224013, 6368.688282093378, 6378.330275321117, 6391.808245752635, 6074.969625151934, 6269.216347956505, 6514.7621951829, 6534.248561415308, 6534.37923397286, 5863.560232982629, 6158.445739883432, 6171.753129874754, 6237.376262373727, 6260.7495700171485, 5422.972885135549, 6102.511799055976, 6115.7247923844425, 6189.907151392823, 6228.501719862491, 5156.226566015128, 5509.650135716775, 5759.827205183851, 6489.513286503437, 6491.350864913471, 3943.861964831217, 4037.313656678313, 4051.6961227907436, 4067.9593204067583, 4070.6946978976102, 6225.937740622536, 6274.341194174546, 6291.245050593775, 6306.905396418965, 6316.273628532777, 5082.466341034138, 5927.525797936241, 5973.0, 6020.217371741821, 6086.60437071589, 5058.418281897481, 6015.12780646795, 6037.516998640186, 6045.883940620747, 6187.690615469299, 4362.498312694081, 5895.4399332063385, 5944.286685597583, 5965.522758179422, 5969.76120955157, 4940.580050695731, 5332.053800362762, 5575.721213939368, 6050.30421501533, 6104.664243466652, 5896.528277737856, 5981.641101533922, 6003.529135361864, 6013.9699301503215, 6016.7292471838], "value": 4262.4400776372895, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.4130842055306, 746.5669911451358, 752.19139425118, 755.2258934592044, 772.2586317135549, 14700.779488307684, 15421.768673469878, 15435.536933891974, 15456.0, 15493.07041577506, 10479.0, 11612.767744645123, 11904.7619047619, 13828.930855345734, 13977.440902363918, 657.8421178917073, 674.9493787965897, 825.5789547330875, 840.3403328387217, 843.3759018326456, 837.6063250272363, 850.2602735620019, 851.2466467176531, 851.3784936995996, 852.21170417364, 749.4229443328661, 896.1307531694246, 900.9863903109002, 903.525649034259, 904.9728508144766, 885.6280362247871, 889.7464222696533, 900.0279697926223, 900.9594568244445, 903.408267584733, 670.306233048797, 671.1241829412614, 671.5903298987614, 671.6238906212487, 673.4208580620663, 14465.349059292357, 14468.565943021726, 14530.0, 14539.78190327146, 14580.854191458162, 12755.808662870064, 13404.932975335158, 14189.503367382194, 14244.92877535616, 14255.215963122024, 10596.417197054236, 10598.78802423955, 10633.043026127685, 10689.518971646294, 10930.890691093067, 3734.2158146789207, 3738.682212011955, 3745.962540374616, 3749.268892565951, 3876.476675648808, 119813.0, 121570.0, 121904.0, 124214.37892810568, 124587.75412245851, 10889.292196007234, 10928.945355273332, 10931.070858976916, 10947.397893115955, 11178.161637877029, 17444.825551744572, 22515.211967581385, 22763.203287884844, 22927.426814329774, 22948.0, 7718.151003389623, 7765.805854853561, 7843.803904902423, 7908.169642187488, 8030.357571394275, 4194.517630472535, 4219.873403797829, 4231.576842315744, 4263.978680106579, 4275.166342563232, 3556.893293201208, 3561.554805649294, 3612.9638703612627, 3665.211979424383, 3833.3291673956946, 639.3255115852727, 701.7228194863001, 715.506300652548, 721.5382155420535, 727.298157278229, 461.9561141691534, 511.3148381169251, 636.7484843486797, 643.2148795714545, 664.5114942528797, 7774.611269436506, 8725.258353039935, 9038.86441703375, 9304.695813786146, 9516.857247141299, 578.3435800366614, 596.680775784953, 638.2851206648584, 642.881067002606, 657.1915748238966, 3816.38937769961, 4288.549702281278, 4389.582989615981, 4679.298105284167, 4695.319178719066, 150891.0, 157424.0, 158003.20998394935, 159393.0, 164118.0, 128017.0, 128509.0, 129432.35283823709, 129577.40845183043, 133981.0, 64615.67692161603, 64713.67643161754, 100369.0, 102044.0, 102285.0, 9414.482203478874, 9955.506673998958, 10004.122441478738, 10046.8492972604, 10112.292139550162, 45113.32330014987, 48886.75556622194, 57185.71407142938, 63877.68061159665, 64408.35591644023, 74775.0, 75559.73320800385, 97344.51327743316, 99705.53386360662, 104937.47531262295, 12987.675308117372, 13080.607581772561, 13267.601971940858, 13376.598702038955, 13458.461661533429, 14065.296735162996, 14092.929535352258, 14143.222122783345, 14237.288135593386, 14461.566153015427, 9576.521173941135, 11057.392467082676, 13466.057375983588, 13586.252756098114, 13859.653508662364, 10848.403337816495, 12203.75592488163, 12557.121001529806, 12810.231386116862, 13040.347982601023, 12918.160319579198, 13275.80086298687, 13309.401076951366, 13344.599662010156, 13360.532381366605, 10597.0, 10839.67480975572, 10847.78304433891, 10850.077743391585, 11279.09767218604, 9301.606613943835, 10116.595336186765, 10199.847002294822, 10988.340699558052, 11216.55133794639], "value": 7603.779287079888, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1679.3999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 116.0, 116.0, 117.0, 118.0, 118.0, 126.0, 116.0, 118.0, 132.0], "value": 118.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 142.0, 195.0, 96.0, 166.0, 160.0, 142.0, 177.0, 110.0], "value": 143.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 273.0, 256.0, 266.0, 195.0, 196.0, 225.0, 278.0, 196.0, 197.0], "value": 228.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 85.0, 76.0, 142.0, 60.0, 61.0, 58.0, 62.0, 136.0, 60.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 121.0, 68.0, 71.0, 82.0, 111.0, 69.0, 75.0, 121.0], "value": 85.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 86.0, 112.0, 105.0, 89.0, 87.0, 116.0, 114.0, 87.0, 76.0], "value": 97.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 153.0, 153.0, 109.0, 150.0, 145.0, 97.0, 100.0, 136.0, 138.0], "value": 127.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 149.0, 87.0, 153.0, 150.0, 117.0, 90.0, 126.0, 88.0, 144.0], "value": 124.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 68.0, 89.0, 87.0, 88.0, 47.0, 51.0, 82.0, 89.0, 87.0], "value": 74.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 69.0, 81.0, 71.0, 110.0, 101.0, 103.0, 109.0, 101.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 103.0, 89.0, 89.0, 90.0, 85.0, 86.0, 85.0, 95.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 155.0, 154.0, 154.0, 131.0, 165.0, 151.0, 148.0, 186.0, 170.0], "value": 157.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 172.0, 230.0, 179.0, 147.0, 151.0, 183.0, 240.0, 236.0, 181.0], "value": 190.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 70.0, 67.0, 70.0, 69.0, 67.0, 63.0, 76.0, 83.0], "value": 70.1, "unit": "score"}]}]} 15:33:23 INFO - Return code: 0 15:33:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:33:23 ERROR - # TBPL SUCCESS # 15:33:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:33:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:33:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:33:23 INFO - 2016-09-06 15:33:23.161 screenresolution[1676:11246] starting screenresolution argv=screenresolution get 15:33:23 INFO - 2016-09-06 15:33:23.163 screenresolution[1676:11246] Display 0: 1600x1200x32@60 15:33:23 INFO - 2016-09-06 15:33:23.169 screenresolution[1677:11249] starting screenresolution argv=screenresolution list 15:33:23 INFO - Available Modes on Display 0 15:33:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:33:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:33:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:33:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:33:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:33:23 INFO - Intel Iris: 15:33:23 INFO - Chipset Model: Intel Iris 15:33:23 INFO - Type: GPU 15:33:23 INFO - Bus: Built-In 15:33:23 INFO - VRAM (Dynamic, Max): 1536 MB 15:33:23 INFO - Vendor: Intel (0x8086) 15:33:23 INFO - Device ID: 0x0a2e 15:33:23 INFO - Revision ID: 0x0009 15:33:23 INFO - Displays: 15:33:23 INFO - Display: 15:33:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:33:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:33:23 INFO - Display Serial Number: 1600x1200 60 15:33:23 INFO - Main Display: Yes 15:33:23 INFO - Mirror: Off 15:33:23 INFO - Online: Yes 15:33:23 INFO - Rotation: Supported 15:33:23 INFO - Return code: 0 15:33:23 INFO - Running post-action listener: _resource_record_post_action 15:33:23 INFO - [mozharness: 2016-09-06 22:33:23.405688Z] Finished run-tests step (success) 15:33:23 INFO - Running post-run listener: _resource_record_post_run 15:33:23 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 33176576; Write bytes: 707901952; Read time: 324; Write time: 2113 15:33:23 INFO - TinderboxPrint: CPU usage
54.2% 15:33:23 INFO - TinderboxPrint: I/O read bytes / time
33,176,576 / 324 15:33:23 INFO - TinderboxPrint: I/O write bytes / time
707,901,952 / 2,113 15:33:23 INFO - TinderboxPrint: CPU idle
2,220.2 (45.8%) 15:33:23 INFO - TinderboxPrint: CPU system
973.7 (20.1%) 15:33:23 INFO - TinderboxPrint: CPU user
1,651.8 (34.1%) 15:33:23 INFO - TinderboxPrint: Swap in / out
435,240,960 / 0 15:33:23 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400419328; Write bytes: 394978816; Read time: 29588; Write time: 693 15:33:23 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31140864; Write bytes: 298243072; Read time: 303; Write time: 1387 15:33:23 INFO - Running post-run listener: _upload_blobber_files 15:33:23 INFO - Blob upload gear active. 15:33:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:33:23 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 15:33:23 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'] 15:33:23 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 15:33:24 INFO - (blobuploader) - INFO - Open directory for files ... 15:33:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:33:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:33:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:33:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:33:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:33:25 INFO - (blobuploader) - INFO - Done attempting. 15:33:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:33:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:33:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:33:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:33:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:33:25 INFO - (blobuploader) - INFO - Done attempting. 15:33:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:33:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:33:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:33:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:33:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:33:29 INFO - (blobuploader) - INFO - Done attempting. 15:33:29 INFO - (blobuploader) - INFO - Iteration through files over. 15:33:29 INFO - Return code: 0 15:33:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:33:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:33:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fa03299fb6503d9272284ed5a8bbb81460b93f4a95384f1c583d9edb10150bcf757444d969eb991c374977e7c68fd44b3130b8f21a142c78a7bc3bccd6a813d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90208bb4a6b788a44391f1520ce0d28518b935c4187061ec18b1f9356ef61431821a76ad09be368293a8e32b48ba67449a8c960cc6be54c53b0a2d4bd7fd05b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8ea26b68874277895b41decdf406c69740143bffc6fa867d24b10389e5ed93f3493232e87081144bb01ba7c56f8b7dc2efedbfc81639cdf6d23ee1987a6f081"} 15:33:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:33:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:33:29 INFO - Contents: 15:33:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fa03299fb6503d9272284ed5a8bbb81460b93f4a95384f1c583d9edb10150bcf757444d969eb991c374977e7c68fd44b3130b8f21a142c78a7bc3bccd6a813d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90208bb4a6b788a44391f1520ce0d28518b935c4187061ec18b1f9356ef61431821a76ad09be368293a8e32b48ba67449a8c960cc6be54c53b0a2d4bd7fd05b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8ea26b68874277895b41decdf406c69740143bffc6fa867d24b10389e5ed93f3493232e87081144bb01ba7c56f8b7dc2efedbfc81639cdf6d23ee1987a6f081"} 15:33:29 INFO - Running post-run listener: copy_logs_to_upload_dir 15:33:29 INFO - Copying logs to upload dir... 15:33:29 INFO - mkdir: /builds/slave/test/build/upload/logs 15:33:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.454054 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-06 15:33:29.108771) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 15:33:29.112444) ========= 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.c06f1oHtUi/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.vUqSBDPvxe/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fa03299fb6503d9272284ed5a8bbb81460b93f4a95384f1c583d9edb10150bcf757444d969eb991c374977e7c68fd44b3130b8f21a142c78a7bc3bccd6a813d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90208bb4a6b788a44391f1520ce0d28518b935c4187061ec18b1f9356ef61431821a76ad09be368293a8e32b48ba67449a8c960cc6be54c53b0a2d4bd7fd05b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8ea26b68874277895b41decdf406c69740143bffc6fa867d24b10389e5ed93f3493232e87081144bb01ba7c56f8b7dc2efedbfc81639cdf6d23ee1987a6f081"} build_url:https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011341 build_url: 'https://queue.taskcluster.net/v1/task/WoHH4EWeTiW_zqlw1wUoDw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fa03299fb6503d9272284ed5a8bbb81460b93f4a95384f1c583d9edb10150bcf757444d969eb991c374977e7c68fd44b3130b8f21a142c78a7bc3bccd6a813d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90208bb4a6b788a44391f1520ce0d28518b935c4187061ec18b1f9356ef61431821a76ad09be368293a8e32b48ba67449a8c960cc6be54c53b0a2d4bd7fd05b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8ea26b68874277895b41decdf406c69740143bffc6fa867d24b10389e5ed93f3493232e87081144bb01ba7c56f8b7dc2efedbfc81639cdf6d23ee1987a6f081"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 15:33:29.146665) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:33:29.147056) ========= 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.c06f1oHtUi/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.vUqSBDPvxe/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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.005087 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:33:29.196163) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 15:33:29.196519) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 15:33:29.196884) ========= ========= Total master_lag: 0.23 =========