builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0114 starttime: 1486045464.91 results: success (0) buildid: 20170202052009 builduid: d9aca38237404166b5a28eadd01723e5 revision: dd7cce059626e9787537bf9ba7863a6ad8ac06a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:24.908214) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:24.909132) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:24.909428) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:24.953811) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:24.954108) ========= 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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 --2017-02-02 06:24:24-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.61M=0.001s 2017-02-02 06:24:25 (9.61 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115833 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:25.143521) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:25.143796) ========= 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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.025071 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:25.192951) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 06:24:25.193263) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dd7cce059626e9787537bf9ba7863a6ad8ac06a1 --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 dd7cce059626e9787537bf9ba7863a6ad8ac06a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 2017-02-02 06:24:25,291 truncating revision to first 12 chars 2017-02-02 06:24:25,291 Setting DEBUG logging. 2017-02-02 06:24:25,291 attempt 1/10 2017-02-02 06:24:25,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dd7cce059626?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 06:24:25,950 unpacking tar archive at: mozilla-central-dd7cce059626/testing/mozharness/ program finished with exit code 0 elapsedTime=0.907920 ========= master_lag: 0.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 06:24:26.375162) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:26.375562) ========= script_repo_revision: dd7cce059626e9787537bf9ba7863a6ad8ac06a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:26.375998) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:26.376290) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:26.403772) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-02 06:24:26.404053) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 06:24:26 INFO - MultiFileLogger online at 20170202 06:24:26 in /builds/slave/test 06:24:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:24:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:24:26 INFO - {'append_to_log': False, 06:24:26 INFO - 'base_work_dir': '/builds/slave/test', 06:24:26 INFO - 'blob_upload_branch': 'Firefox', 06:24:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:24:26 INFO - 'branch': 'Firefox', 06:24:26 INFO - 'buildbot_json_path': 'buildprops.json', 06:24:26 INFO - 'config_files': ('talos/mac_config.py',), 06:24:26 INFO - 'default_actions': ('clobber', 06:24:26 INFO - 'read-buildbot-config', 06:24:26 INFO - 'download-and-extract', 06:24:26 INFO - 'populate-webroot', 06:24:26 INFO - 'create-virtualenv', 06:24:26 INFO - 'install', 06:24:26 INFO - 'run-tests'), 06:24:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:24:26 INFO - 'download_minidump_stackwalk': True, 06:24:26 INFO - 'download_symbols': 'ondemand', 06:24:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:24:26 INFO - 'tooltool.py': '/tools/tooltool.py', 06:24:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:24:26 INFO - '/tools/misc-python/virtualenv.py')}, 06:24:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:24:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:24:26 INFO - 'installer_path': 'installer.exe', 06:24:26 INFO - 'log_level': 'info', 06:24:26 INFO - 'log_name': 'talos', 06:24:26 INFO - 'log_to_console': True, 06:24:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:24:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:24:26 INFO - 'opt_config_files': (), 06:24:26 INFO - 'pip_index': False, 06:24:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:24:26 INFO - 'cmd': ('bash', 06:24:26 INFO - '-c', 06:24:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:24:26 INFO - 'enabled': True, 06:24:26 INFO - 'halt_on_failure': False, 06:24:26 INFO - 'name': 'check_screen_resolution'},), 06:24:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:24:26 INFO - 'cmd': ('bash', 06:24:26 INFO - '-c', 06:24:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:24:26 INFO - 'enabled': True, 06:24:26 INFO - 'halt_on_failure': False, 06:24:26 INFO - 'name': 'check_screen_resolution'},), 06:24:26 INFO - 'run_cmd_checks_enabled': True, 06:24:26 INFO - 'sps_profile': False, 06:24:26 INFO - 'sps_profile_interval': 0, 06:24:26 INFO - 'suite': 'dromaeojs', 06:24:26 INFO - 'system_bits': '32', 06:24:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:24:26 INFO - 'title': 't-yosemite-r7-0114', 06:24:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:24:26 INFO - 'use_talos_json': True, 06:24:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:24:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:24:26 INFO - 'work_dir': 'build'} 06:24:26 INFO - [mozharness: 2017-02-02 14:24:26.620119Z] Running clobber step. 06:24:26 INFO - Running pre-action listener: _resource_record_pre_action 06:24:26 INFO - Running main action method: clobber 06:24:26 INFO - rmtree: /builds/slave/test/build 06:24:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:24:29 INFO - Running post-action listener: _resource_record_post_action 06:24:29 INFO - [mozharness: 2017-02-02 14:24:29.169961Z] Finished clobber step (success) 06:24:29 INFO - [mozharness: 2017-02-02 14:24:29.170084Z] Running read-buildbot-config step. 06:24:29 INFO - Running pre-action listener: _resource_record_pre_action 06:24:29 INFO - Running main action method: read_buildbot_config 06:24:29 INFO - Using buildbot properties: 06:24:29 INFO - { 06:24:29 INFO - "project": "", 06:24:29 INFO - "product": "firefox", 06:24:29 INFO - "script_repo_revision": "production", 06:24:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:24:29 INFO - "repository": "", 06:24:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:24:29 INFO - "buildid": "20170202052009", 06:24:29 INFO - "pgo_build": "False", 06:24:29 INFO - "basedir": "/builds/slave/test", 06:24:29 INFO - "buildnumber": 1, 06:24:29 INFO - "slavename": "t-yosemite-r7-0114", 06:24:29 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:24:29 INFO - "platform": "yosemite_r7", 06:24:29 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:24:29 INFO - "branch": "mozilla-central", 06:24:29 INFO - "repo_path": "mozilla-central", 06:24:29 INFO - "revision": "dd7cce059626e9787537bf9ba7863a6ad8ac06a1", 06:24:29 INFO - "stage_platform": "macosx64", 06:24:29 INFO - "builduid": "d9aca38237404166b5a28eadd01723e5", 06:24:29 INFO - "slavebuilddir": "test" 06:24:29 INFO - } 06:24:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 06:24:29 INFO - Running post-action listener: _resource_record_post_action 06:24:29 INFO - [mozharness: 2017-02-02 14:24:29.173301Z] Finished read-buildbot-config step (success) 06:24:29 INFO - [mozharness: 2017-02-02 14:24:29.173432Z] Running download-and-extract step. 06:24:29 INFO - Running pre-action listener: _resource_record_pre_action 06:24:29 INFO - Running main action method: download_and_extract 06:24:29 INFO - mkdir: /builds/slave/test/build/tests 06:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:29 INFO - trying https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 06:24:29 INFO - Downloading https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:24:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 06:24:30 INFO - Downloaded 1199 bytes. 06:24:30 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:24:30 INFO - Using the following test package requirements: 06:24:30 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:24:30 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 06:24:30 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 06:24:30 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'jsshell-mac.zip'], 06:24:30 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 06:24:30 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:24:30 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 06:24:30 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 06:24:30 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 06:24:30 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 06:24:30 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:24:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:24:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:24:30 INFO - Fetch https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:24:31 INFO - Expected file size: 34848321 06:24:31 INFO - Obtained file size: 34848321 06:24:38 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:24:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:24:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:24:38 INFO - Fetch https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:24:39 INFO - Expected file size: 34848321 06:24:39 INFO - Obtained file size: 34848321 06:24:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 06:24:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:24:46 INFO - Fetch https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 06:24:47 INFO - Expected file size: 14403454 06:24:47 INFO - Obtained file size: 14403454 06:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:47 INFO - trying https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:24:47 INFO - Downloading https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 06:24:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 06:24:49 INFO - Downloaded 70195404 bytes. 06:24:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:24:49 INFO - mkdir: /builds/slave/test/properties 06:24:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:24:49 INFO - Writing to file /builds/slave/test/properties/build_url 06:24:49 INFO - Contents: 06:24:49 INFO - build_url:https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:24:50 INFO - Running post-action listener: _resource_record_post_action 06:24:50 INFO - Running post-action listener: set_extra_try_arguments 06:24:50 INFO - [mozharness: 2017-02-02 14:24:50.682124Z] Finished download-and-extract step (success) 06:24:50 INFO - [mozharness: 2017-02-02 14:24:50.682278Z] Running populate-webroot step. 06:24:50 INFO - Running pre-action listener: _resource_record_pre_action 06:24:50 INFO - Running main action method: populate_webroot 06:24:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:24:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:24:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:24:50 INFO - '--tppagecycles', 06:24:50 INFO - '7'], 06:24:50 INFO - 'tests': ['tsvgm']}}, 06:24:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:24:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:24:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:24:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:24:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:24:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:24:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:24:50 INFO - 'talos_options': ['--disable-e10s'], 06:24:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:24:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:24:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:24:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:24:50 INFO - 'talos_options': ['--disable-e10s'], 06:24:50 INFO - 'tests': ['damp', 'tps']}, 06:24:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:24:50 INFO - 'tests': ['damp', 'tps']}, 06:24:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:24:50 INFO - 'tests': ['dromaeo_dom']}, 06:24:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:24:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:24:50 INFO - 'tests': ['basic_compositor_video']}, 06:24:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:24:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:24:50 INFO - 'tests': ['a11yr', 06:24:50 INFO - 'ts_paint', 06:24:50 INFO - 'tpaint', 06:24:50 INFO - 'sessionrestore', 06:24:50 INFO - 'sessionrestore_no_auto_restore', 06:24:50 INFO - 'tabpaint']}, 06:24:50 INFO - 'other-e10s': {'tests': ['a11yr', 06:24:50 INFO - 'ts_paint', 06:24:50 INFO - 'tpaint', 06:24:50 INFO - 'sessionrestore', 06:24:50 INFO - 'sessionrestore_no_auto_restore', 06:24:50 INFO - 'tabpaint']}, 06:24:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:24:50 INFO - 'ts_paint', 06:24:50 INFO - 'tpaint', 06:24:50 INFO - 'sessionrestore', 06:24:50 INFO - 'sessionrestore_no_auto_restore', 06:24:50 INFO - 'tabpaint']}, 06:24:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:24:50 INFO - 'ts_paint', 06:24:50 INFO - 'tpaint', 06:24:50 INFO - 'sessionrestore', 06:24:50 INFO - 'sessionrestore_no_auto_restore', 06:24:50 INFO - 'tabpaint']}, 06:24:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:24:50 INFO - 'tests': ['a11yr', 06:24:50 INFO - 'ts_paint', 06:24:50 INFO - 'tpaint', 06:24:50 INFO - 'sessionrestore', 06:24:50 INFO - 'sessionrestore_no_auto_restore', 06:24:50 INFO - 'tabpaint']}, 06:24:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:24:50 INFO - 'tests': ['a11yr', 06:24:50 INFO - 'ts_paint', 06:24:50 INFO - 'tpaint', 06:24:50 INFO - 'sessionrestore', 06:24:50 INFO - 'sessionrestore_no_auto_restore', 06:24:50 INFO - 'tabpaint']}, 06:24:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:24:50 INFO - 'tests': ['tsvgx', 06:24:50 INFO - 'tsvgr_opacity', 06:24:50 INFO - 'tart', 06:24:50 INFO - 'tscrollx', 06:24:50 INFO - 'cart', 06:24:50 INFO - 'tsvg_static']}, 06:24:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:24:50 INFO - 'tsvgr_opacity', 06:24:50 INFO - 'tart', 06:24:50 INFO - 'tscrollx', 06:24:50 INFO - 'cart', 06:24:50 INFO - 'tsvg_static']}, 06:24:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:24:50 INFO - 'talos_options': ['--disable-e10s'], 06:24:50 INFO - 'tests': ['tp5o']}, 06:24:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:24:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:24:50 INFO - 'talos_options': ['--disable-e10s', 06:24:50 INFO - '--xperf_path', 06:24:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:24:50 INFO - 'tests': ['tp5n']}, 06:24:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:24:50 INFO - 'talos_options': ['--xperf_path', 06:24:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:24:50 INFO - 'tests': ['tp5n']}}, 06:24:50 INFO - 'talos.zip': {'path': '', 06:24:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:24:50 INFO - Running post-action listener: _resource_record_post_action 06:24:50 INFO - [mozharness: 2017-02-02 14:24:50.700824Z] Finished populate-webroot step (success) 06:24:50 INFO - [mozharness: 2017-02-02 14:24:50.700941Z] Running create-virtualenv step. 06:24:50 INFO - Running pre-action listener: _resource_record_pre_action 06:24:50 INFO - Running main action method: create_virtualenv 06:24:50 INFO - Creating virtualenv /builds/slave/test/build/venv 06:24:50 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 06:24:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:24:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:24:50 INFO - Using real prefix '/tools/python27' 06:24:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:24:51 INFO - Installing distribute.............................................................................................................................................................................................done. 06:24:54 INFO - Installing pip.................done. 06:24:54 INFO - Return code: 0 06:24:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:24:54 INFO - Reading from file tmpfile_stdout 06:24:54 INFO - Output received: 06:24:54 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:24:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:54 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 0x109a73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbba5b9ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109851d30>, '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 0x109778db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109846be8>, '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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 06:24:54 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 06:24:54 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 06:24:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o5c2Qzy4Xb/Render', 06:24:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:24:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:24:54 INFO - 'HOME': '/Users/cltbld', 06:24:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:24:54 INFO - 'LOGNAME': 'cltbld', 06:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:24:54 INFO - 'MOZ_NO_REMOTE': '1', 06:24:54 INFO - 'NO_EM_RESTART': '1', 06:24:54 INFO - 'PAGER': '/bin/cat', 06:24:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:24:54 INFO - 'PWD': '/builds/slave/test', 06:24:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:24:54 INFO - 'SHELL': '/bin/bash', 06:24:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aqZSv29SKj/Listeners', 06:24:54 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 06:24:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:24:54 INFO - 'USER': 'cltbld', 06:24:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:24:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:24:54 INFO - 'XPC_FLAGS': '0x0', 06:24:54 INFO - 'XPC_SERVICE_NAME': '0', 06:24:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:24:54 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 06:24:54 INFO - Cleaning up... 06:24:54 INFO - Return code: 0 06:24:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:54 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 0x109a73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbba5b9ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109851d30>, '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 0x109778db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109846be8>, '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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 06:24:54 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 06:24:54 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 06:24:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o5c2Qzy4Xb/Render', 06:24:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:24:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:24:54 INFO - 'HOME': '/Users/cltbld', 06:24:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:24:54 INFO - 'LOGNAME': 'cltbld', 06:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:24:54 INFO - 'MOZ_NO_REMOTE': '1', 06:24:54 INFO - 'NO_EM_RESTART': '1', 06:24:54 INFO - 'PAGER': '/bin/cat', 06:24:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:24:54 INFO - 'PWD': '/builds/slave/test', 06:24:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:24:54 INFO - 'SHELL': '/bin/bash', 06:24:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aqZSv29SKj/Listeners', 06:24:54 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 06:24:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:24:54 INFO - 'USER': 'cltbld', 06:24:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:24:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:24:54 INFO - 'XPC_FLAGS': '0x0', 06:24:54 INFO - 'XPC_SERVICE_NAME': '0', 06:24:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:24:55 INFO - Downloading/unpacking psutil>=3.1.1 06:24:55 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 06:24:55 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 06:24:55 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 06:24:55 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 06:24:55 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 06:24:55 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 06:24:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:24:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:24:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:24:58 INFO - Installing collected packages: psutil 06:24:58 INFO - Running setup.py install for psutil 06:24:59 INFO - building 'psutil._psutil_osx' extension 06:24:59 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 06:24:59 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 06:24:59 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 06:24:59 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 06:24:59 INFO - building 'psutil._psutil_posix' extension 06:24:59 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 06:24:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:24:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:24:59 INFO - ^ 06:25:00 INFO - 1 warning generated. 06:25:00 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 06:25:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:25:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:25:00 INFO - Successfully installed psutil 06:25:00 INFO - Cleaning up... 06:25:00 INFO - Return code: 0 06:25:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:25: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')]} 06:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25: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')]} 06:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:00 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 0x109a73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbba5b9ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109851d30>, '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 0x109778db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109846be8>, '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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 06:25:00 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 06:25:00 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 06:25:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o5c2Qzy4Xb/Render', 06:25:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:00 INFO - 'HOME': '/Users/cltbld', 06:25:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:00 INFO - 'LOGNAME': 'cltbld', 06:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:00 INFO - 'MOZ_NO_REMOTE': '1', 06:25:00 INFO - 'NO_EM_RESTART': '1', 06:25:00 INFO - 'PAGER': '/bin/cat', 06:25:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:00 INFO - 'PWD': '/builds/slave/test', 06:25:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:00 INFO - 'SHELL': '/bin/bash', 06:25:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aqZSv29SKj/Listeners', 06:25:00 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 06:25:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:00 INFO - 'USER': 'cltbld', 06:25:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:00 INFO - 'XPC_FLAGS': '0x0', 06:25:00 INFO - 'XPC_SERVICE_NAME': '0', 06:25:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:25:00 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 06:25:00 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 06:25:00 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 06:25:00 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 06:25:00 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 06:25:00 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 06:25:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:25:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:25:03 INFO - Installing collected packages: mozsystemmonitor 06:25:03 INFO - Running setup.py install for mozsystemmonitor 06:25:03 INFO - Successfully installed mozsystemmonitor 06:25:03 INFO - Cleaning up... 06:25:03 INFO - Return code: 0 06:25:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:25:03 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')]} 06:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:03 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')]} 06:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:03 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 0x109a73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbba5b9ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109851d30>, '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 0x109778db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109846be8>, '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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 06:25:03 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 06:25:03 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 06:25:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o5c2Qzy4Xb/Render', 06:25:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:03 INFO - 'HOME': '/Users/cltbld', 06:25:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:03 INFO - 'LOGNAME': 'cltbld', 06:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:03 INFO - 'MOZ_NO_REMOTE': '1', 06:25:03 INFO - 'NO_EM_RESTART': '1', 06:25:03 INFO - 'PAGER': '/bin/cat', 06:25:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:03 INFO - 'PWD': '/builds/slave/test', 06:25:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:03 INFO - 'SHELL': '/bin/bash', 06:25:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aqZSv29SKj/Listeners', 06:25:03 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 06:25:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:03 INFO - 'USER': 'cltbld', 06:25:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:03 INFO - 'XPC_FLAGS': '0x0', 06:25:03 INFO - 'XPC_SERVICE_NAME': '0', 06:25:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:03 INFO - Downloading/unpacking jsonschema==2.5.1 06:25:03 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 06:25:03 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 06:25:03 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 06:25:03 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 06:25:03 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 06:25:03 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 06:25:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:25:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:06 INFO - Downloading functools32-3.2.3-2.tar.gz 06:25:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:25:06 INFO - warning: no files found matching '*.txt' 06:25:06 INFO - no previously-included directories found matching 'build' 06:25:06 INFO - no previously-included directories found matching 'dist' 06:25:06 INFO - no previously-included directories found matching '.git*' 06:25:06 INFO - Installing collected packages: jsonschema, functools32 06:25:06 INFO - Running setup.py install for functools32 06:25:06 INFO - warning: no files found matching '*.txt' 06:25:06 INFO - no previously-included directories found matching 'build' 06:25:06 INFO - no previously-included directories found matching 'dist' 06:25:06 INFO - no previously-included directories found matching '.git*' 06:25:06 INFO - Successfully installed jsonschema functools32 06:25:06 INFO - Cleaning up... 06:25:07 INFO - Return code: 0 06:25:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:25:07 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')]} 06:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:07 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')]} 06:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:07 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 0x109a73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbba5b9ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109851d30>, '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 0x109778db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109846be8>, '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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 06:25:07 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 06:25:07 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 06:25:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o5c2Qzy4Xb/Render', 06:25:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:07 INFO - 'HOME': '/Users/cltbld', 06:25:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:07 INFO - 'LOGNAME': 'cltbld', 06:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:07 INFO - 'MOZ_NO_REMOTE': '1', 06:25:07 INFO - 'NO_EM_RESTART': '1', 06:25:07 INFO - 'PAGER': '/bin/cat', 06:25:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:07 INFO - 'PWD': '/builds/slave/test', 06:25:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:07 INFO - 'SHELL': '/bin/bash', 06:25:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aqZSv29SKj/Listeners', 06:25:07 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 06:25:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:07 INFO - 'USER': 'cltbld', 06:25:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:07 INFO - 'XPC_FLAGS': '0x0', 06:25:07 INFO - 'XPC_SERVICE_NAME': '0', 06:25:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:25:07 INFO - Cleaning up... 06:25:07 INFO - Return code: 0 06:25:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:25:07 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')]} 06:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:07 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')]} 06:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:07 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 0x109a73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbba5b9ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109851d30>, '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 0x109778db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109846be8>, '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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 06:25:07 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 06:25:07 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 06:25:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o5c2Qzy4Xb/Render', 06:25:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:07 INFO - 'HOME': '/Users/cltbld', 06:25:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:07 INFO - 'LOGNAME': 'cltbld', 06:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:07 INFO - 'MOZ_NO_REMOTE': '1', 06:25:07 INFO - 'NO_EM_RESTART': '1', 06:25:07 INFO - 'PAGER': '/bin/cat', 06:25:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:07 INFO - 'PWD': '/builds/slave/test', 06:25:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:07 INFO - 'SHELL': '/bin/bash', 06:25:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aqZSv29SKj/Listeners', 06:25:07 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 06:25:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:07 INFO - 'USER': 'cltbld', 06:25:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:07 INFO - 'XPC_FLAGS': '0x0', 06:25:07 INFO - 'XPC_SERVICE_NAME': '0', 06:25:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:07 INFO - Downloading/unpacking blobuploader==1.2.4 06:25: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 06:25: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 06:25: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 06:25: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 06:25: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 06:25: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 06:25:10 INFO - Downloading blobuploader-1.2.4.tar.gz 06:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:25:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:25:10 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 06:25:10 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 06:25:10 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 06:25:10 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 06:25:10 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 06:25:10 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 06:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:25:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:25:10 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 06:25:10 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 06:25:10 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 06:25:10 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 06:25:10 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 06:25:10 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 06:25:11 INFO - Downloading docopt-0.6.1.tar.gz 06:25:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:25:11 INFO - Installing collected packages: blobuploader, requests, docopt 06:25:11 INFO - Running setup.py install for blobuploader 06:25:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:25:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:25:11 INFO - Running setup.py install for requests 06:25:12 INFO - Running setup.py install for docopt 06:25:12 INFO - Successfully installed blobuploader requests docopt 06:25:12 INFO - Cleaning up... 06:25:12 INFO - Return code: 0 06:25:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:12 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 0x109a73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbba5b9ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109851d30>, '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 0x109778db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109846be8>, '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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 06:25:12 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 06:25:12 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 06:25:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o5c2Qzy4Xb/Render', 06:25:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:12 INFO - 'HOME': '/Users/cltbld', 06:25:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:12 INFO - 'LOGNAME': 'cltbld', 06:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:12 INFO - 'MOZ_NO_REMOTE': '1', 06:25:12 INFO - 'NO_EM_RESTART': '1', 06:25:12 INFO - 'PAGER': '/bin/cat', 06:25:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:12 INFO - 'PWD': '/builds/slave/test', 06:25:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:12 INFO - 'SHELL': '/bin/bash', 06:25:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aqZSv29SKj/Listeners', 06:25:12 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 06:25:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:12 INFO - 'USER': 'cltbld', 06:25:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:12 INFO - 'XPC_FLAGS': '0x0', 06:25:12 INFO - 'XPC_SERVICE_NAME': '0', 06:25:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:25:12 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-tdI8cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:25:12 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-fWEcEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:25:12 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-MvYaj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:25:12 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-17oFen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:25:12 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-1TVvBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:25:13 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-QLYpqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:25:13 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-YkZBzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:25:13 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-24O6id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:25:13 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-_pwjQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:25:13 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-8Th9ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:25:13 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-1ciBDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:25:13 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-6XOCWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:25:13 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-sXnea7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:25:13 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-uwJ9z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:14 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-W2e1kB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:25:14 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Ifd9FX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:25:14 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-DBXmIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:25:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:25:14 INFO - Running setup.py install for manifestparser 06:25:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Running setup.py install for mozcrash 06:25:14 INFO - Running setup.py install for mozdebug 06:25:14 INFO - Running setup.py install for mozdevice 06:25:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Running setup.py install for mozfile 06:25:15 INFO - Running setup.py install for mozhttpd 06:25:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Running setup.py install for mozinfo 06:25:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Running setup.py install for mozInstall 06:25:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Running setup.py install for mozleak 06:25:15 INFO - Running setup.py install for mozlog 06:25:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Running setup.py install for moznetwork 06:25:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Running setup.py install for mozprocess 06:25:16 INFO - Running setup.py install for mozprofile 06:25:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:25:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:25:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:25:16 INFO - Running setup.py install for mozrunner 06:25:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:25:16 INFO - Running setup.py install for mozscreenshot 06:25:16 INFO - Running setup.py install for moztest 06:25:16 INFO - Running setup.py install for mozversion 06:25:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:25:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:25:16 INFO - Cleaning up... 06:25:16 INFO - Return code: 0 06:25:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:16 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 0x109a73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbba5b9ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109851d30>, '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 0x109778db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109846be8>, '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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 06:25:16 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 06:25:16 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 06:25:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o5c2Qzy4Xb/Render', 06:25:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:16 INFO - 'HOME': '/Users/cltbld', 06:25:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:16 INFO - 'LOGNAME': 'cltbld', 06:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:16 INFO - 'MOZ_NO_REMOTE': '1', 06:25:16 INFO - 'NO_EM_RESTART': '1', 06:25:16 INFO - 'PAGER': '/bin/cat', 06:25:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:16 INFO - 'PWD': '/builds/slave/test', 06:25:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:16 INFO - 'SHELL': '/bin/bash', 06:25:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aqZSv29SKj/Listeners', 06:25:16 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 06:25:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:16 INFO - 'USER': 'cltbld', 06:25:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:16 INFO - 'XPC_FLAGS': '0x0', 06:25:16 INFO - 'XPC_SERVICE_NAME': '0', 06:25:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:25:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-d9Pycq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:25:17 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:25:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-BLNoKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:25:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:25:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-2RZCow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:25:17 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:25:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-BcdctB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:25:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:25:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Fz7_lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:25:17 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:25:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-K9mWZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:25:17 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:25:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-2DJb9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:25:17 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:25:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-png8op-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:25:18 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)) 06:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:25:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-jyUuoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:25:18 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)) 06:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:25:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-qKikpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 06:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:25:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-xfpLcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:25:18 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)) 06:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:25:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-iJcgrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 06:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:25:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-2nt1sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:25:18 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)) 06:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:25:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-YBqp2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 06:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-K4VCj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:18 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)) 06:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:25:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-LqivFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 06:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:25:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Phq9Wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:25:18 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)) 06:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:25:18 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)) 06:25:18 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:25:18 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:25:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:21 INFO - Downloading blessings-1.6.tar.gz 06:25:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:25:21 INFO - Installing collected packages: blessings 06:25:21 INFO - Running setup.py install for blessings 06:25:21 INFO - Successfully installed blessings 06:25:21 INFO - Cleaning up... 06:25:21 INFO - Return code: 0 06:25:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:25:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:25:22 INFO - Reading from file tmpfile_stdout 06:25:22 INFO - Current package versions: 06:25:22 INFO - blessings == 1.6 06:25:22 INFO - blobuploader == 1.2.4 06:25:22 INFO - docopt == 0.6.1 06:25:22 INFO - functools32 == 3.2.3-2 06:25:22 INFO - jsonschema == 2.5.1 06:25:22 INFO - manifestparser == 1.1 06:25:22 INFO - mozInstall == 1.12 06:25:22 INFO - mozcrash == 1.0 06:25:22 INFO - mozdebug == 0.1 06:25:22 INFO - mozdevice == 0.49 06:25:22 INFO - mozfile == 1.2 06:25:22 INFO - mozhttpd == 0.7 06:25:22 INFO - mozinfo == 0.9 06:25:22 INFO - mozleak == 0.1 06:25:22 INFO - mozlog == 3.4 06:25:22 INFO - moznetwork == 0.27 06:25:22 INFO - mozprocess == 0.24 06:25:22 INFO - mozprofile == 0.28 06:25:22 INFO - mozrunner == 6.13 06:25:22 INFO - mozscreenshot == 0.1 06:25:22 INFO - mozsystemmonitor == 0.3 06:25:22 INFO - moztest == 0.8 06:25:22 INFO - mozversion == 1.4 06:25:22 INFO - psutil == 3.1.1 06:25:22 INFO - requests == 1.2.3 06:25:22 INFO - wsgiref == 0.1.2 06:25:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:22 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 0x109a73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbba5b9ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109851d30>, '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 0x109778db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109846be8>, '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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 06:25:22 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 06:25:22 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 06:25:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o5c2Qzy4Xb/Render', 06:25:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:22 INFO - 'HOME': '/Users/cltbld', 06:25:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:22 INFO - 'LOGNAME': 'cltbld', 06:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:22 INFO - 'MOZ_NO_REMOTE': '1', 06:25:22 INFO - 'NO_EM_RESTART': '1', 06:25:22 INFO - 'PAGER': '/bin/cat', 06:25:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:22 INFO - 'PWD': '/builds/slave/test', 06:25:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:22 INFO - 'SHELL': '/bin/bash', 06:25:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aqZSv29SKj/Listeners', 06:25:22 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 06:25:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:22 INFO - 'USER': 'cltbld', 06:25:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:22 INFO - 'XPC_FLAGS': '0x0', 06:25:22 INFO - 'XPC_SERVICE_NAME': '0', 06:25:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 INFO - Cleaning up... 06:25:22 INFO - Return code: 0 06:25:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:22 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 0x109a73b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a76768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbba5b9ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109851d30>, '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 0x109778db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109846be8>, '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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 06:25:22 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 06:25:22 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 06:25:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o5c2Qzy4Xb/Render', 06:25:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:22 INFO - 'HOME': '/Users/cltbld', 06:25:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:22 INFO - 'LOGNAME': 'cltbld', 06:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:22 INFO - 'MOZ_NO_REMOTE': '1', 06:25:22 INFO - 'NO_EM_RESTART': '1', 06:25:22 INFO - 'PAGER': '/bin/cat', 06:25:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:22 INFO - 'PWD': '/builds/slave/test', 06:25:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:22 INFO - 'SHELL': '/bin/bash', 06:25:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aqZSv29SKj/Listeners', 06:25:22 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 06:25:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:22 INFO - 'USER': 'cltbld', 06:25:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:22 INFO - 'XPC_FLAGS': '0x0', 06:25:22 INFO - 'XPC_SERVICE_NAME': '0', 06:25:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:25:22 INFO - Cleaning up... 06:25:22 INFO - Return code: 0 06:25:22 INFO - Running post-action listener: _resource_record_post_action 06:25:22 INFO - Running post-action listener: _start_resource_monitoring 06:25:22 INFO - Starting resource monitoring. 06:25:22 INFO - [mozharness: 2017-02-02 14:25:22.734753Z] Finished create-virtualenv step (success) 06:25:22 INFO - [mozharness: 2017-02-02 14:25:22.735736Z] Running install step. 06:25:22 INFO - Running pre-action listener: _resource_record_pre_action 06:25:22 INFO - Running main action method: install 06:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:25:22 INFO - Reading from file tmpfile_stdout 06:25:22 INFO - Detecting whether we're running mozinstall >=1.0... 06:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:25:23 INFO - Reading from file tmpfile_stdout 06:25:23 INFO - Output received: 06:25:23 INFO - Usage: mozinstall [options] installer 06:25:23 INFO - Options: 06:25:23 INFO - -h, --help show this help message and exit 06:25:23 INFO - -d DEST, --destination=DEST 06:25:23 INFO - Directory to install application into. [default: 06:25:23 INFO - "/builds/slave/test"] 06:25:23 INFO - --app=APP Application being installed. [default: firefox] 06:25:23 INFO - mkdir: /builds/slave/test/build/application 06:25:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:25:45 INFO - Reading from file tmpfile_stdout 06:25:45 INFO - Output received: 06:25:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:25:45 INFO - Running post-action listener: _resource_record_post_action 06:25:45 INFO - [mozharness: 2017-02-02 14:25:45.102873Z] Finished install step (success) 06:25:45 INFO - [mozharness: 2017-02-02 14:25:45.103025Z] Running run-tests step. 06:25:45 INFO - Running pre-action listener: _resource_record_pre_action 06:25:45 INFO - Running main action method: run_tests 06:25:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:25:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:25:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:25:45 INFO - 2017-02-02 06:25:45.117 screenresolution[1633:8085] starting screenresolution argv=screenresolution get 06:25:45 INFO - 2017-02-02 06:25:45.120 screenresolution[1633:8085] Display 0: 1600x1200x32@60 06:25:45 INFO - 2017-02-02 06:25:45.125 screenresolution[1634:8087] starting screenresolution argv=screenresolution list 06:25:45 INFO - Available Modes on Display 0 06:25:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:25:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:25:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:25:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:25:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:25:45 INFO - Intel Iris: 06:25:45 INFO - Chipset Model: Intel Iris 06:25:45 INFO - Type: GPU 06:25:45 INFO - Bus: Built-In 06:25:45 INFO - VRAM (Dynamic, Max): 1536 MB 06:25:45 INFO - Vendor: Intel (0x8086) 06:25:45 INFO - Device ID: 0x0a2e 06:25:45 INFO - Revision ID: 0x0009 06:25:45 INFO - Displays: 06:25:45 INFO - Display: 06:25:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:25:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:25:45 INFO - Display Serial Number: 1600x1200 60 06:25:45 INFO - Main Display: Yes 06:25:45 INFO - Mirror: Off 06:25:45 INFO - Online: Yes 06:25:45 INFO - Rotation: Supported 06:25:45 INFO - Return code: 0 06:25:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:25:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:25:45 INFO - Python 2.7.3 06:25:45 INFO - Return code: 0 06:25:45 INFO - grabbing minidump binary from tooltool 06:25:45 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')]} 06:25:45 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 0x109851d30>, '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 0x109778db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109846be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:25:45 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 06:25:45 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 06:25:45 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 06:25:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:25:45 INFO - Return code: 0 06:25:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:25:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:25:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:25:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:25:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:25:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:25:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0114 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:25:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o5c2Qzy4Xb/Render', 06:25:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:45 INFO - 'HOME': '/Users/cltbld', 06:25:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:45 INFO - 'LOGNAME': 'cltbld', 06:25:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:25:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:45 INFO - 'MOZ_NO_REMOTE': '1', 06:25:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:25:45 INFO - 'NO_EM_RESTART': '1', 06:25:45 INFO - 'PAGER': '/bin/cat', 06:25:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:45 INFO - 'PWD': '/builds/slave/test', 06:25:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:25:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:45 INFO - 'SHELL': '/bin/bash', 06:25:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aqZSv29SKj/Listeners', 06:25:45 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 06:25:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:45 INFO - 'USER': 'cltbld', 06:25:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:45 INFO - 'XPC_FLAGS': '0x0', 06:25:45 INFO - 'XPC_SERVICE_NAME': '0', 06:25:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:25:45 INFO - mozversion application_buildid: 20170202052009 06:25:45 INFO - mozversion application_changeset: dd7cce059626e9787537bf9ba7863a6ad8ac06a1 06:25:45 INFO - mozversion application_display_name: Nightly 06:25:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:25:45 INFO - mozversion application_name: Firefox 06:25:45 INFO - mozversion application_remotingname: firefox 06:25:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:25:45 INFO - mozversion application_vendor: Mozilla 06:25:45 INFO - mozversion application_version: 54.0a1 06:25:45 INFO - mozversion platform_buildid: 20170202052009 06:25:45 INFO - mozversion platform_changeset: dd7cce059626e9787537bf9ba7863a6ad8ac06a1 06:25:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:25:45 INFO - mozversion platform_version: 54.0a1 06:25:45 INFO - using testdate: 1486045545 06:25:45 INFO - actual date: 1486045545 06:25:45 INFO - starting webserver on 'localhost:49243' 06:25:45 INFO - SUITE-START | Running 2 tests 06:25:45 INFO - TEST-START | dromaeo_css 06:25:45 INFO - operating with platform_type : mac_ 06:25:45 INFO - Initialising browser for dromaeo_css test... 06:25:45 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpA4UxEO/profile http://localhost:49243/getInfo.html) 06:25:47 INFO - PROCESS | 1644 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 06:25:48 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 06:25:48 INFO - PROCESS | 1644 | colorDepth:24 06:25:48 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 06:25:48 INFO - PROCESS | 1644 | __metrics 06:25:48 INFO - PROCESS | 1644 | 1486045548342 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 06:25:48 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:25:48 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:25:49 INFO - TEST-INFO | 1644: exit 0 06:25:49 INFO - Browser initialized. 06:25:49 INFO - Running cycle 1/1 for dromaeo_css test... 06:25:49 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpA4UxEO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:26:01 INFO - PROCESS | 1646 | RSS: Main: 236834816 06:26:01 INFO - PROCESS | 1646 | 06:29:04 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 06:29:06 INFO - PROCESS | 1646 | RSS: Main: 372658176 06:29:06 INFO - PROCESS | 1646 | 06:30:50 INFO - PROCESS | 1646 | ************************* 06:30:50 INFO - PROCESS | 1646 | A coding exception was thrown and uncaught in a Task. 06:30:50 INFO - PROCESS | 1646 | 06:30:50 ERROR - PROCESS | 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:30:50 INFO - PROCESS | 1646 | Full stack: 06:30:50 INFO - PROCESS | 1646 | ************************* 06:32:09 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 06:32:10 INFO - PROCESS | 1646 | RSS: Main: 370315264 06:32:10 INFO - PROCESS | 1646 | 06:35:12 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 06:35:13 INFO - PROCESS | 1646 | RSS: Main: 334778368 06:35:13 INFO - PROCESS | 1646 | 06:38:15 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 06:38:16 INFO - PROCESS | 1646 | RSS: Main: 333283328 06:38:16 INFO - PROCESS | 1646 | 06:41:20 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 06:41:20 INFO - PROCESS | 1646 | RSS: Main: 334647296 06:41:20 INFO - PROCESS | 1646 | 06:44:24 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 06:44:24 INFO - PROCESS | 1646 | RSS: Main: 448737280 06:44:24 INFO - PROCESS | 1646 | 06:44:24 INFO - PROCESS | 1646 | __start_tp_report 06:44:24 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 06:44:24 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 06:44:24 INFO - PROCESS | 1646 | |i|pagename|runs| 06:44:24 INFO - PROCESS | 1646 | |0;dojo.html;2816.4085542036173;3337.415952208363;3342.9665703342976;3352.2625022495045;3376.5610470638812;5564.165375193713;5634.408387119355;5654.688992105433;5671;5705.600607957439;5642.294713160855;5658.405867383927;5714.885702285952;5754.65472071675;5800.332961709394;5034.5468907798295;5152.458991805863;5186.611004174702;5192.3249977503065;5226.477352264762;5151.613628977823;5251.343582052244;5267.315249017623;5285.577153827685;5288.4711528847;5273.947260527422;5275.683458992473;5300.178472336794;5304.602154838402;5307.628466007381;5067.61992850534;5097.898042039175;5127.717975511346;5138.665986710852;5158.74206289684;4292.635126014293;5109.846704598868;5111;5148.4079330876675;5205.401378841406;5576.9163462548095;5596.132599447093;5600.215969764236;5605.0751625982075;5610.635308704921;6068.605540639845;6112.847178820521;6114.419130182626;6211.9129152398145;6212.968935155295;6189.009758438673;6189.81430557084;6270.7805226816845;6280.214973128359;6313.368663133696;5751.8562035949435;5806.0129777937855;5814.680192589448;5816.534677225812;5822.214001109838;6217.937820621826;6331.430171284606;6339.2392912850755;6343.873122537523;6355.523335749837;6017.458428741435;6110.406879924458;6111.602745821508;6117.235345581802;6167.259928808572;6265.310815810257;6279.340669229757;6281.685915704196;6285.465735412449;6287.937120628827;6100.206973093469;6134.601250918676;6137.297691397357;6137.897974673478;6154.815355539341;6168.167297414881;6232.657203853832;6257.530685198628;6271.811845644638;6271.843203919847;4955.4301255356095;4979.875503112378;4979.950200497949;4981.651284410129;5002.924956125661;4416.205083085076;4419.513853476115;4456.554344565582;4467.419235499365;4485.511707811147;4830.130576496264;5651.237082993744;5685.971570142206;5745.2818397700285;5753.252077229933;6277.7175027123885;6282.560220784592;6294.9685251574365;6300.684965751694;6326.49388048955;5704.828855134353;5749.367569567345;5749.626274292157;5760.337561180518;5855.970720146372;5307.761150748225;5356.625036247426;5453.400125986138;5458.099413596712;5469.07025805614;6237.812865614039;6283.02613094971;6286.15136956505;6311.684415779285;6337.271213810471;6285.842853928689;6300.621962682254;6309.400606942333;6324.430801227912;6328.208973878265;6267.122602835698;6286.245650521967;6316.178896743394;6324.588901721373;6330;5926.762929482772;5967.850803729942;6122.489639783254;6138.878900475427;6186.536009799193;6289.088082228142;6301.212348456443;6301.306856245717;6304.05439184126;6305.432511074026;5026.246063090565;5917.911231331447;6076.179715738495;6109.08363745424;6121.296050954197;5623.606347555796;5887.116932460165;5931.911021334769;6016.458518733335;6023.216981792339;5754.827355179351;5933.495652869382;5948.464638182584;5960.850978725566;5967.254093238345;5843.649381037151;5884.264466941632;6096.9390306096375;6102.115193297036;6108.633481991094;5760.135979603093;5786.9131963019745;5799.159121927381;5803.82588522345;5810.796622118206 06:44:24 INFO - PROCESS | 1646 | |1;ext.html;444.768720265462;474.3975151557522;482.13456844963287;485.30116632049834;516.9224616307554;30188;39113.41329880051;40876.59123408762;41026.7948660257;42952.57047429525;41752.58247417525;42217.788911055475;42232.57767422325;42261.366079508756;42280;8319.417640765148;9243.768905777359;9408.81182376352;9441.19749821266;9441.244700423984;8367.497950122981;11138.443077846076;11353;11413.600523981662;11571.363575003372;11284.435778211056;11334.773304533946;11337.773244535145;11367.204295699305;11394;8316.459430137022;10037.949810250975;11290.322580645146;11355.375454350007;11363.545458181663;8357.749267521984;10951.123910087173;11254.26847254934;11269.830952535718;11450.713732156679;40162.39756403627;40576.59423405758;40628.79685601553;40629.18741625151;40708.79645601783;4021.399733497732;4028.9194216115516;4039.636432721069;4052.1085361220485;4184.51878034027;24260;27322;29822.40355192884;30690.69309306923;30747.846260768776;3071.262896904749;3240.9351812963846;3248.447763880146;3281.770276080675;3285.983570082134;337852;340209;340576;340787;341552.2922385397;57953.710231448575;58127.418725812204;58127.837443251745;58176.4182358171;58444.70777646085;59708;59785.70107149436;59872.70063649654;59942;59971.70014149989;15485.380584776709;15720;15724.528264152093;15740.92129539345;15753.527394177963;11128.998390144925;11329.660110196708;12251.448684809206;12306.953908917842;12335.383230838423;13469.326533673277;14944.252787360805;15224.858135639872;15240.23798810055;15616.3753449863;5216.322492642677;6076.815021070849;6325.177726895474;6440.194975628046;6539.2479864815205;800.3557136505053;840.3907167303656;852.2840813716508;856.2550580994574;879.8988116366572;28397.716022839508;29290.414191716045;29590.408191836043;30294.54558181629;30555.847220763753;1018.5620183321244;1044.8380501022355;1052.5000624703205;1088.1294964028903;1161.5644133449957;15452.690946181014;15493;15526.611834704223;15542;15727.68544629124;559920.6091299553;560742;561112;565186;567862;58968;58975.705121474115;59015.40984590185;59176.704116479144;59186.70406647939;1397.4619771387886;1419.2407062221869;1432.5057855040202;1434.734574103794;1442.213993373614;26970.190894273204;28957.855210724654;29263.48838139437;29627.40745185127;29765.25586860259;47701.522984769705;48396.274055889895;48401.27398088961;48535.5146448531;48541;416831;417757.5793936417;418138;420216;420789;37310.813445932596;38227.80886095552;38666.03334916649;38708.6129138705;38757.6124238754;44723.55276447194;44880.492328611515;45061.549384505735;45772.08455830798;46028.7698561505;36505.63494365022;36787.08032299214;37157;37311.06722331963;37596.436053459765;36281.45577816282;36413.817930910176;37585.4362184562;37679.81160094182;37943.05142371352;35901.281974359845;36535.26929461343;36591;36841.263174736894;36938.076548086516;31942.201444964063;32602.51096233511;32619.836900815342;32715;32820;24182.75817241805;24200.394990124685;24594.75405245996;24819.88310526067;24932.750672493043 06:44:24 INFO - PROCESS | 1646 | |2;jquery.html;367.93193259247056;393.58083640922416;396.1957226829537;405.5984575270483;423.4030017675077;14535.493996860967;15857.603559911007;17477.650446991054;19064.523386915334;21213.787862121375;21805.454863628416;21959.890200549016;22124.115035398605;22302.665460018114;22479.55040899181;5938.257717785277;5982.401759824026;6079.878402431971;6168.568200225964;6233.812985610416;4252.702310838243;5044.596432285409;6170.660613666247;6176.72204750785;6197.969010154943;4490.5509449055;6408.711607977652;6780.389764921156;6829.760958366458;6850.451963842906;6507.837304067389;6651.733930642768;6748.089007983908;6821.829454263633;6904.206016308139;6044.304904935944;6047.879042419127;6050.122732203806;6056.666883321415;6067.969660151715;26982;27472.862635686695;27991.580126298122;28088.859555702296;28448.71551284502;2717.0761940940174;2735.4529094181044;2749.752522273005;2755.4489102179646;2836.602875597418;16030.992251937016;16072.276747546388;16251;16495.752563711554;16808.075555844425;1826.6072794349145;1855.3320804510365;1856.9442916712521;1859.6838537448532;1870.8120343581788;126535.36732316372;138432.53918652012;143527.8001369885;147305.26347368196;151447.18487668192;9971.451570163566;10517.579296828042;10681.572737090584;10747.83878241827;10819.459027048617;12863.807042894365;12965.027622928317;12970.870291296966;12971.09202355826;12996.02529810268;8078.55567943757;8169.060558035783;8220.095789463154;8315.04376996641;8328.125546817497;8341.8331633367;8384.077751447336;8427.494350338999;8472.788180295542;8521.957390213009;4048.149888523384;4124.546299907008;4134;4182.163567286494;4249.936250956238;2021.6361055009945;2061.526328137094;2063.0613071052635;2064.669652855536;2072.067569593689;814.9592520373958;816.0044745410589;823.5923218007125;839.7480755773365;867.9218870301577;19617.509562261057;20004.099815508336;20989.790102098785;21698.338473783577;21800.454988625093;799.2606838674319;843.1105184030783;846.4413487098485;863.1497974495118;873.9694110706095;7684.884726729104;9251.814963700688;10718.142548596094;11073.833892491619;11118.555257789598;726514;731279.1848733639;738594;746152;746716;134720;147933;155491;156734;158120;965.9227261819178;983.2527279267665;1037.5953378182514;1046.1578429364283;1052.9052385285208;19614.332781713714;19848.60302793965;19882.60234795325;20197.394078177676;20433;59281.70359148177;59293.407065930514;59547;59690.80618387521;60618.696906515186;86845;88410;89414.55292723495;90158.19683606422;90236.54881725549;8030.638621262174;8232.217939295757;8414.83170336602;8486;8494.490330580185;7699.615019249127;9744.805103898025;12769.361531923552;12797.36013199355;13264.602061938158;9489.288303377412;11280.887191127984;11448.198626096093;11539.711507212387;11561.710957226136;5294.417614062373;9404.623815047322;10034.297599167989;10854.782904342026;11185.440727963733;9481.905180948102;11289.08085625462;11450.141239406912;11611.303321800719;11694.824577631513;7096.574205547684;7354.117505899112;7421.3320801126;8126.065502467292;8542.18849209324;10271.435071070864;10769.192310576895;10777.46112694378;11189.272697274651;11237.3257604544 06:44:24 INFO - PROCESS | 1646 | |3;mootools.html;1420.4034305591651;1428.649980754715;1444.689391780767;1445.8072090525313;1446.3563714147208;7971.960140199298;8255.752327430171;8261.75214743558;8302.252797248246;8398.958005209981;7432.331090201881;7635.618219089038;7653.655585498652;7997.160298168681;8201.876971845428;2214.6567282071223;2237.407087121914;2268.0814270220503;2277.1119263487253;2285.2915596165158;1988.4332965104948;2260.49138943738;2294.1855641247366;2298.7586303438147;2305.8847057647135;2005.1077270614583;2079.8232150267245;2205.095910676615;2265.29775769512;2291.8854057297067;2256.3569382725927;2272.3864556569715;2277.2712731925626;2277.533105713325;2279.5326957973575;2148.2803260907613;2194.1897934204344;2214.8671079735104;2279.806216471602;2282.4978504728933;8255.339572834158;8344.541050242235;8362.372822038311;8368.24685778283;8374.958125209336;4982.377202849644;5171.120909445366;5177.171652535576;5178.093833579146;5181.119209734316;3279.3769183854993;3450.723942084627;3455.6889879910677;3516.0155156556207;3530.5586801649793;1162.6105254739673;1224.9142560020803;1237.1772771107294;1258.6098309524077;1262.1606631590073;10282.383057016603;10608.62869799561;10633.46832658364;10641.627543035956;10661.893381066167;3586.5696116466193;4420.911581768347;4454.888627784267;4461.08547747711;4496.100779843979;8641.654333826576;8652.134786521297;8665;8669.306455483546;8671.002834673967;5102.311187989648;5106.285120083191;5106.974465127651;5112.185174263083;5112.923306150411;4613.0773845230415;4732.692374995683;4806.759662016885;4806.903861922742;4842.612590992712;3085.228692826793;3579.2304654499408;3679.6688298053305;3681.1349332906743;3712.276106159327;1041.9791604167874;1164.2490593567102;1165.3299352871963;1172.2146162071263;1176.8705442401329;777.1956025513566;855.4012191465998;889.6930558957149;895.861141523052;935.4387367579398;5401.297831281908;5429.239906413105;5655.717214139277;5696.458836410535;5855.59010869235;830.759079866844;864.6714248585623;970.8301047316692;982.7666278820035;1012.7441971954723;3729.067733066733;3735.551733791908;3780.31954248238;3829.310724069694;3863;9253.768655783659;9369.859452108223;9493.288003399774;9633.566489507988;9794.706158815246;9647.421154730739;9749.268804839665;9750.951245243728;9756.902430975599;9769.16962058233;7559.3196612305155;7850.764477065812;7863.95537160331;7864.803379915548;7871;5493.505584497414;5627.748900439857;5718.228039214653;5761.395053519488;5806.4193580641595;8895.866562001445;9032.36773425854;9127.77180570491;9191.356605037583;9203.539823008956;8333.083360830182;8618;8757.60590773404;9246;9268.490233036979;5625.803096891589;6671.66641667905;7116.786496405116;7122.789125848514;7229.457790665826;7803.180666030212;7899.210078992055;7975.680972761024;7975.84048319042;8001.479903806234;5201.375834899836;6293.087502312231;6299.968500157654;6364.586301890362;6415.615063096229;6862.348076932683;6938.340857618518;6949.930500694928;6992.021117043516;7008.194057683431;6311.305756366888;6415.743370265137;6417.903731443938;6439.710213040331;6440.937245354464;4853.708777473363;4940.7776650051555;4964.602831773377;5003.824866129668;5148.871278218075;4955.35580374549;4964.751762411937;5056.089903817348;5069.138246498178;5149.150390185578 06:44:24 INFO - PROCESS | 1646 | |4;prototype.html;355.9412696905011;356.1719003317288;366.3588719740454;374.28698329681964;375.07918061159853;5256.7634456449505;5277.630565860387;5283.445238249986;5343.89312213756;5388.541973932211;4353.49934757503;4935.815404302968;5035.723035233061;5071.873203169914;5125.513076257749;3445.8104804235695;3859.6333348331864;3978.781167035812;4112.712110152291;4146.709730318879;3780.9980355205894;3790.1093243087853;4086.2440448516973;4101.610347017028;4101.79491025449;3451.637578054306;3452.8087809705735;3707.3326801175776;3871.728978971459;4101.302778527643;3962.2556683933694;3967.690662081506;3975.344068228724;3988.541317748467;4019.839206431739;2868.928276793076;3442.208292092833;3446.172918499567;3455.5507783988173;3456.2914602506426;4161.729487583298;5070.188769796851;5097.541221290065;5099.515546023122;5108.872278193037;3638.6725194732603;4282.785860706952;4520.547945205486;4557.012613962783;4565.863024109282;5520.475554822286;5528.585356098269;5542.889142217174;5550.666959982414;5580.330360356743;3822.26087130355;3823.9426408603895;3829.215010922782;3831.1188426662025;3837.808109594509;5454.809081682122;5457.561736106228;5732.760990040655;5742.7415766290615;5765.971170144123;3411.4030044742567;3845.615438456132;3861;3861.189150278473;3861.613838616172;3439.0053768814028;3544.9822750886083;3909.179072394829;3919.2553414851086;3934.9606503935165;4381.583749543788;4414.359917811964;4495.483019452805;4546.636269098464;4578.725276483422;5209.609279304067;5276.868078298074;5528.590209496552;5620.578456615688;5648.068068768607;1022.4223313827664;1129.1418521923233;1253.5111306590436;1257.4970011995092;1262.8295180150747;804.4208170117623;831.7296878514506;832.134580036767;839.4333824668324;862.3575436299872;4867.9269810952865;4899.252072164797;4976.606550165887;4981.402231732215;5019.924701129486;5014.222795466636;5109.642325037212;5371.4628537146755;5408.295852073963;5512.283403157644;4408.515063343029;4693.6949098308505;4824.517548245218;5294.894102117936;5313.495217954288;2414.4084699248447;2746.83259614667;2853.7586150024804;2874.7843911706705;2891.3783536539745;4709.505501922317;5051.924221136685;5621.915671264935;5661.207430959669;5703.975088843484;4623.745693986796;4843.564079232886;5196.2062300534635;5461.617686761968;5606.635568688022;4661.230896902041;5354.625176237704;5565.276514053228;5665.4617811307035;5712.714364281853;4546.74992875395;4869.147899117711;4894.510548945077;5436.6194366393975;5521.0738308696455;4596.988662494246;5045.2432135180015;5287.391949925808;5368.248445217595;5425.647332923348;4849.987255289016;5284.867878303073;5526.972365138148;5687.440651706831;5741.885162296814;3136.153238625604;3294.7364210862593;3474.6004209516227;3647.507586475792;3692.2246328271362;5195.402528709247;5203.297554830049;5255.45110621356;5461.006732542673;5488.463230295523;5234.2672025915645;5255.973720131375;5310.389305229948;5328.442347673216;5359.919601205907;4978.361468421742;5205.297284866646;5231.293775340432;5235.973820130875;5255.63210575256;4330.653547716112;4813.9037219256115;5134.922976155286;5187.948120518747;5199.076341753575;4795.160846851717;5338.54622357104;5383.5962302826665;5413.215083812904;5516.282883225155;5174.172132458789;5192.766325515437;5423.915216956703;5439.4288599696465;5450.863728406822 06:44:24 INFO - PROCESS | 1646 | |5;yui.html;705.266522816271;725.8439435521364;745.9440541959351;783.9886546355202;823.8599438095523;14957.17735524547;15834.208289585504;15862.365505379785;15864.048157110552;15892.20538973051;14570.12579245246;14705;14714.190719510423;14736.484223052195;14755.92622036891;681.6148875885134;703.2721133626698;711.1927961763384;861.5649097205918;881.775147337428;859.2481578618708;887.769180013198;888.1518150166576;898.1557336104074;899.4558292233203;941.3645789092369;941.5151197133482;942.1238248428958;943.1370296178987;945.8202941441104;713.9214686384494;935.8642996765465;936.4615346175976;936.6206686292063;939.1406862720603;543.8912217556465;674.9797506074825;687.1547995964941;696.7874798186551;697.5779653309776;13766.348038235476;13767.655808604764;15086.321115549827;15133.621659458491;15138.924305378403;13534.458621655122;13566.593002209851;13602.31988400576;13712.725745485035;13732.244726540037;12527.812082818766;12595.937020314932;12668.239905605693;13027.348632568333;13100.47598096075;3543.1850674344787;3887.086534664352;4484.8878778030485;4622.607078398307;4705.4588722296685;104036.39909002259;104147;105638;107332.4633376836;108154.45922770415;10844.945775271073;10862.511186996604;10875.83686244707;10941.56233750641;10997.230193886511;20955.476113097295;20969;21012.264570740255;21029.474263143544;21029.89485052565;7465.552066876005;7525.285097915689;7611.314981651678;7623.275788800055;7725.806854828675;4316.719413238129;4363.563643635611;4367.388565600819;4367.759773212504;4372.606465418064;3585.1395665040204;3684.5394325709285;3687.6681098701247;3688.981555092207;3689.1514951560753;666.7099811581977;668.6756922892394;671.1912145864222;690.9619970901548;691.6576294734139;575.467692384551;637.677972623822;637.9904301435482;676.4892506157918;711.6263961420295;9507.952460237655;10387.68836934893;10455.483954826508;10797.352158870493;10811.135109191246;547.5025804424895;584.5586582130464;587.8295294364586;601.8164459839703;725.2493669052506;4502.257127573979;4600.688803690932;5042.571381432619;5057.241413787961;5059.645824792303;76538;96448;100172;104096;106885;88559.55720221357;89595;89946;92045;92432.53783731039;76498.61750691211;77520.22479775242;77767.22232777713;78568.2143178561;78903.60548197222;9507.857382139411;9679.983601721719;9713.271504637321;9726.36778609388;9750.804983900423;50251.49748502614;50919.745401272754;51803.481965181374;51963;53140.7631817069;69770.65114674394;71038;71050;71310;72636.63681681558;10538.78922421532;11439.542418303508;11583.305001699924;11632.127590430582;11866.28802271832;10798.622048228275;12777.552785652457;12804.10371274002;13028.804567931678;13067.714882620261;11943.402829858645;12008.099392545419;12139.696507587381;12447.688807779878;12448.066395020229;11934.104942129201;12003.279803211835;12104;12132.878671213175;12513.562025329069;11763.88236117662;11897.286162830258;11922.880771192176;12000.63998080059;12034.217775844541;9651.517424128906;9691.176250018827;10143.949280253551;10235.539400727133;10329.74175645615;9655.517224138905;9749.025097490194;9761.219102471927;9891.752706182404;9910.653127140515 06:44:24 INFO - PROCESS | 1646 | __end_tp_report 06:44:24 INFO - PROCESS | 1646 | __start_cc_report 06:44:24 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,1590 06:44:24 INFO - PROCESS | 1646 | __end_cc_report 06:44:24 INFO - PROCESS | 1646 | __startTimestamp1486046664283__endTimestamp 06:44:24 INFO - PROCESS | 1646 | 06:44:24 INFO - PROCESS | 1646 | ------- Summary: start ------- 06:44:24 INFO - PROCESS | 1646 | Number of tests: 6 06:44:24 INFO - PROCESS | 1646 | 06:44:24 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5710.00 Median:5843.65 stddev:661.13 (11.3%) stddev-sans-first:622.97 06:44:24 INFO - PROCESS | 1646 | Values: 2816.4 3337.4 3343.0 3352.3 3376.6 5564.2 5634.4 5654.7 5671.0 5705.6 5642.3 5658.4 5714.9 5754.7 5800.3 5034.5 5152.5 5186.6 5192.3 5226.5 5151.6 5251.3 5267.3 5285.6 5288.5 5273.9 5275.7 5300.2 5304.6 5307.6 5067.6 5097.9 5127.7 5138.7 5158.7 4292.6 5109.8 5111.0 5148.4 5205.4 5576.9 5596.1 5600.2 5605.1 5610.6 6068.6 6112.8 6114.4 6211.9 6213.0 6189.0 6189.8 6270.8 6280.2 6313.4 5751.9 5806.0 5814.7 5816.5 5822.2 6217.9 6331.4 6339.2 6343.9 6355.5 6017.5 6110.4 6111.6 6117.2 6167.3 6265.3 6279.3 6281.7 6285.5 6287.9 6100.2 6134.6 6137.3 6137.9 6154.8 6168.2 6232.7 6257.5 6271.8 6271.8 4955.4 4979.9 4980.0 4981.7 5002.9 4416.2 4419.5 4456.6 4467.4 4485.5 4830.1 5651.2 5686.0 5745.3 5753.3 6277.7 6282.6 6295.0 6300.7 6326.5 5704.8 5749.4 5749.6 5760.3 5856.0 5307.8 5356.6 5453.4 5458.1 5469.1 6237.8 6283.0 6286.2 6311.7 6337.3 6285.8 6300.6 6309.4 6324.4 6328.2 6267.1 6286.2 6316.2 6324.6 6330.0 5926.8 5967.9 6122.5 6138.9 6186.5 6289.1 6301.2 6301.3 6304.1 6305.4 5026.2 5917.9 6076.2 6109.1 6121.3 5623.6 5887.1 5931.9 6016.5 6023.2 5754.8 5933.5 5948.5 5960.9 5967.3 5843.6 5884.3 6096.9 6102.1 6108.6 5760.1 5786.9 5799.2 5803.8 5810.8 06:44:24 INFO - PROCESS | 1646 | 06:44:24 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:59308.85 Median:36889.67 stddev:119638.80 (324.3%) stddev-sans-first:119892.79 06:44:24 INFO - PROCESS | 1646 | Values: 444.8 474.4 482.1 485.3 516.9 30188.0 39113.4 40876.6 41026.8 42952.6 41752.6 42217.8 42232.6 42261.4 42280.0 8319.4 9243.8 9408.8 9441.2 9441.2 8367.5 11138.4 11353.0 11413.6 11571.4 11284.4 11334.8 11337.8 11367.2 11394.0 8316.5 10037.9 11290.3 11355.4 11363.5 8357.7 10951.1 11254.3 11269.8 11450.7 40162.4 40576.6 40628.8 40629.2 40708.8 4021.4 4028.9 4039.6 4052.1 4184.5 24260.0 27322.0 29822.4 30690.7 30747.8 3071.3 3240.9 3248.4 3281.8 3286.0 337852.0 340209.0 340576.0 340787.0 341552.3 57953.7 58127.4 58127.8 58176.4 58444.7 59708.0 59785.7 59872.7 59942.0 59971.7 15485.4 15720.0 15724.5 15740.9 15753.5 11129.0 11329.7 12251.4 12307.0 12335.4 13469.3 14944.3 15224.9 15240.2 15616.4 5216.3 6076.8 6325.2 6440.2 6539.2 800.4 840.4 852.3 856.3 879.9 28397.7 29290.4 29590.4 30294.5 30555.8 1018.6 1044.8 1052.5 1088.1 1161.6 15452.7 15493.0 15526.6 15542.0 15727.7 559920.6 560742.0 561112.0 565186.0 567862.0 58968.0 58975.7 59015.4 59176.7 59186.7 1397.5 1419.2 1432.5 1434.7 1442.2 26970.2 28957.9 29263.5 29627.4 29765.3 47701.5 48396.3 48401.3 48535.5 48541.0 416831.0 417757.6 418138.0 420216.0 420789.0 37310.8 38227.8 38666.0 38708.6 38757.6 44723.6 44880.5 45061.5 45772.1 46028.8 36505.6 36787.1 37157.0 37311.1 37596.4 36281.5 36413.8 37585.4 37679.8 37943.1 35901.3 36535.3 36591.0 36841.3 36938.1 31942.2 32602.5 32619.8 32715.0 32820.0 24182.8 24200.4 24594.8 24819.9 24932.8 06:44:24 INFO - PROCESS | 1646 | 06:44:24 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:40769.72 Median:394.89 stddev:123170.16 (31191.1%) stddev-sans-first:123478.32 06:44:24 INFO - PROCESS | 1646 | Values: 367.9 393.6 396.2 405.6 423.4 14535.5 15857.6 17477.7 19064.5 21213.8 21805.5 21959.9 22124.1 22302.7 22479.6 5938.3 5982.4 6079.9 6168.6 6233.8 4252.7 5044.6 6170.7 6176.7 6198.0 4490.6 6408.7 6780.4 6829.8 6850.5 6507.8 6651.7 6748.1 6821.8 6904.2 6044.3 6047.9 6050.1 6056.7 6068.0 26982.0 27472.9 27991.6 28088.9 28448.7 2717.1 2735.5 2749.8 2755.4 2836.6 16031.0 16072.3 16251.0 16495.8 16808.1 1826.6 1855.3 1856.9 1859.7 1870.8 126535.4 138432.5 143527.8 147305.3 151447.2 9971.5 10517.6 10681.6 10747.8 10819.5 12863.8 12965.0 12970.9 12971.1 12996.0 8078.6 8169.1 8220.1 8315.0 8328.1 8341.8 8384.1 8427.5 8472.8 8522.0 4048.1 4124.5 4134.0 4182.2 4249.9 2021.6 2061.5 2063.1 2064.7 2072.1 815.0 816.0 823.6 839.7 867.9 19617.5 20004.1 20989.8 21698.3 21800.5 799.3 843.1 846.4 863.1 874.0 7684.9 9251.8 10718.1 11073.8 11118.6 726514.0 731279.2 738594.0 746152.0 746716.0 134720.0 147933.0 155491.0 156734.0 158120.0 965.9 983.3 1037.6 1046.2 1052.9 19614.3 19848.6 19882.6 20197.4 20433.0 59281.7 59293.4 59547.0 59690.8 60618.7 86845.0 88410.0 89414.6 90158.2 90236.5 8030.6 8232.2 8414.8 8486.0 8494.5 7699.6 9744.8 12769.4 12797.4 13264.6 9489.3 11280.9 11448.2 11539.7 11561.7 5294.4 9404.6 10034.3 10854.8 11185.4 9481.9 11289.1 11450.1 11611.3 11694.8 7096.6 7354.1 7421.3 8126.1 8542.2 10271.4 10769.2 10777.5 11189.3 11237.3 06:44:24 INFO - PROCESS | 1646 | 06:44:24 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5301.99 Median:5191.25 stddev:2863.97 (55.2%) stddev-sans-first:2857.15 06:44:24 INFO - PROCESS | 1646 | Values: 1420.4 1428.6 1444.7 1445.8 1446.4 7972.0 8255.8 8261.8 8302.3 8399.0 7432.3 7635.6 7653.7 7997.2 8201.9 2214.7 2237.4 2268.1 2277.1 2285.3 1988.4 2260.5 2294.2 2298.8 2305.9 2005.1 2079.8 2205.1 2265.3 2291.9 2256.4 2272.4 2277.3 2277.5 2279.5 2148.3 2194.2 2214.9 2279.8 2282.5 8255.3 8344.5 8362.4 8368.2 8375.0 4982.4 5171.1 5177.2 5178.1 5181.1 3279.4 3450.7 3455.7 3516.0 3530.6 1162.6 1224.9 1237.2 1258.6 1262.2 10282.4 10608.6 10633.5 10641.6 10661.9 3586.6 4420.9 4454.9 4461.1 4496.1 8641.7 8652.1 8665.0 8669.3 8671.0 5102.3 5106.3 5107.0 5112.2 5112.9 4613.1 4732.7 4806.8 4806.9 4842.6 3085.2 3579.2 3679.7 3681.1 3712.3 1042.0 1164.2 1165.3 1172.2 1176.9 777.2 855.4 889.7 895.9 935.4 5401.3 5429.2 5655.7 5696.5 5855.6 830.8 864.7 970.8 982.8 1012.7 3729.1 3735.6 3780.3 3829.3 3863.0 9253.8 9369.9 9493.3 9633.6 9794.7 9647.4 9749.3 9751.0 9756.9 9769.2 7559.3 7850.8 7864.0 7864.8 7871.0 5493.5 5627.7 5718.2 5761.4 5806.4 8895.9 9032.4 9127.8 9191.4 9203.5 8333.1 8618.0 8757.6 9246.0 9268.5 5625.8 6671.7 7116.8 7122.8 7229.5 7803.2 7899.2 7975.7 7975.8 8001.5 5201.4 6293.1 6300.0 6364.6 6415.6 6862.3 6938.3 6949.9 6992.0 7008.2 6311.3 6415.7 6417.9 6439.7 6440.9 4853.7 4940.8 4964.6 5003.8 5148.9 4955.4 4964.8 5056.1 5069.1 5149.2 06:44:24 INFO - PROCESS | 1646 | 06:44:24 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4372.33 Median:4977.48 stddev:1331.83 (26.8%) stddev-sans-first:1301.00 06:44:24 INFO - PROCESS | 1646 | Values: 355.9 356.2 366.4 374.3 375.1 5256.8 5277.6 5283.4 5343.9 5388.5 4353.5 4935.8 5035.7 5071.9 5125.5 3445.8 3859.6 3978.8 4112.7 4146.7 3781.0 3790.1 4086.2 4101.6 4101.8 3451.6 3452.8 3707.3 3871.7 4101.3 3962.3 3967.7 3975.3 3988.5 4019.8 2868.9 3442.2 3446.2 3455.6 3456.3 4161.7 5070.2 5097.5 5099.5 5108.9 3638.7 4282.8 4520.5 4557.0 4565.9 5520.5 5528.6 5542.9 5550.7 5580.3 3822.3 3823.9 3829.2 3831.1 3837.8 5454.8 5457.6 5732.8 5742.7 5766.0 3411.4 3845.6 3861.0 3861.2 3861.6 3439.0 3545.0 3909.2 3919.3 3935.0 4381.6 4414.4 4495.5 4546.6 4578.7 5209.6 5276.9 5528.6 5620.6 5648.1 1022.4 1129.1 1253.5 1257.5 1262.8 804.4 831.7 832.1 839.4 862.4 4867.9 4899.3 4976.6 4981.4 5019.9 5014.2 5109.6 5371.5 5408.3 5512.3 4408.5 4693.7 4824.5 5294.9 5313.5 2414.4 2746.8 2853.8 2874.8 2891.4 4709.5 5051.9 5621.9 5661.2 5704.0 4623.7 4843.6 5196.2 5461.6 5606.6 4661.2 5354.6 5565.3 5665.5 5712.7 4546.7 4869.1 4894.5 5436.6 5521.1 4597.0 5045.2 5287.4 5368.2 5425.6 4850.0 5284.9 5527.0 5687.4 5741.9 3136.2 3294.7 3474.6 3647.5 3692.2 5195.4 5203.3 5255.5 5461.0 5488.5 5234.3 5256.0 5310.4 5328.4 5359.9 4978.4 5205.3 5231.3 5236.0 5255.6 4330.7 4813.9 5134.9 5187.9 5199.1 4795.2 5338.5 5383.6 5413.2 5516.3 5174.2 5192.8 5423.9 5439.4 5450.9 06:44:24 INFO - PROCESS | 1646 | 06:44:24 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:20241.75 Median:4664.03 stddev:29322.96 (628.7%) stddev-sans-first:29368.52 06:44:24 INFO - PROCESS | 1646 | Values: 705.3 725.8 745.9 784.0 823.9 14957.2 15834.2 15862.4 15864.0 15892.2 14570.1 14705.0 14714.2 14736.5 14755.9 681.6 703.3 711.2 861.6 881.8 859.2 887.8 888.2 898.2 899.5 941.4 941.5 942.1 943.1 945.8 713.9 935.9 936.5 936.6 939.1 543.9 675.0 687.2 696.8 697.6 13766.3 13767.7 15086.3 15133.6 15138.9 13534.5 13566.6 13602.3 13712.7 13732.2 12527.8 12595.9 12668.2 13027.3 13100.5 3543.2 3887.1 4484.9 4622.6 4705.5 104036.4 104147.0 105638.0 107332.5 108154.5 10844.9 10862.5 10875.8 10941.6 10997.2 20955.5 20969.0 21012.3 21029.5 21029.9 7465.6 7525.3 7611.3 7623.3 7725.8 4316.7 4363.6 4367.4 4367.8 4372.6 3585.1 3684.5 3687.7 3689.0 3689.2 666.7 668.7 671.2 691.0 691.7 575.5 637.7 638.0 676.5 711.6 9508.0 10387.7 10455.5 10797.4 10811.1 547.5 584.6 587.8 601.8 725.2 4502.3 4600.7 5042.6 5057.2 5059.6 76538.0 96448.0 100172.0 104096.0 106885.0 88559.6 89595.0 89946.0 92045.0 92432.5 76498.6 77520.2 77767.2 78568.2 78903.6 9507.9 9680.0 9713.3 9726.4 9750.8 50251.5 50919.7 51803.5 51963.0 53140.8 69770.7 71038.0 71050.0 71310.0 72636.6 10538.8 11439.5 11583.3 11632.1 11866.3 10798.6 12777.6 12804.1 13028.8 13067.7 11943.4 12008.1 12139.7 12447.7 12448.1 11934.1 12003.3 12104.0 12132.9 12513.6 11763.9 11897.3 11922.9 12000.6 12034.2 9651.5 9691.2 10143.9 10235.5 10329.7 9655.5 9749.0 9761.2 9891.8 9910.7 06:44:24 INFO - PROCESS | 1646 | -------- Summary: end -------- 06:44:24 INFO - PROCESS | 1646 | 06:44:24 INFO - TEST-INFO | 1646: exit 0 06:44:24 INFO - TEST-OK | dromaeo_css | took 1119249ms 06:44:24 INFO - TEST-START | kraken 06:44:24 INFO - operating with platform_type : mac_ 06:44:24 INFO - Initialising browser for kraken test... 06:44:24 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpeYzCLj/profile http://localhost:49243/getInfo.html) 06:44:27 INFO - PROCESS | 1652 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 06:44:27 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 06:44:27 INFO - PROCESS | 1652 | colorDepth:24 06:44:27 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 06:44:27 INFO - PROCESS | 1652 | __metrics 06:44:27 INFO - PROCESS | 1652 | 1486046667605 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 06:44:27 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:44:27 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:44:28 INFO - TEST-INFO | 1652: exit 0 06:44:28 INFO - Browser initialized. 06:44:28 INFO - Running cycle 1/1 for kraken test... 06:44:28 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpeYzCLj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:44:40 INFO - PROCESS | 1653 | RSS: Main: 237117440 06:44:40 INFO - PROCESS | 1653 | 06:44:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 06:44:44 INFO - PROCESS | 1653 | RSS: Main: 288968704 06:44:44 INFO - PROCESS | 1653 | 06:44:46 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 06:44:47 INFO - PROCESS | 1653 | RSS: Main: 290459648 06:44:47 INFO - PROCESS | 1653 | 06:44:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 06:44:51 INFO - PROCESS | 1653 | RSS: Main: 383041536 06:44:51 INFO - PROCESS | 1653 | 06:44:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 06:44:53 INFO - PROCESS | 1653 | RSS: Main: 284712960 06:44:53 INFO - PROCESS | 1653 | 06:44:55 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:44:56 INFO - PROCESS | 1653 | RSS: Main: 288239616 06:44:56 INFO - PROCESS | 1653 | 06:44:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 06:44:59 INFO - PROCESS | 1653 | RSS: Main: 331091968 06:44:59 INFO - PROCESS | 1653 | 06:45:02 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 06:45:03 INFO - PROCESS | 1653 | RSS: Main: 332439552 06:45:03 INFO - PROCESS | 1653 | 06:45:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 06:45:06 INFO - PROCESS | 1653 | RSS: Main: 331161600 06:45:06 INFO - PROCESS | 1653 | 06:45:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:45:09 INFO - PROCESS | 1653 | RSS: Main: 305618944 06:45:09 INFO - PROCESS | 1653 | 06:45:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:45:11 INFO - PROCESS | 1653 | RSS: Main: 309321728 06:45:11 INFO - PROCESS | 1653 | 06:45:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:45:14 INFO - PROCESS | 1653 | RSS: Main: 310743040 06:45:14 INFO - PROCESS | 1653 | 06:45:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:45:18 INFO - PROCESS | 1653 | RSS: Main: 328810496 06:45:18 INFO - PROCESS | 1653 | 06:45:21 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:45:22 INFO - PROCESS | 1653 | RSS: Main: 322867200 06:45:22 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 06:45:24 INFO - PROCESS | 1653 | RSS: Main: 367661056 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | __start_tp_report 06:45:24 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 06:45:24 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 06:45:24 INFO - PROCESS | 1653 | |i|pagename|runs| 06:45:24 INFO - PROCESS | 1653 | |0;ai-astar;113;113;112;111;112;113;110;111;113;112 06:45:24 INFO - PROCESS | 1653 | |1;audio-beat-detection;140;110;109;145;114;111;107;107;131;112 06:45:24 INFO - PROCESS | 1653 | |2;audio-dft;186;181;182;182;181;184;185;182;183;264 06:45:24 INFO - PROCESS | 1653 | |3;audio-fft;81;59;59;87;59;59;59;60;74;57 06:45:24 INFO - PROCESS | 1653 | |4;audio-oscillator;90;70;84;71;69;69;86;68;69;82 06:45:24 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;87;84;86;84;87;84;88;84;94;131 06:45:24 INFO - PROCESS | 1653 | |6;imaging-darkroom;95;97;96;97;96;95;107;96;94;95 06:45:24 INFO - PROCESS | 1653 | |7;imaging-desaturate;92;86;85;85;85;85;86;85;87;85 06:45:24 INFO - PROCESS | 1653 | |8;json-parse-financial;60;58;56;57;58;58;58;58;58;58 06:45:24 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;68;74;78;78;72;69;73;71;77;71 06:45:24 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;87;85;85;87;89;108;107;86;89;87 06:45:24 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;147;152;134;151;155;148;134;140;180;174 06:45:24 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;228;243;175;174;176;175;174;173;174;176 06:45:24 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;69;67;69;68;69;77;87;90;85;74 06:45:24 INFO - PROCESS | 1653 | __end_tp_report 06:45:24 INFO - PROCESS | 1653 | __start_cc_report 06:45:24 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,2059 06:45:24 INFO - PROCESS | 1653 | __end_cc_report 06:45:24 INFO - PROCESS | 1653 | __startTimestamp1486046724253__endTimestamp 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | ------- Summary: start ------- 06:45:24 INFO - PROCESS | 1653 | Number of tests: 14 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.50 stddev:1.05 (0.9%) stddev-sans-first:1.05 06:45:24 INFO - PROCESS | 1653 | Values: 113.0 113.0 112.0 111.0 112.0 113.0 110.0 111.0 113.0 112.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:118.60 Median:113.00 stddev:14.40 (12.7%) stddev-sans-first:13.03 06:45:24 INFO - PROCESS | 1653 | Values: 140.0 110.0 109.0 145.0 114.0 111.0 107.0 107.0 131.0 112.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:191.00 Median:183.50 stddev:25.70 (14.0%) stddev-sans-first:27.20 06:45:24 INFO - PROCESS | 1653 | Values: 186.0 181.0 182.0 182.0 181.0 184.0 185.0 182.0 183.0 264.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:65.40 Median:59.50 stddev:11.00 (18.5%) stddev-sans-first:10.11 06:45:24 INFO - PROCESS | 1653 | Values: 81.0 59.0 59.0 87.0 59.0 59.0 59.0 60.0 74.0 57.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:76.50 stddev:8.61 (11.3%) stddev-sans-first:7.45 06:45:24 INFO - PROCESS | 1653 | Values: 90.0 70.0 84.0 71.0 69.0 69.0 86.0 68.0 69.0 82.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:90.90 Median:86.50 stddev:14.42 (16.7%) stddev-sans-first:15.22 06:45:24 INFO - PROCESS | 1653 | Values: 87.0 84.0 86.0 84.0 87.0 84.0 88.0 84.0 94.0 131.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:96.00 stddev:3.71 (3.9%) stddev-sans-first:3.87 06:45:24 INFO - PROCESS | 1653 | Values: 95.0 97.0 96.0 97.0 96.0 95.0 107.0 96.0 94.0 95.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:85.50 stddev:2.18 (2.6%) stddev-sans-first:0.73 06:45:24 INFO - PROCESS | 1653 | Values: 92.0 86.0 85.0 85.0 85.0 85.0 86.0 85.0 87.0 85.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.00 stddev:0.99 (1.7%) stddev-sans-first:0.71 06:45:24 INFO - PROCESS | 1653 | Values: 60.0 58.0 56.0 57.0 58.0 58.0 58.0 58.0 58.0 58.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:73.50 stddev:3.60 (4.9%) stddev-sans-first:3.32 06:45:24 INFO - PROCESS | 1653 | Values: 68.0 74.0 78.0 78.0 72.0 69.0 73.0 71.0 77.0 71.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:87.00 stddev:8.81 (10.1%) stddev-sans-first:9.22 06:45:24 INFO - PROCESS | 1653 | Values: 87.0 85.0 85.0 87.0 89.0 108.0 107.0 86.0 89.0 87.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:151.50 stddev:15.31 (10.1%) stddev-sans-first:16.15 06:45:24 INFO - PROCESS | 1653 | Values: 147.0 152.0 134.0 151.0 155.0 148.0 134.0 140.0 180.0 174.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.80 Median:175.50 stddev:25.93 (14.8%) stddev-sans-first:22.81 06:45:24 INFO - PROCESS | 1653 | Values: 228.0 243.0 175.0 174.0 176.0 175.0 174.0 173.0 174.0 176.0 06:45:24 INFO - PROCESS | 1653 | 06:45:24 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.50 Median:75.50 stddev:8.77 (11.6%) stddev-sans-first:8.98 06:45:24 INFO - PROCESS | 1653 | Values: 69.0 67.0 69.0 68.0 69.0 77.0 87.0 90.0 85.0 74.0 06:45:24 INFO - PROCESS | 1653 | -------- Summary: end -------- 06:45:24 INFO - PROCESS | 1653 | 06:45:25 INFO - TEST-INFO | 1653: exit 0 06:45:25 INFO - TEST-OK | kraken | took 60214ms 06:45:25 INFO - SUITE-END | took 1179s 06:45:25 INFO - Completed test suite (00:19:39) 06:45:25 INFO - javascript benchmark 06:45:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7176.824937310223, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2816.4085542036173, 3337.415952208363, 3342.9665703342976, 3352.2625022495045, 3376.5610470638812, 5564.165375193713, 5634.408387119355, 5654.688992105433, 5671.0, 5705.600607957439, 5642.294713160855, 5658.405867383927, 5714.885702285952, 5754.65472071675, 5800.332961709394, 5034.5468907798295, 5152.458991805863, 5186.611004174702, 5192.3249977503065, 5226.477352264762, 5151.613628977823, 5251.343582052244, 5267.315249017623, 5285.577153827685, 5288.4711528847, 5273.947260527422, 5275.683458992473, 5300.178472336794, 5304.602154838402, 5307.628466007381, 5067.61992850534, 5097.898042039175, 5127.717975511346, 5138.665986710852, 5158.74206289684, 4292.635126014293, 5109.846704598868, 5111.0, 5148.4079330876675, 5205.401378841406, 5576.9163462548095, 5596.132599447093, 5600.215969764236, 5605.0751625982075, 5610.635308704921, 6068.605540639845, 6112.847178820521, 6114.419130182626, 6211.9129152398145, 6212.968935155295, 6189.009758438673, 6189.81430557084, 6270.7805226816845, 6280.214973128359, 6313.368663133696, 5751.8562035949435, 5806.0129777937855, 5814.680192589448, 5816.534677225812, 5822.214001109838, 6217.937820621826, 6331.430171284606, 6339.2392912850755, 6343.873122537523, 6355.523335749837, 6017.458428741435, 6110.406879924458, 6111.602745821508, 6117.235345581802, 6167.259928808572, 6265.310815810257, 6279.340669229757, 6281.685915704196, 6285.465735412449, 6287.937120628827, 6100.206973093469, 6134.601250918676, 6137.297691397357, 6137.897974673478, 6154.815355539341, 6168.167297414881, 6232.657203853832, 6257.530685198628, 6271.811845644638, 6271.843203919847, 4955.4301255356095, 4979.875503112378, 4979.950200497949, 4981.651284410129, 5002.924956125661, 4416.205083085076, 4419.513853476115, 4456.554344565582, 4467.419235499365, 4485.511707811147, 4830.130576496264, 5651.237082993744, 5685.971570142206, 5745.2818397700285, 5753.252077229933, 6277.7175027123885, 6282.560220784592, 6294.9685251574365, 6300.684965751694, 6326.49388048955, 5704.828855134353, 5749.367569567345, 5749.626274292157, 5760.337561180518, 5855.970720146372, 5307.761150748225, 5356.625036247426, 5453.400125986138, 5458.099413596712, 5469.07025805614, 6237.812865614039, 6283.02613094971, 6286.15136956505, 6311.684415779285, 6337.271213810471, 6285.842853928689, 6300.621962682254, 6309.400606942333, 6324.430801227912, 6328.208973878265, 6267.122602835698, 6286.245650521967, 6316.178896743394, 6324.588901721373, 6330.0, 5926.762929482772, 5967.850803729942, 6122.489639783254, 6138.878900475427, 6186.536009799193, 6289.088082228142, 6301.212348456443, 6301.306856245717, 6304.05439184126, 6305.432511074026, 5026.246063090565, 5917.911231331447, 6076.179715738495, 6109.08363745424, 6121.296050954197, 5623.606347555796, 5887.116932460165, 5931.911021334769, 6016.458518733335, 6023.216981792339, 5754.827355179351, 5933.495652869382, 5948.464638182584, 5960.850978725566, 5967.254093238345, 5843.649381037151, 5884.264466941632, 6096.9390306096375, 6102.115193297036, 6108.633481991094, 5760.135979603093, 5786.9131963019745, 5799.159121927381, 5803.82588522345, 5810.796622118206], "value": 5666.032222841844, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.768720265462, 474.3975151557522, 482.13456844963287, 485.30116632049834, 516.9224616307554, 30188.0, 39113.41329880051, 40876.59123408762, 41026.7948660257, 42952.57047429525, 41752.58247417525, 42217.788911055475, 42232.57767422325, 42261.366079508756, 42280.0, 8319.417640765148, 9243.768905777359, 9408.81182376352, 9441.19749821266, 9441.244700423984, 8367.497950122981, 11138.443077846076, 11353.0, 11413.600523981662, 11571.363575003372, 11284.435778211056, 11334.773304533946, 11337.773244535145, 11367.204295699305, 11394.0, 8316.459430137022, 10037.949810250975, 11290.322580645146, 11355.375454350007, 11363.545458181663, 8357.749267521984, 10951.123910087173, 11254.26847254934, 11269.830952535718, 11450.713732156679, 40162.39756403627, 40576.59423405758, 40628.79685601553, 40629.18741625151, 40708.79645601783, 4021.399733497732, 4028.9194216115516, 4039.636432721069, 4052.1085361220485, 4184.51878034027, 24260.0, 27322.0, 29822.40355192884, 30690.69309306923, 30747.846260768776, 3071.262896904749, 3240.9351812963846, 3248.447763880146, 3281.770276080675, 3285.983570082134, 337852.0, 340209.0, 340576.0, 340787.0, 341552.2922385397, 57953.710231448575, 58127.418725812204, 58127.837443251745, 58176.4182358171, 58444.70777646085, 59708.0, 59785.70107149436, 59872.70063649654, 59942.0, 59971.70014149989, 15485.380584776709, 15720.0, 15724.528264152093, 15740.92129539345, 15753.527394177963, 11128.998390144925, 11329.660110196708, 12251.448684809206, 12306.953908917842, 12335.383230838423, 13469.326533673277, 14944.252787360805, 15224.858135639872, 15240.23798810055, 15616.3753449863, 5216.322492642677, 6076.815021070849, 6325.177726895474, 6440.194975628046, 6539.2479864815205, 800.3557136505053, 840.3907167303656, 852.2840813716508, 856.2550580994574, 879.8988116366572, 28397.716022839508, 29290.414191716045, 29590.408191836043, 30294.54558181629, 30555.847220763753, 1018.5620183321244, 1044.8380501022355, 1052.5000624703205, 1088.1294964028903, 1161.5644133449957, 15452.690946181014, 15493.0, 15526.611834704223, 15542.0, 15727.68544629124, 559920.6091299553, 560742.0, 561112.0, 565186.0, 567862.0, 58968.0, 58975.705121474115, 59015.40984590185, 59176.704116479144, 59186.70406647939, 1397.4619771387886, 1419.2407062221869, 1432.5057855040202, 1434.734574103794, 1442.213993373614, 26970.190894273204, 28957.855210724654, 29263.48838139437, 29627.40745185127, 29765.25586860259, 47701.522984769705, 48396.274055889895, 48401.27398088961, 48535.5146448531, 48541.0, 416831.0, 417757.5793936417, 418138.0, 420216.0, 420789.0, 37310.813445932596, 38227.80886095552, 38666.03334916649, 38708.6129138705, 38757.6124238754, 44723.55276447194, 44880.492328611515, 45061.549384505735, 45772.08455830798, 46028.7698561505, 36505.63494365022, 36787.08032299214, 37157.0, 37311.06722331963, 37596.436053459765, 36281.45577816282, 36413.817930910176, 37585.4362184562, 37679.81160094182, 37943.05142371352, 35901.281974359845, 36535.26929461343, 36591.0, 36841.263174736894, 36938.076548086516, 31942.201444964063, 32602.51096233511, 32619.836900815342, 32715.0, 32820.0, 24182.75817241805, 24200.394990124685, 24594.75405245996, 24819.88310526067, 24932.750672493043], "value": 19434.228008278926, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.93193259247056, 393.58083640922416, 396.1957226829537, 405.5984575270483, 423.4030017675077, 14535.493996860967, 15857.603559911007, 17477.650446991054, 19064.523386915334, 21213.787862121375, 21805.454863628416, 21959.890200549016, 22124.115035398605, 22302.665460018114, 22479.55040899181, 5938.257717785277, 5982.401759824026, 6079.878402431971, 6168.568200225964, 6233.812985610416, 4252.702310838243, 5044.596432285409, 6170.660613666247, 6176.72204750785, 6197.969010154943, 4490.5509449055, 6408.711607977652, 6780.389764921156, 6829.760958366458, 6850.451963842906, 6507.837304067389, 6651.733930642768, 6748.089007983908, 6821.829454263633, 6904.206016308139, 6044.304904935944, 6047.879042419127, 6050.122732203806, 6056.666883321415, 6067.969660151715, 26982.0, 27472.862635686695, 27991.580126298122, 28088.859555702296, 28448.71551284502, 2717.0761940940174, 2735.4529094181044, 2749.752522273005, 2755.4489102179646, 2836.602875597418, 16030.992251937016, 16072.276747546388, 16251.0, 16495.752563711554, 16808.075555844425, 1826.6072794349145, 1855.3320804510365, 1856.9442916712521, 1859.6838537448532, 1870.8120343581788, 126535.36732316372, 138432.53918652012, 143527.8001369885, 147305.26347368196, 151447.18487668192, 9971.451570163566, 10517.579296828042, 10681.572737090584, 10747.83878241827, 10819.459027048617, 12863.807042894365, 12965.027622928317, 12970.870291296966, 12971.09202355826, 12996.02529810268, 8078.55567943757, 8169.060558035783, 8220.095789463154, 8315.04376996641, 8328.125546817497, 8341.8331633367, 8384.077751447336, 8427.494350338999, 8472.788180295542, 8521.957390213009, 4048.149888523384, 4124.546299907008, 4134.0, 4182.163567286494, 4249.936250956238, 2021.6361055009945, 2061.526328137094, 2063.0613071052635, 2064.669652855536, 2072.067569593689, 814.9592520373958, 816.0044745410589, 823.5923218007125, 839.7480755773365, 867.9218870301577, 19617.509562261057, 20004.099815508336, 20989.790102098785, 21698.338473783577, 21800.454988625093, 799.2606838674319, 843.1105184030783, 846.4413487098485, 863.1497974495118, 873.9694110706095, 7684.884726729104, 9251.814963700688, 10718.142548596094, 11073.833892491619, 11118.555257789598, 726514.0, 731279.1848733639, 738594.0, 746152.0, 746716.0, 134720.0, 147933.0, 155491.0, 156734.0, 158120.0, 965.9227261819178, 983.2527279267665, 1037.5953378182514, 1046.1578429364283, 1052.9052385285208, 19614.332781713714, 19848.60302793965, 19882.60234795325, 20197.394078177676, 20433.0, 59281.70359148177, 59293.407065930514, 59547.0, 59690.80618387521, 60618.696906515186, 86845.0, 88410.0, 89414.55292723495, 90158.19683606422, 90236.54881725549, 8030.638621262174, 8232.217939295757, 8414.83170336602, 8486.0, 8494.490330580185, 7699.615019249127, 9744.805103898025, 12769.361531923552, 12797.36013199355, 13264.602061938158, 9489.288303377412, 11280.887191127984, 11448.198626096093, 11539.711507212387, 11561.710957226136, 5294.417614062373, 9404.623815047322, 10034.297599167989, 10854.782904342026, 11185.440727963733, 9481.905180948102, 11289.08085625462, 11450.141239406912, 11611.303321800719, 11694.824577631513, 7096.574205547684, 7354.117505899112, 7421.3320801126, 8126.065502467292, 8542.18849209324, 10271.435071070864, 10769.192310576895, 10777.46112694378, 11189.272697274651, 11237.3257604544], "value": 9843.03306263645, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.4034305591651, 1428.649980754715, 1444.689391780767, 1445.8072090525313, 1446.3563714147208, 7971.960140199298, 8255.752327430171, 8261.75214743558, 8302.252797248246, 8398.958005209981, 7432.331090201881, 7635.618219089038, 7653.655585498652, 7997.160298168681, 8201.876971845428, 2214.6567282071223, 2237.407087121914, 2268.0814270220503, 2277.1119263487253, 2285.2915596165158, 1988.4332965104948, 2260.49138943738, 2294.1855641247366, 2298.7586303438147, 2305.8847057647135, 2005.1077270614583, 2079.8232150267245, 2205.095910676615, 2265.29775769512, 2291.8854057297067, 2256.3569382725927, 2272.3864556569715, 2277.2712731925626, 2277.533105713325, 2279.5326957973575, 2148.2803260907613, 2194.1897934204344, 2214.8671079735104, 2279.806216471602, 2282.4978504728933, 8255.339572834158, 8344.541050242235, 8362.372822038311, 8368.24685778283, 8374.958125209336, 4982.377202849644, 5171.120909445366, 5177.171652535576, 5178.093833579146, 5181.119209734316, 3279.3769183854993, 3450.723942084627, 3455.6889879910677, 3516.0155156556207, 3530.5586801649793, 1162.6105254739673, 1224.9142560020803, 1237.1772771107294, 1258.6098309524077, 1262.1606631590073, 10282.383057016603, 10608.62869799561, 10633.46832658364, 10641.627543035956, 10661.893381066167, 3586.5696116466193, 4420.911581768347, 4454.888627784267, 4461.08547747711, 4496.100779843979, 8641.654333826576, 8652.134786521297, 8665.0, 8669.306455483546, 8671.002834673967, 5102.311187989648, 5106.285120083191, 5106.974465127651, 5112.185174263083, 5112.923306150411, 4613.0773845230415, 4732.692374995683, 4806.759662016885, 4806.903861922742, 4842.612590992712, 3085.228692826793, 3579.2304654499408, 3679.6688298053305, 3681.1349332906743, 3712.276106159327, 1041.9791604167874, 1164.2490593567102, 1165.3299352871963, 1172.2146162071263, 1176.8705442401329, 777.1956025513566, 855.4012191465998, 889.6930558957149, 895.861141523052, 935.4387367579398, 5401.297831281908, 5429.239906413105, 5655.717214139277, 5696.458836410535, 5855.59010869235, 830.759079866844, 864.6714248585623, 970.8301047316692, 982.7666278820035, 1012.7441971954723, 3729.067733066733, 3735.551733791908, 3780.31954248238, 3829.310724069694, 3863.0, 9253.768655783659, 9369.859452108223, 9493.288003399774, 9633.566489507988, 9794.706158815246, 9647.421154730739, 9749.268804839665, 9750.951245243728, 9756.902430975599, 9769.16962058233, 7559.3196612305155, 7850.764477065812, 7863.95537160331, 7864.803379915548, 7871.0, 5493.505584497414, 5627.748900439857, 5718.228039214653, 5761.395053519488, 5806.4193580641595, 8895.866562001445, 9032.36773425854, 9127.77180570491, 9191.356605037583, 9203.539823008956, 8333.083360830182, 8618.0, 8757.60590773404, 9246.0, 9268.490233036979, 5625.803096891589, 6671.66641667905, 7116.786496405116, 7122.789125848514, 7229.457790665826, 7803.180666030212, 7899.210078992055, 7975.680972761024, 7975.84048319042, 8001.479903806234, 5201.375834899836, 6293.087502312231, 6299.968500157654, 6364.586301890362, 6415.615063096229, 6862.348076932683, 6938.340857618518, 6949.930500694928, 6992.021117043516, 7008.194057683431, 6311.305756366888, 6415.743370265137, 6417.903731443938, 6439.710213040331, 6440.937245354464, 4853.708777473363, 4940.7776650051555, 4964.602831773377, 5003.824866129668, 5148.871278218075, 4955.35580374549, 4964.751762411937, 5056.089903817348, 5069.138246498178, 5149.150390185578], "value": 4318.62575079541, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.9412696905011, 356.1719003317288, 366.3588719740454, 374.28698329681964, 375.07918061159853, 5256.7634456449505, 5277.630565860387, 5283.445238249986, 5343.89312213756, 5388.541973932211, 4353.49934757503, 4935.815404302968, 5035.723035233061, 5071.873203169914, 5125.513076257749, 3445.8104804235695, 3859.6333348331864, 3978.781167035812, 4112.712110152291, 4146.709730318879, 3780.9980355205894, 3790.1093243087853, 4086.2440448516973, 4101.610347017028, 4101.79491025449, 3451.637578054306, 3452.8087809705735, 3707.3326801175776, 3871.728978971459, 4101.302778527643, 3962.2556683933694, 3967.690662081506, 3975.344068228724, 3988.541317748467, 4019.839206431739, 2868.928276793076, 3442.208292092833, 3446.172918499567, 3455.5507783988173, 3456.2914602506426, 4161.729487583298, 5070.188769796851, 5097.541221290065, 5099.515546023122, 5108.872278193037, 3638.6725194732603, 4282.785860706952, 4520.547945205486, 4557.012613962783, 4565.863024109282, 5520.475554822286, 5528.585356098269, 5542.889142217174, 5550.666959982414, 5580.330360356743, 3822.26087130355, 3823.9426408603895, 3829.215010922782, 3831.1188426662025, 3837.808109594509, 5454.809081682122, 5457.561736106228, 5732.760990040655, 5742.7415766290615, 5765.971170144123, 3411.4030044742567, 3845.615438456132, 3861.0, 3861.189150278473, 3861.613838616172, 3439.0053768814028, 3544.9822750886083, 3909.179072394829, 3919.2553414851086, 3934.9606503935165, 4381.583749543788, 4414.359917811964, 4495.483019452805, 4546.636269098464, 4578.725276483422, 5209.609279304067, 5276.868078298074, 5528.590209496552, 5620.578456615688, 5648.068068768607, 1022.4223313827664, 1129.1418521923233, 1253.5111306590436, 1257.4970011995092, 1262.8295180150747, 804.4208170117623, 831.7296878514506, 832.134580036767, 839.4333824668324, 862.3575436299872, 4867.9269810952865, 4899.252072164797, 4976.606550165887, 4981.402231732215, 5019.924701129486, 5014.222795466636, 5109.642325037212, 5371.4628537146755, 5408.295852073963, 5512.283403157644, 4408.515063343029, 4693.6949098308505, 4824.517548245218, 5294.894102117936, 5313.495217954288, 2414.4084699248447, 2746.83259614667, 2853.7586150024804, 2874.7843911706705, 2891.3783536539745, 4709.505501922317, 5051.924221136685, 5621.915671264935, 5661.207430959669, 5703.975088843484, 4623.745693986796, 4843.564079232886, 5196.2062300534635, 5461.617686761968, 5606.635568688022, 4661.230896902041, 5354.625176237704, 5565.276514053228, 5665.4617811307035, 5712.714364281853, 4546.74992875395, 4869.147899117711, 4894.510548945077, 5436.6194366393975, 5521.0738308696455, 4596.988662494246, 5045.2432135180015, 5287.391949925808, 5368.248445217595, 5425.647332923348, 4849.987255289016, 5284.867878303073, 5526.972365138148, 5687.440651706831, 5741.885162296814, 3136.153238625604, 3294.7364210862593, 3474.6004209516227, 3647.507586475792, 3692.2246328271362, 5195.402528709247, 5203.297554830049, 5255.45110621356, 5461.006732542673, 5488.463230295523, 5234.2672025915645, 5255.973720131375, 5310.389305229948, 5328.442347673216, 5359.919601205907, 4978.361468421742, 5205.297284866646, 5231.293775340432, 5235.973820130875, 5255.63210575256, 4330.653547716112, 4813.9037219256115, 5134.922976155286, 5187.948120518747, 5199.076341753575, 4795.160846851717, 5338.54622357104, 5383.5962302826665, 5413.215083812904, 5516.282883225155, 5174.172132458789, 5192.766325515437, 5423.915216956703, 5439.4288599696465, 5450.863728406822], "value": 3959.724077900281, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.266522816271, 725.8439435521364, 745.9440541959351, 783.9886546355202, 823.8599438095523, 14957.17735524547, 15834.208289585504, 15862.365505379785, 15864.048157110552, 15892.20538973051, 14570.12579245246, 14705.0, 14714.190719510423, 14736.484223052195, 14755.92622036891, 681.6148875885134, 703.2721133626698, 711.1927961763384, 861.5649097205918, 881.775147337428, 859.2481578618708, 887.769180013198, 888.1518150166576, 898.1557336104074, 899.4558292233203, 941.3645789092369, 941.5151197133482, 942.1238248428958, 943.1370296178987, 945.8202941441104, 713.9214686384494, 935.8642996765465, 936.4615346175976, 936.6206686292063, 939.1406862720603, 543.8912217556465, 674.9797506074825, 687.1547995964941, 696.7874798186551, 697.5779653309776, 13766.348038235476, 13767.655808604764, 15086.321115549827, 15133.621659458491, 15138.924305378403, 13534.458621655122, 13566.593002209851, 13602.31988400576, 13712.725745485035, 13732.244726540037, 12527.812082818766, 12595.937020314932, 12668.239905605693, 13027.348632568333, 13100.47598096075, 3543.1850674344787, 3887.086534664352, 4484.8878778030485, 4622.607078398307, 4705.4588722296685, 104036.39909002259, 104147.0, 105638.0, 107332.4633376836, 108154.45922770415, 10844.945775271073, 10862.511186996604, 10875.83686244707, 10941.56233750641, 10997.230193886511, 20955.476113097295, 20969.0, 21012.264570740255, 21029.474263143544, 21029.89485052565, 7465.552066876005, 7525.285097915689, 7611.314981651678, 7623.275788800055, 7725.806854828675, 4316.719413238129, 4363.563643635611, 4367.388565600819, 4367.759773212504, 4372.606465418064, 3585.1395665040204, 3684.5394325709285, 3687.6681098701247, 3688.981555092207, 3689.1514951560753, 666.7099811581977, 668.6756922892394, 671.1912145864222, 690.9619970901548, 691.6576294734139, 575.467692384551, 637.677972623822, 637.9904301435482, 676.4892506157918, 711.6263961420295, 9507.952460237655, 10387.68836934893, 10455.483954826508, 10797.352158870493, 10811.135109191246, 547.5025804424895, 584.5586582130464, 587.8295294364586, 601.8164459839703, 725.2493669052506, 4502.257127573979, 4600.688803690932, 5042.571381432619, 5057.241413787961, 5059.645824792303, 76538.0, 96448.0, 100172.0, 104096.0, 106885.0, 88559.55720221357, 89595.0, 89946.0, 92045.0, 92432.53783731039, 76498.61750691211, 77520.22479775242, 77767.22232777713, 78568.2143178561, 78903.60548197222, 9507.857382139411, 9679.983601721719, 9713.271504637321, 9726.36778609388, 9750.804983900423, 50251.49748502614, 50919.745401272754, 51803.481965181374, 51963.0, 53140.7631817069, 69770.65114674394, 71038.0, 71050.0, 71310.0, 72636.63681681558, 10538.78922421532, 11439.542418303508, 11583.305001699924, 11632.127590430582, 11866.28802271832, 10798.622048228275, 12777.552785652457, 12804.10371274002, 13028.804567931678, 13067.714882620261, 11943.402829858645, 12008.099392545419, 12139.696507587381, 12447.688807779878, 12448.066395020229, 11934.104942129201, 12003.279803211835, 12104.0, 12132.878671213175, 12513.562025329069, 11763.88236117662, 11897.286162830258, 11922.880771192176, 12000.63998080059, 12034.217775844541, 9651.517424128906, 9691.176250018827, 10143.949280253551, 10235.539400727133, 10329.74175645615, 9655.517224138905, 9749.025097490194, 9761.219102471927, 9891.752706182404, 9910.653127140515], "value": 7371.555931186389, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1472.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 111.0, 112.0, 113.0, 110.0, 111.0, 113.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 110.0, 109.0, 145.0, 114.0, 111.0, 107.0, 107.0, 131.0, 112.0], "value": 118.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 181.0, 182.0, 182.0, 181.0, 184.0, 185.0, 182.0, 183.0, 264.0], "value": 191.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 87.0, 59.0, 59.0, 59.0, 60.0, 74.0, 57.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 84.0, 71.0, 69.0, 69.0, 86.0, 68.0, 69.0, 82.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 84.0, 87.0, 84.0, 88.0, 84.0, 94.0, 131.0], "value": 90.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 97.0, 96.0, 95.0, 107.0, 96.0, 94.0, 95.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 86.0, 85.0, 85.0, 85.0, 85.0, 86.0, 85.0, 87.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 56.0, 57.0, 58.0, 58.0, 58.0, 58.0, 58.0, 58.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 78.0, 78.0, 72.0, 69.0, 73.0, 71.0, 77.0, 71.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 87.0, 89.0, 108.0, 107.0, 86.0, 89.0, 87.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 152.0, 134.0, 151.0, 155.0, 148.0, 134.0, 140.0, 180.0, 174.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 243.0, 175.0, 174.0, 176.0, 175.0, 174.0, 173.0, 174.0, 176.0], "value": 186.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 69.0, 68.0, 69.0, 77.0, 87.0, 90.0, 85.0, 74.0], "value": 75.5, "unit": "score"}]}]} 06:45:25 INFO - Return code: 0 06:45:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:45:25 ERROR - # TBPL SUCCESS # 06:45:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:45:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:45:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:45:25 INFO - 2017-02-02 06:45:25.578 screenresolution[1657:10332] starting screenresolution argv=screenresolution get 06:45:25 INFO - 2017-02-02 06:45:25.581 screenresolution[1657:10332] Display 0: 1600x1200x32@60 06:45:25 INFO - 2017-02-02 06:45:25.588 screenresolution[1658:10335] starting screenresolution argv=screenresolution list 06:45:25 INFO - Available Modes on Display 0 06:45:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:45:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:45:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:45:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:45:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:45:25 INFO - Intel Iris: 06:45:25 INFO - Chipset Model: Intel Iris 06:45:25 INFO - Type: GPU 06:45:25 INFO - Bus: Built-In 06:45:25 INFO - VRAM (Dynamic, Max): 1536 MB 06:45:25 INFO - Vendor: Intel (0x8086) 06:45:25 INFO - Device ID: 0x0a2e 06:45:25 INFO - Revision ID: 0x0009 06:45:25 INFO - Displays: 06:45:25 INFO - Display: 06:45:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:45:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:45:25 INFO - Display Serial Number: 1600x1200 60 06:45:25 INFO - Main Display: Yes 06:45:25 INFO - Mirror: Off 06:45:25 INFO - Online: Yes 06:45:25 INFO - Rotation: Supported 06:45:25 INFO - Return code: 0 06:45:25 INFO - Running post-action listener: _resource_record_post_action 06:45:25 INFO - [mozharness: 2017-02-02 14:45:25.821145Z] Finished run-tests step (success) 06:45:25 INFO - Running post-run listener: _resource_record_post_run 06:45:25 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 33512448; Write bytes: 553517056; Read time: 313; Write time: 1747 06:45:25 INFO - TinderboxPrint: CPU usage
53.6% 06:45:25 INFO - TinderboxPrint: I/O read bytes / time
33,512,448 / 313 06:45:25 INFO - TinderboxPrint: I/O write bytes / time
553,517,056 / 1,747 06:45:25 INFO - TinderboxPrint: CPU idle
2,229.3 (46.4%) 06:45:25 INFO - TinderboxPrint: CPU system
958.4 (19.9%) 06:45:25 INFO - TinderboxPrint: CPU user
1,622.1 (33.7%) 06:45:25 INFO - TinderboxPrint: Swap in / out
281,382,912 / 0 06:45:25 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 246772224; Write bytes: 241129472; Read time: 18004; Write time: 497 06:45:25 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 31427584; Write bytes: 297031680; Read time: 290; Write time: 1200 06:45:26 INFO - Running post-run listener: _upload_blobber_files 06:45:26 INFO - Blob upload gear active. 06:45:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:45:26 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 06:45:26 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'] 06:45:26 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 06:45:26 INFO - (blobuploader) - INFO - Open directory for files ... 06:45:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:45:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:45:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:45:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:45:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:45:27 INFO - (blobuploader) - INFO - Done attempting. 06:45:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:45:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:45:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:45:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:45:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:45:28 INFO - (blobuploader) - INFO - Done attempting. 06:45:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:45:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:45:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:45:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:45:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:45:30 INFO - (blobuploader) - INFO - Done attempting. 06:45:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:45:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:45:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:45:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:45:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:45:32 INFO - (blobuploader) - INFO - Done attempting. 06:45:32 INFO - (blobuploader) - INFO - Iteration through files over. 06:45:32 INFO - Return code: 0 06:45:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:45:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:45:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0737630a979c108b0a88d907964818d024e4c5c540983863e4112b1773677be595b9dfd6128d1e1e7d6a1d69eee3a2b129a1fee5a624b9e3041fab864bd15862", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e45307006c124cab2aad0db7eb5d1ad9b3939c60ea894813b0d65c7dfdda1e171a888f7b6530fd3ec581d8e5ed8cc7cd8743aedd85f58d0d41a6d05f9ba2f52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8656910221f21a1710262688daad35d61c307d594f2b1dcf9eda2027b351b44798bb0b3d1b4dc8711e3956797a7e3f9ac35333dfcad46fe9cc5fe0826997b2c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06719b88a135201e3a1791fbbf3d7eb7c45bd742ae77c65b467dc2e16317be1cd4bc8083fd323d535d7fe62b9ee90aa2ad31a1e63a49b548c4d91c97807d5851"} 06:45:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:45:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:45:32 INFO - Contents: 06:45:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0737630a979c108b0a88d907964818d024e4c5c540983863e4112b1773677be595b9dfd6128d1e1e7d6a1d69eee3a2b129a1fee5a624b9e3041fab864bd15862", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e45307006c124cab2aad0db7eb5d1ad9b3939c60ea894813b0d65c7dfdda1e171a888f7b6530fd3ec581d8e5ed8cc7cd8743aedd85f58d0d41a6d05f9ba2f52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8656910221f21a1710262688daad35d61c307d594f2b1dcf9eda2027b351b44798bb0b3d1b4dc8711e3956797a7e3f9ac35333dfcad46fe9cc5fe0826997b2c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06719b88a135201e3a1791fbbf3d7eb7c45bd742ae77c65b467dc2e16317be1cd4bc8083fd323d535d7fe62b9ee90aa2ad31a1e63a49b548c4d91c97807d5851"} 06:45:32 INFO - Running post-run listener: copy_logs_to_upload_dir 06:45:32 INFO - Copying logs to upload dir... 06:45:32 INFO - mkdir: /builds/slave/test/build/upload/logs 06:45:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.026037 ========= master_lag: 0.66 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-02 06:45:33.091115) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 06:45:33.094777) ========= 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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0737630a979c108b0a88d907964818d024e4c5c540983863e4112b1773677be595b9dfd6128d1e1e7d6a1d69eee3a2b129a1fee5a624b9e3041fab864bd15862", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e45307006c124cab2aad0db7eb5d1ad9b3939c60ea894813b0d65c7dfdda1e171a888f7b6530fd3ec581d8e5ed8cc7cd8743aedd85f58d0d41a6d05f9ba2f52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8656910221f21a1710262688daad35d61c307d594f2b1dcf9eda2027b351b44798bb0b3d1b4dc8711e3956797a7e3f9ac35333dfcad46fe9cc5fe0826997b2c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06719b88a135201e3a1791fbbf3d7eb7c45bd742ae77c65b467dc2e16317be1cd4bc8083fd323d535d7fe62b9ee90aa2ad31a1e63a49b548c4d91c97807d5851"} build_url:https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010995 build_url: 'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0737630a979c108b0a88d907964818d024e4c5c540983863e4112b1773677be595b9dfd6128d1e1e7d6a1d69eee3a2b129a1fee5a624b9e3041fab864bd15862", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e45307006c124cab2aad0db7eb5d1ad9b3939c60ea894813b0d65c7dfdda1e171a888f7b6530fd3ec581d8e5ed8cc7cd8743aedd85f58d0d41a6d05f9ba2f52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8656910221f21a1710262688daad35d61c307d594f2b1dcf9eda2027b351b44798bb0b3d1b4dc8711e3956797a7e3f9ac35333dfcad46fe9cc5fe0826997b2c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06719b88a135201e3a1791fbbf3d7eb7c45bd742ae77c65b467dc2e16317be1cd4bc8083fd323d535d7fe62b9ee90aa2ad31a1e63a49b548c4d91c97807d5851"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 06:45:33.128671) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:45:33.129013) ========= 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.o5c2Qzy4Xb/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.aqZSv29SKj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.005097 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:45:33.176955) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 06:45:33.177252) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 06:45:33.177565) ========= ========= Total master_lag: 1.10 =========