builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0282 starttime: 1491729449.44 results: success (0) buildid: 20170409004009 builduid: b755bd1b29f249fd85317fc24a6002d0 revision: 20c110248317f6f6592d004cbb5f71fa1731639b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-09 02:17:29.443327) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-09 02:17:29.443759) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-09 02:17:29.444028) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-09 02:17:29.470876) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-09 02:17:29.471207) ========= 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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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-04-09 02:17:29-- 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% 18.9M=0.001s 2017-04-09 02:17:30 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.688696 ========= master_lag: 0.65 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-09 02:17:30.810117) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 02:17:30.810426) ========= 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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.035334 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 02:17:30.868576) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-09 02:17:30.868930) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 20c110248317f6f6592d004cbb5f71fa1731639b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 20c110248317f6f6592d004cbb5f71fa1731639b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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-04-09 02:17:31,156 truncating revision to first 12 chars 2017-04-09 02:17:31,156 Setting DEBUG logging. 2017-04-09 02:17:31,156 attempt 1/10 2017-04-09 02:17:31,156 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/20c110248317?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-09 02:17:32,325 unpacking tar archive at: mozilla-aurora-20c110248317/testing/mozharness/ program finished with exit code 0 elapsedTime=1.435271 ========= master_lag: 0.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-09 02:17:32.494623) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-09 02:17:32.494926) ========= script_repo_revision: 20c110248317f6f6592d004cbb5f71fa1731639b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-09 02:17:32.495309) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-09 02:17:32.495584) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-09 02:17:32.525122) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 41 secs) (at 2017-04-09 02:17:32.525432) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:17:32 INFO - MultiFileLogger online at 20170409 02:17:32 in /builds/slave/test 02:17:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:17:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:17:32 INFO - {'append_to_log': False, 02:17:32 INFO - 'base_work_dir': '/builds/slave/test', 02:17:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:17:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:17:32 INFO - 'branch': 'Mozilla-Aurora', 02:17:32 INFO - 'buildbot_json_path': 'buildprops.json', 02:17:32 INFO - 'config_files': ('talos/mac_config.py',), 02:17:32 INFO - 'default_actions': ('clobber', 02:17:32 INFO - 'read-buildbot-config', 02:17:32 INFO - 'download-and-extract', 02:17:32 INFO - 'populate-webroot', 02:17:32 INFO - 'create-virtualenv', 02:17:32 INFO - 'install', 02:17:32 INFO - 'run-tests'), 02:17:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:17:32 INFO - 'download_minidump_stackwalk': True, 02:17:32 INFO - 'download_symbols': 'ondemand', 02:17:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:17:32 INFO - 'tooltool.py': '/tools/tooltool.py', 02:17:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:17:32 INFO - '/tools/misc-python/virtualenv.py')}, 02:17:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:17:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:17:32 INFO - 'gecko_profile': False, 02:17:32 INFO - 'gecko_profile_interval': 0, 02:17:32 INFO - 'installer_path': 'installer.exe', 02:17:32 INFO - 'log_level': 'info', 02:17:32 INFO - 'log_name': 'talos', 02:17:32 INFO - 'log_to_console': True, 02:17:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:17:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:17:32 INFO - 'opt_config_files': (), 02:17:32 INFO - 'pip_index': False, 02:17:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:17:32 INFO - 'cmd': ('bash', 02:17:32 INFO - '-c', 02:17:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:17:32 INFO - 'enabled': True, 02:17:32 INFO - 'halt_on_failure': False, 02:17:32 INFO - 'name': 'check_screen_resolution'},), 02:17:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:17:32 INFO - 'cmd': ('bash', 02:17:32 INFO - '-c', 02:17:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:17:32 INFO - 'enabled': True, 02:17:32 INFO - 'halt_on_failure': False, 02:17:32 INFO - 'name': 'check_screen_resolution'},), 02:17:32 INFO - 'run_cmd_checks_enabled': True, 02:17:32 INFO - 'suite': 'dromaeojs', 02:17:32 INFO - 'system_bits': '32', 02:17:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:17:32 INFO - 'title': 't-yosemite-r7-0282', 02:17:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:17:32 INFO - 'use_talos_json': True, 02:17:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:17:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:17:32 INFO - 'work_dir': 'build'} 02:17:32 INFO - [mozharness: 2017-04-09 09:17:32.919798Z] Running clobber step. 02:17:32 INFO - Running pre-action listener: _resource_record_pre_action 02:17:32 INFO - Running main action method: clobber 02:17:32 INFO - rmtree: /builds/slave/test/build 02:17:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:17:35 INFO - Running post-action listener: _resource_record_post_action 02:17:35 INFO - [mozharness: 2017-04-09 09:17:35.443742Z] Finished clobber step (success) 02:17:35 INFO - [mozharness: 2017-04-09 09:17:35.443865Z] Running read-buildbot-config step. 02:17:35 INFO - Running pre-action listener: _resource_record_pre_action 02:17:35 INFO - Running main action method: read_buildbot_config 02:17:35 INFO - Using buildbot properties: 02:17:35 INFO - { 02:17:35 INFO - "project": "", 02:17:35 INFO - "product": "firefox", 02:17:35 INFO - "script_repo_revision": "production", 02:17:35 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:17:35 INFO - "repository": "", 02:17:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:17:35 INFO - "buildid": "20170409004009", 02:17:35 INFO - "stage_platform": "macosx64", 02:17:35 INFO - "basedir": "/builds/slave/test", 02:17:35 INFO - "nightly_build": "True", 02:17:35 INFO - "buildnumber": 24, 02:17:35 INFO - "slavename": "t-yosemite-r7-0282", 02:17:35 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:17:35 INFO - "platform": "yosemite_r7", 02:17:35 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:17:35 INFO - "branch": "mozilla-aurora", 02:17:35 INFO - "repo_path": "releases/mozilla-aurora", 02:17:35 INFO - "revision": "20c110248317f6f6592d004cbb5f71fa1731639b", 02:17:35 INFO - "pgo_build": "False", 02:17:35 INFO - "builduid": "b755bd1b29f249fd85317fc24a6002d0", 02:17:35 INFO - "slavebuilddir": "test" 02:17:35 INFO - } 02:17:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:17:35 INFO - Running post-action listener: _resource_record_post_action 02:17:35 INFO - [mozharness: 2017-04-09 09:17:35.447389Z] Finished read-buildbot-config step (success) 02:17:35 INFO - [mozharness: 2017-04-09 09:17:35.447482Z] Running download-and-extract step. 02:17:35 INFO - Running pre-action listener: _resource_record_pre_action 02:17:35 INFO - Running main action method: download_and_extract 02:17:35 INFO - mkdir: /builds/slave/test/build/tests 02:17:35 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')]} 02:17:35 INFO - trying https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:17:35 INFO - Downloading https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:17:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:17:36 INFO - Downloaded 1199 bytes. 02:17:36 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:17:36 INFO - Using the following test package requirements: 02:17:36 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:17:36 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:17:36 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:17:36 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:17:36 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:17:36 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:17:36 INFO - u'jsshell-mac.zip'], 02:17:36 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:17:36 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:17:36 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:17:36 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:17:36 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:17:36 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:17:36 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:17:36 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:17:36 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:17:36 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:17:36 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:17:36 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:17:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:17:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:17:36 INFO - Fetch https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:17:38 INFO - Expected file size: 51790276 02:17:38 INFO - Obtained file size: 51790276 02:17:53 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:17:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:17:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:17:53 INFO - Fetch https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:18:14 INFO - Expected file size: 51790276 02:18:14 INFO - Obtained file size: 51790276 02:18:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:18:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:18:29 INFO - Fetch https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:18:30 INFO - Expected file size: 14404424 02:18:30 INFO - Obtained file size: 14404424 02:18:31 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')]} 02:18:31 INFO - trying https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:18:31 INFO - Downloading https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:18:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:18:33 INFO - Downloaded 74729974 bytes. 02:18:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:18:33 INFO - mkdir: /builds/slave/test/properties 02:18:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:18:33 INFO - Writing to file /builds/slave/test/properties/build_url 02:18:33 INFO - Contents: 02:18:33 INFO - build_url:https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:18:34 INFO - Running post-action listener: _resource_record_post_action 02:18:34 INFO - Running post-action listener: set_extra_try_arguments 02:18:34 INFO - [mozharness: 2017-04-09 09:18:34.421161Z] Finished download-and-extract step (success) 02:18:34 INFO - [mozharness: 2017-04-09 09:18:34.421302Z] Running populate-webroot step. 02:18:34 INFO - Running pre-action listener: _resource_record_pre_action 02:18:34 INFO - Running main action method: populate_webroot 02:18:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:18:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:18:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:18:34 INFO - '--tppagecycles', 02:18:34 INFO - '7'], 02:18:34 INFO - 'tests': ['tsvgm']}}, 02:18:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:18:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:18:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:18:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:18:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:18:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:18:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:18:34 INFO - 'talos_options': ['--disable-e10s'], 02:18:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:18:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:18:34 INFO - 'talos_options': ['--disable-e10s'], 02:18:34 INFO - 'tests': ['damp', 'tps']}, 02:18:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:18:34 INFO - 'tests': ['damp', 'tps']}, 02:18:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:18:34 INFO - 'tests': ['dromaeo_dom']}, 02:18:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:18:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:18:34 INFO - 'tests': ['basic_compositor_video']}, 02:18:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:18:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:18:34 INFO - 'tests': ['a11yr', 02:18:34 INFO - 'ts_paint', 02:18:34 INFO - 'tpaint', 02:18:34 INFO - 'sessionrestore', 02:18:34 INFO - 'sessionrestore_no_auto_restore', 02:18:34 INFO - 'tabpaint']}, 02:18:34 INFO - 'other-e10s': {'tests': ['a11yr', 02:18:34 INFO - 'ts_paint', 02:18:34 INFO - 'tpaint', 02:18:34 INFO - 'sessionrestore', 02:18:34 INFO - 'sessionrestore_no_auto_restore', 02:18:34 INFO - 'tabpaint']}, 02:18:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:18:34 INFO - 'ts_paint', 02:18:34 INFO - 'tpaint', 02:18:34 INFO - 'sessionrestore', 02:18:34 INFO - 'sessionrestore_no_auto_restore', 02:18:34 INFO - 'tabpaint']}, 02:18:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:18:34 INFO - 'ts_paint', 02:18:34 INFO - 'tpaint', 02:18:34 INFO - 'sessionrestore', 02:18:34 INFO - 'sessionrestore_no_auto_restore', 02:18:34 INFO - 'tabpaint']}, 02:18:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:18:34 INFO - 'tests': ['a11yr', 02:18:34 INFO - 'ts_paint', 02:18:34 INFO - 'tpaint', 02:18:34 INFO - 'sessionrestore', 02:18:34 INFO - 'sessionrestore_no_auto_restore', 02:18:34 INFO - 'tabpaint']}, 02:18:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:18:34 INFO - 'tests': ['a11yr', 02:18:34 INFO - 'ts_paint', 02:18:34 INFO - 'tpaint', 02:18:34 INFO - 'sessionrestore', 02:18:34 INFO - 'sessionrestore_no_auto_restore', 02:18:34 INFO - 'tabpaint']}, 02:18:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:18:34 INFO - 'tests': ['tsvgx', 02:18:34 INFO - 'tsvgr_opacity', 02:18:34 INFO - 'tart', 02:18:34 INFO - 'tscrollx', 02:18:34 INFO - 'cart', 02:18:34 INFO - 'tsvg_static']}, 02:18:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:18:34 INFO - 'tsvgr_opacity', 02:18:34 INFO - 'tart', 02:18:34 INFO - 'tscrollx', 02:18:34 INFO - 'cart', 02:18:34 INFO - 'tsvg_static']}, 02:18:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:18:34 INFO - 'talos_options': ['--disable-e10s'], 02:18:34 INFO - 'tests': ['tp5o']}, 02:18:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:18:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:18:34 INFO - 'talos_options': ['--disable-e10s', 02:18:34 INFO - '--xperf_path', 02:18:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:34 INFO - 'tests': ['tp5n']}, 02:18:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:18:34 INFO - 'talos_options': ['--xperf_path', 02:18:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:34 INFO - 'tests': ['tp5n']}}, 02:18:34 INFO - 'talos.zip': {'path': '', 02:18:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:18:34 INFO - Running post-action listener: _resource_record_post_action 02:18:34 INFO - [mozharness: 2017-04-09 09:18:34.439797Z] Finished populate-webroot step (success) 02:18:34 INFO - [mozharness: 2017-04-09 09:18:34.439900Z] Running create-virtualenv step. 02:18:34 INFO - Running pre-action listener: _resource_record_pre_action 02:18:34 INFO - Running main action method: create_virtualenv 02:18:34 INFO - Creating virtualenv /builds/slave/test/build/venv 02:18:34 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 02:18:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:18:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:18:34 INFO - Using real prefix '/tools/python27' 02:18:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:18:35 INFO - Installing distribute.............................................................................................................................................................................................done. 02:18:38 INFO - Installing pip.................done. 02:18:38 INFO - Return code: 0 02:18:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:18:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:18:38 INFO - Reading from file tmpfile_stdout 02:18:38 INFO - Output received: 02:18:38 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) 02:18:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1079c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb89570990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a0d30>, '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 0x1076c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107795be8>, '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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:18:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:18:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:18:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aJ8PnfxsPi/Render', 02:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:38 INFO - 'HOME': '/Users/cltbld', 02:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:38 INFO - 'LOGNAME': 'cltbld', 02:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:38 INFO - 'MOZ_NO_REMOTE': '1', 02:18:38 INFO - 'NO_EM_RESTART': '1', 02:18:38 INFO - 'PAGER': '/bin/cat', 02:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:38 INFO - 'PWD': '/builds/slave/test', 02:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:38 INFO - 'SHELL': '/bin/bash', 02:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KnhxwiIwkj/Listeners', 02:18:38 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:38 INFO - 'USER': 'cltbld', 02:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:38 INFO - 'XPC_FLAGS': '0x0', 02:18:38 INFO - 'XPC_SERVICE_NAME': '0', 02:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:38 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 02:18:38 INFO - Cleaning up... 02:18:38 INFO - Return code: 0 02:18:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1079c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb89570990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a0d30>, '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 0x1076c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107795be8>, '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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:18:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:18:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:18:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aJ8PnfxsPi/Render', 02:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:38 INFO - 'HOME': '/Users/cltbld', 02:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:38 INFO - 'LOGNAME': 'cltbld', 02:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:38 INFO - 'MOZ_NO_REMOTE': '1', 02:18:38 INFO - 'NO_EM_RESTART': '1', 02:18:38 INFO - 'PAGER': '/bin/cat', 02:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:38 INFO - 'PWD': '/builds/slave/test', 02:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:38 INFO - 'SHELL': '/bin/bash', 02:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KnhxwiIwkj/Listeners', 02:18:38 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:38 INFO - 'USER': 'cltbld', 02:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:38 INFO - 'XPC_FLAGS': '0x0', 02:18:38 INFO - 'XPC_SERVICE_NAME': '0', 02:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:38 INFO - Downloading/unpacking psutil>=3.1.1 02:18:38 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 02:18:38 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 02:18:38 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 02:18:38 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 02:18:38 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 02:18:38 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 02:18:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:18:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:18:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:18:42 INFO - Installing collected packages: psutil 02:18:42 INFO - Running setup.py install for psutil 02:18:42 INFO - building 'psutil._psutil_osx' extension 02:18:42 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 02:18:43 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 02:18:43 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 02:18:43 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 02:18:43 INFO - building 'psutil._psutil_posix' extension 02:18:43 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 02:18:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:18:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:18:43 INFO - ^ 02:18:43 INFO - 1 warning generated. 02:18:43 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 02:18:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:18:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:18:43 INFO - Successfully installed psutil 02:18:43 INFO - Cleaning up... 02:18:44 INFO - Return code: 0 02:18:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1079c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb89570990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a0d30>, '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 0x1076c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107795be8>, '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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:18:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:18:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aJ8PnfxsPi/Render', 02:18:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:44 INFO - 'HOME': '/Users/cltbld', 02:18:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:44 INFO - 'LOGNAME': 'cltbld', 02:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:44 INFO - 'MOZ_NO_REMOTE': '1', 02:18:44 INFO - 'NO_EM_RESTART': '1', 02:18:44 INFO - 'PAGER': '/bin/cat', 02:18:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:44 INFO - 'PWD': '/builds/slave/test', 02:18:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:44 INFO - 'SHELL': '/bin/bash', 02:18:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KnhxwiIwkj/Listeners', 02:18:44 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:18:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:44 INFO - 'USER': 'cltbld', 02:18:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:44 INFO - 'XPC_FLAGS': '0x0', 02:18:44 INFO - 'XPC_SERVICE_NAME': '0', 02:18:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:18:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:18:47 INFO - Installing collected packages: mozsystemmonitor 02:18:47 INFO - Running setup.py install for mozsystemmonitor 02:18:47 INFO - Successfully installed mozsystemmonitor 02:18:47 INFO - Cleaning up... 02:18:47 INFO - Return code: 0 02:18:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1079c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb89570990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a0d30>, '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 0x1076c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107795be8>, '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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:18:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aJ8PnfxsPi/Render', 02:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:47 INFO - 'HOME': '/Users/cltbld', 02:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:47 INFO - 'LOGNAME': 'cltbld', 02:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:47 INFO - 'MOZ_NO_REMOTE': '1', 02:18:47 INFO - 'NO_EM_RESTART': '1', 02:18:47 INFO - 'PAGER': '/bin/cat', 02:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:47 INFO - 'PWD': '/builds/slave/test', 02:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:47 INFO - 'SHELL': '/bin/bash', 02:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KnhxwiIwkj/Listeners', 02:18:47 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:47 INFO - 'USER': 'cltbld', 02:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:47 INFO - 'XPC_FLAGS': '0x0', 02:18:47 INFO - 'XPC_SERVICE_NAME': '0', 02:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:47 INFO - Downloading/unpacking jsonschema==2.5.1 02:18:47 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 02:18:47 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 02:18:47 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 02:18:47 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 02:18:47 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 02:18:47 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 02:18:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:18:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:18:50 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 02:18:50 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 02:18:50 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 02:18:50 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 02:18:50 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 02:18:50 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 02:18:50 INFO - Downloading functools32-3.2.3-2.tar.gz 02:18:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:18:50 INFO - warning: no files found matching '*.txt' 02:18:50 INFO - no previously-included directories found matching 'build' 02:18:50 INFO - no previously-included directories found matching 'dist' 02:18:50 INFO - no previously-included directories found matching '.git*' 02:18:50 INFO - Installing collected packages: jsonschema, functools32 02:18:50 INFO - Running setup.py install for functools32 02:18:50 INFO - warning: no files found matching '*.txt' 02:18:50 INFO - no previously-included directories found matching 'build' 02:18:50 INFO - no previously-included directories found matching 'dist' 02:18:50 INFO - no previously-included directories found matching '.git*' 02:18:50 INFO - Successfully installed jsonschema functools32 02:18:50 INFO - Cleaning up... 02:18:51 INFO - Return code: 0 02:18:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:18:51 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')]} 02:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:51 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')]} 02:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:51 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 0x1079c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb89570990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a0d30>, '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 0x1076c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107795be8>, '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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:18:51 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 02:18:51 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 02:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aJ8PnfxsPi/Render', 02:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:51 INFO - 'HOME': '/Users/cltbld', 02:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:51 INFO - 'LOGNAME': 'cltbld', 02:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:51 INFO - 'MOZ_NO_REMOTE': '1', 02:18:51 INFO - 'NO_EM_RESTART': '1', 02:18:51 INFO - 'PAGER': '/bin/cat', 02:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:51 INFO - 'PWD': '/builds/slave/test', 02:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:51 INFO - 'SHELL': '/bin/bash', 02:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KnhxwiIwkj/Listeners', 02:18:51 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:51 INFO - 'USER': 'cltbld', 02:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:51 INFO - 'XPC_FLAGS': '0x0', 02:18:51 INFO - 'XPC_SERVICE_NAME': '0', 02:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:18:51 INFO - Cleaning up... 02:18:51 INFO - Return code: 0 02:18:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:18:51 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')]} 02:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:51 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')]} 02:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:51 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 0x1079c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb89570990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a0d30>, '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 0x1076c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107795be8>, '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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:18:51 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 02:18:51 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 02:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aJ8PnfxsPi/Render', 02:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:51 INFO - 'HOME': '/Users/cltbld', 02:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:51 INFO - 'LOGNAME': 'cltbld', 02:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:51 INFO - 'MOZ_NO_REMOTE': '1', 02:18:51 INFO - 'NO_EM_RESTART': '1', 02:18:51 INFO - 'PAGER': '/bin/cat', 02:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:51 INFO - 'PWD': '/builds/slave/test', 02:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:51 INFO - 'SHELL': '/bin/bash', 02:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KnhxwiIwkj/Listeners', 02:18:51 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:51 INFO - 'USER': 'cltbld', 02:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:51 INFO - 'XPC_FLAGS': '0x0', 02:18:51 INFO - 'XPC_SERVICE_NAME': '0', 02:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:51 INFO - Downloading/unpacking blobuploader==1.2.4 02:18:51 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 02:18:51 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 02:18:51 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 02:18:51 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 02:18:51 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 02:18:51 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 02:18:54 INFO - Downloading blobuploader-1.2.4.tar.gz 02:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:18:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:18:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:55 INFO - Downloading docopt-0.6.1.tar.gz 02:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:18:55 INFO - Installing collected packages: blobuploader, requests, docopt 02:18:55 INFO - Running setup.py install for blobuploader 02:18:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:18:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:18:55 INFO - Running setup.py install for requests 02:18:56 INFO - Running setup.py install for docopt 02:18:56 INFO - Successfully installed blobuploader requests docopt 02:18:56 INFO - Cleaning up... 02:18:56 INFO - Return code: 0 02:18:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:56 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 0x1079c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb89570990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a0d30>, '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 0x1076c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107795be8>, '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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:18:56 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 02:18:56 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 02:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aJ8PnfxsPi/Render', 02:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:56 INFO - 'HOME': '/Users/cltbld', 02:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:56 INFO - 'LOGNAME': 'cltbld', 02:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:56 INFO - 'MOZ_NO_REMOTE': '1', 02:18:56 INFO - 'NO_EM_RESTART': '1', 02:18:56 INFO - 'PAGER': '/bin/cat', 02:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:56 INFO - 'PWD': '/builds/slave/test', 02:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:56 INFO - 'SHELL': '/bin/bash', 02:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KnhxwiIwkj/Listeners', 02:18:56 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:56 INFO - 'USER': 'cltbld', 02:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:56 INFO - 'XPC_FLAGS': '0x0', 02:18:56 INFO - 'XPC_SERVICE_NAME': '0', 02:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:18:56 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-0sKlGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:18:56 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-xVnMYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:18:56 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-F7ivOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:18:56 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-3bid8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:18:56 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-qYzR3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:18:57 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-rIzdzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:18:57 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-DxSYER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:18:57 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-VxBVIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:18:57 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-v6tGYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:18:57 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-75Njnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:18:57 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-2884HH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:18:57 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-EgzoDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:18:57 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-CBsjR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:18:57 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-54xvvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:58 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-EfFShG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:18:58 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-dhLzhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:18:58 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-dKDBUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:18:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:18:58 INFO - Running setup.py install for manifestparser 02:18:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:18:58 INFO - Running setup.py install for mozcrash 02:18:58 INFO - Running setup.py install for mozdebug 02:18:58 INFO - Running setup.py install for mozdevice 02:18:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:18:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:18:58 INFO - Running setup.py install for mozfile 02:18:59 INFO - Running setup.py install for mozhttpd 02:18:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:18:59 INFO - Running setup.py install for mozinfo 02:18:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:18:59 INFO - Running setup.py install for mozInstall 02:18:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:18:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:18:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:18:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:18:59 INFO - Running setup.py install for mozleak 02:18:59 INFO - Running setup.py install for mozlog 02:18:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:18:59 INFO - Running setup.py install for moznetwork 02:18:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:18:59 INFO - Running setup.py install for mozprocess 02:19:00 INFO - Running setup.py install for mozprofile 02:19:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:19:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:19:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:19:00 INFO - Running setup.py install for mozrunner 02:19:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:19:00 INFO - Running setup.py install for mozscreenshot 02:19:00 INFO - Running setup.py install for moztest 02:19:00 INFO - Running setup.py install for mozversion 02:19:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:19:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:19:00 INFO - Cleaning up... 02:19:00 INFO - Return code: 0 02:19:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:19: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')]} 02:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19: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')]} 02:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:00 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 0x1079c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb89570990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a0d30>, '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 0x1076c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107795be8>, '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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:19:00 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 02:19:00 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 02:19:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aJ8PnfxsPi/Render', 02:19:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:00 INFO - 'HOME': '/Users/cltbld', 02:19:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:00 INFO - 'LOGNAME': 'cltbld', 02:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:00 INFO - 'MOZ_NO_REMOTE': '1', 02:19:00 INFO - 'NO_EM_RESTART': '1', 02:19:00 INFO - 'PAGER': '/bin/cat', 02:19:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:00 INFO - 'PWD': '/builds/slave/test', 02:19:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:00 INFO - 'SHELL': '/bin/bash', 02:19:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KnhxwiIwkj/Listeners', 02:19:00 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:19:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:00 INFO - 'USER': 'cltbld', 02:19:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:00 INFO - 'XPC_FLAGS': '0x0', 02:19:00 INFO - 'XPC_SERVICE_NAME': '0', 02:19:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:19:01 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-WcwwIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:19:01 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)) 02:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:19:01 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-gbG8lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:19:01 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)) 02:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:19:01 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-YvmxAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:19:01 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)) 02:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:19:01 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-2ND8w9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:19:01 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)) 02:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:19:01 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-4bdWgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:19:01 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)) 02:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:19:01 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-vbFhFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:19:01 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)) 02:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:19:01 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-RkhyVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:19:01 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)) 02:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:19:01 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-LzNlm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:19:02 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)) 02:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:19:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-X7r1Ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:19:02 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)) 02:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:19:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-qIFrXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:19:02 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)) 02:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:19:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Ulaz3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:19:02 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)) 02:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:19:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-aAe4qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 02:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:19:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-u1Txax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:19:02 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)) 02:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:19:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-muzD8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:19:02 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)) 02:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-oUvqZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:02 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)) 02:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:19:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-0CQ0TD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:19:02 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)) 02:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:19:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-82y5qU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:19:02 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)) 02:19:02 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)) 02:19:02 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)) 02:19:02 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)) 02:19:02 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)) 02:19:02 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)) 02:19:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:19:02 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 02:19:02 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 02:19:02 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 02:19:02 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 02:19:02 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 02:19:02 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 02:19:05 INFO - Downloading blessings-1.6.tar.gz 02:19:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:19:05 INFO - Installing collected packages: blessings 02:19:05 INFO - Running setup.py install for blessings 02:19:05 INFO - Successfully installed blessings 02:19:05 INFO - Cleaning up... 02:19:06 INFO - Return code: 0 02:19:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:19:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:19:06 INFO - Reading from file tmpfile_stdout 02:19:06 INFO - Current package versions: 02:19:06 INFO - blessings == 1.6 02:19:06 INFO - blobuploader == 1.2.4 02:19:06 INFO - docopt == 0.6.1 02:19:06 INFO - functools32 == 3.2.3-2 02:19:06 INFO - jsonschema == 2.5.1 02:19:06 INFO - manifestparser == 1.1 02:19:06 INFO - mozInstall == 1.12 02:19:06 INFO - mozcrash == 1.0 02:19:06 INFO - mozdebug == 0.1 02:19:06 INFO - mozdevice == 0.49 02:19:06 INFO - mozfile == 1.2 02:19:06 INFO - mozhttpd == 0.7 02:19:06 INFO - mozinfo == 0.9 02:19:06 INFO - mozleak == 0.1 02:19:06 INFO - mozlog == 3.4 02:19:06 INFO - moznetwork == 0.27 02:19:06 INFO - mozprocess == 0.25 02:19:06 INFO - mozprofile == 0.28 02:19:06 INFO - mozrunner == 6.13 02:19:06 INFO - mozscreenshot == 0.1 02:19:06 INFO - mozsystemmonitor == 0.3 02:19:06 INFO - moztest == 0.8 02:19:06 INFO - mozversion == 1.4 02:19:06 INFO - psutil == 3.1.1 02:19:06 INFO - requests == 1.2.3 02:19:06 INFO - wsgiref == 0.1.2 02:19:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:06 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 0x1079c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb89570990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a0d30>, '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 0x1076c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107795be8>, '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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:19:06 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 02:19:06 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 02:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aJ8PnfxsPi/Render', 02:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:06 INFO - 'HOME': '/Users/cltbld', 02:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:06 INFO - 'LOGNAME': 'cltbld', 02:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:06 INFO - 'MOZ_NO_REMOTE': '1', 02:19:06 INFO - 'NO_EM_RESTART': '1', 02:19:06 INFO - 'PAGER': '/bin/cat', 02:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:06 INFO - 'PWD': '/builds/slave/test', 02:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:06 INFO - 'SHELL': '/bin/bash', 02:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KnhxwiIwkj/Listeners', 02:19:06 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:06 INFO - 'USER': 'cltbld', 02:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:06 INFO - 'XPC_FLAGS': '0x0', 02:19:06 INFO - 'XPC_SERVICE_NAME': '0', 02:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:06 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)) 02:19:06 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)) 02:19:06 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)) 02:19:06 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)) 02:19:06 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)) 02:19:06 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)) 02:19:06 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)) 02:19:06 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)) 02:19:06 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)) 02:19:06 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)) 02:19:06 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)) 02:19:06 INFO - Cleaning up... 02:19:06 INFO - Return code: 0 02:19:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1079c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb89570990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a0d30>, '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 0x1076c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107795be8>, '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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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 02:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aJ8PnfxsPi/Render', 02:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:06 INFO - 'HOME': '/Users/cltbld', 02:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:06 INFO - 'LOGNAME': 'cltbld', 02:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:06 INFO - 'MOZ_NO_REMOTE': '1', 02:19:06 INFO - 'NO_EM_RESTART': '1', 02:19:06 INFO - 'PAGER': '/bin/cat', 02:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:06 INFO - 'PWD': '/builds/slave/test', 02:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:06 INFO - 'SHELL': '/bin/bash', 02:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KnhxwiIwkj/Listeners', 02:19:06 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:06 INFO - 'USER': 'cltbld', 02:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:06 INFO - 'XPC_FLAGS': '0x0', 02:19:06 INFO - 'XPC_SERVICE_NAME': '0', 02:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:19:06 INFO - Cleaning up... 02:19:06 INFO - Return code: 0 02:19:06 INFO - Running post-action listener: _resource_record_post_action 02:19:06 INFO - Running post-action listener: _start_resource_monitoring 02:19:06 INFO - Starting resource monitoring. 02:19:06 INFO - [mozharness: 2017-04-09 09:19:06.933927Z] Finished create-virtualenv step (success) 02:19:06 INFO - [mozharness: 2017-04-09 09:19:06.934868Z] Running install step. 02:19:06 INFO - Running pre-action listener: _resource_record_pre_action 02:19:06 INFO - Running main action method: install 02:19:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:19:07 INFO - Reading from file tmpfile_stdout 02:19:07 INFO - Detecting whether we're running mozinstall >=1.0... 02:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:19:07 INFO - Reading from file tmpfile_stdout 02:19:07 INFO - Output received: 02:19:07 INFO - Usage: mozinstall [options] installer 02:19:07 INFO - Options: 02:19:07 INFO - -h, --help show this help message and exit 02:19:07 INFO - -d DEST, --destination=DEST 02:19:07 INFO - Directory to install application into. [default: 02:19:07 INFO - "/builds/slave/test"] 02:19:07 INFO - --app=APP Application being installed. [default: firefox] 02:19:07 INFO - mkdir: /builds/slave/test/build/application 02:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:19:27 INFO - Reading from file tmpfile_stdout 02:19:27 INFO - Output received: 02:19:27 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:19:27 INFO - Running post-action listener: _resource_record_post_action 02:19:27 INFO - [mozharness: 2017-04-09 09:19:27.445656Z] Finished install step (success) 02:19:27 INFO - [mozharness: 2017-04-09 09:19:27.445796Z] Running run-tests step. 02:19:27 INFO - Running pre-action listener: _resource_record_pre_action 02:19:27 INFO - Running main action method: run_tests 02:19:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:19:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:19:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:19:27 INFO - 2017-04-09 02:19:27.460 screenresolution[1632:7820] starting screenresolution argv=screenresolution get 02:19:27 INFO - 2017-04-09 02:19:27.463 screenresolution[1632:7820] Display 0: 1600x1200x32@60 02:19:27 INFO - 2017-04-09 02:19:27.468 screenresolution[1633:7823] starting screenresolution argv=screenresolution list 02:19:27 INFO - Available Modes on Display 0 02:19:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:19:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:19:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:19:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:19:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:19:27 INFO - Intel Iris: 02:19:27 INFO - Chipset Model: Intel Iris 02:19:27 INFO - Type: GPU 02:19:27 INFO - Bus: Built-In 02:19:27 INFO - VRAM (Dynamic, Max): 1536 MB 02:19:27 INFO - Vendor: Intel (0x8086) 02:19:27 INFO - Device ID: 0x0a2e 02:19:27 INFO - Revision ID: 0x0009 02:19:27 INFO - Displays: 02:19:27 INFO - Display: 02:19:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:19:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:19:27 INFO - Display Serial Number: 1600x1200 60 02:19:27 INFO - Main Display: Yes 02:19:27 INFO - Mirror: Off 02:19:27 INFO - Online: Yes 02:19:27 INFO - Rotation: Supported 02:19:27 INFO - Return code: 0 02:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:19:27 INFO - Python 2.7.3 02:19:27 INFO - Return code: 0 02:19:27 INFO - grabbing minidump binary from tooltool 02:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:27 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 0x1077a0d30>, '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 0x1076c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107795be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:19:27 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 02:19:27 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 02:19:27 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 02:19:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:19:27 INFO - Return code: 0 02:19:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:19:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:19:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:19:27 INFO - ENV: RUST_BACKTRACE is now 1 02:19:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:19:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--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 02:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0282 --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 02:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aJ8PnfxsPi/Render', 02:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:27 INFO - 'HOME': '/Users/cltbld', 02:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:27 INFO - 'LOGNAME': 'cltbld', 02:19:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:19:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:27 INFO - 'MOZ_NO_REMOTE': '1', 02:19:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:19:27 INFO - 'NO_EM_RESTART': '1', 02:19:27 INFO - 'PAGER': '/bin/cat', 02:19:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:27 INFO - 'PWD': '/builds/slave/test', 02:19:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:27 INFO - 'RUST_BACKTRACE': '1', 02:19:27 INFO - 'SHELL': '/bin/bash', 02:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KnhxwiIwkj/Listeners', 02:19:27 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 02:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:27 INFO - 'USER': 'cltbld', 02:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:27 INFO - 'XPC_FLAGS': '0x0', 02:19:27 INFO - 'XPC_SERVICE_NAME': '0', 02:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--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 02:19:28 INFO - mozversion application_buildid: 20170409004009 02:19:28 INFO - mozversion application_changeset: 20c110248317f6f6592d004cbb5f71fa1731639b 02:19:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:19:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:19:28 INFO - mozversion application_name: Firefox 02:19:28 INFO - mozversion application_remotingname: firefox-dev 02:19:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:19:28 INFO - mozversion application_vendor: Mozilla 02:19:28 INFO - mozversion application_version: 54.0a2 02:19:28 INFO - mozversion platform_buildid: 20170409004009 02:19:28 INFO - mozversion platform_changeset: 20c110248317f6f6592d004cbb5f71fa1731639b 02:19:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:19:28 INFO - mozversion platform_version: 54.0a2 02:19:28 INFO - using testdate: 1491729568 02:19:28 INFO - actual date: 1491729568 02:19:28 INFO - starting webserver on 'localhost:49239' 02:19:28 INFO - SUITE-START | Running 2 tests 02:19:28 INFO - TEST-START | dromaeo_css 02:19:28 INFO - operating with platform_type : mac_ 02:19:28 INFO - Initialising browser for dromaeo_css test... 02:19:28 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpfGa9fo/profile http://localhost:49239/getInfo.html) 02:19:30 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 02:19:30 INFO - PROCESS | 1643 | colorDepth:24 02:19:30 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 02:19:30 INFO - PROCESS | 1643 | __metrics 02:19:30 INFO - PROCESS | 1643 | 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" 02:19:30 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:19:31 INFO - TEST-INFO | 1643: exit 0 02:19:31 INFO - Browser initialized. 02:19:31 INFO - Running cycle 1/1 for dromaeo_css test... 02:19:31 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpfGa9fo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:19:43 INFO - PROCESS | 1647 | RSS: Main: 210546688 02:19:43 INFO - PROCESS | 1647 | 02:22:48 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 02:22:49 INFO - PROCESS | 1647 | RSS: Main: 376508416 02:22:49 INFO - PROCESS | 1647 | 02:24:33 INFO - PROCESS | 1647 | ************************* 02:24:33 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 02:24:33 INFO - PROCESS | 1647 | 02:24:33 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:24:33 INFO - PROCESS | 1647 | Full stack: 02:24:33 INFO - PROCESS | 1647 | ************************* 02:24:33 INFO - PROCESS | 1647 | ************************* 02:24:33 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 02:24:33 INFO - PROCESS | 1647 | 02:24:33 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:24:33 INFO - PROCESS | 1647 | Full stack: 02:24:33 INFO - PROCESS | 1647 | ************************* 02:24:33 INFO - PROCESS | 1647 | ************************* 02:24:33 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 02:24:33 INFO - PROCESS | 1647 | 02:24:33 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:24:33 INFO - PROCESS | 1647 | Full stack: 02:24:33 INFO - PROCESS | 1647 | ************************* 02:24:33 INFO - PROCESS | 1647 | ************************* 02:24:33 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 02:24:33 INFO - PROCESS | 1647 | 02:24:33 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:24:33 INFO - PROCESS | 1647 | Full stack: 02:24:33 INFO - PROCESS | 1647 | ************************* 02:25:52 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 02:25:53 INFO - PROCESS | 1647 | RSS: Main: 349339648 02:25:53 INFO - PROCESS | 1647 | 02:28:55 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 02:28:56 INFO - PROCESS | 1647 | RSS: Main: 346451968 02:28:56 INFO - PROCESS | 1647 | 02:31:58 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 02:31:59 INFO - PROCESS | 1647 | RSS: Main: 316805120 02:31:59 INFO - PROCESS | 1647 | 02:35:03 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 02:35:03 INFO - PROCESS | 1647 | RSS: Main: 316096512 02:35:03 INFO - PROCESS | 1647 | 02:38:07 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 02:38:07 INFO - PROCESS | 1647 | RSS: Main: 381456384 02:38:07 INFO - PROCESS | 1647 | 02:38:07 INFO - PROCESS | 1647 | __start_tp_report 02:38:07 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 02:38:07 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 02:38:07 INFO - PROCESS | 1647 | |i|pagename|runs| 02:38:07 INFO - PROCESS | 1647 | |0;dojo.html;3417.4702921047246;3422.537957375754;3433.7939723616587;3453.412919803633;3483.2859263850914;6083.847903802407;6085.300190478097;6093.969530152349;6117.755289788403;6129.233845769279;6070.998285282934;6115.48018418435;6139.72371243294;6141.877162456759;6149.13912052313;5449.891002179954;5487;5513.641613295143;5539.556835453174;5613.326400831906;4530.275155975045;5322.574194064485;5403.405625381205;5600.887982240352;5644.717764111799;5464.371597266306;5497.340319161706;5503.752331145088;5503.972480137593;5505.394406615269;5478.369987451454;5481.588880833933;5493.285872836505;5501.312335958005;5504.532114770249;5330.493603107738;5452.59105567084;5456.017916774979;5456.590755693299;5485.424030476783;6064.787732429388;6078.331383547807;6078.696065196723;6080.787172448987;6096.69516524172;6338.87322253557;6349.777757778502;6354.618722876596;6370.585911915757;6378.362163783585;6325.240971083454;6334.873302533923;6397.872042559169;6409.102725618417;6410.583312084699;6073.787417440412;6123.387661233885;6129.20320358355;6141.4472697456995;6150.723217455181;6289.465395441347;6659.600423974577;6659.800205993828;6665.900011499832;6668.133142691516;6297.086922396318;6313.05304204373;6330.493560515148;6366.745330186741;6425.293217746044;6451.29035806061;6454.483641308684;6456.9354306456335;6461.418472337418;6470.54706170573;6241.3856132949895;6388.488920886318;6391.296957334689;6391.840203994844;6397.488200943913;6304.369563043659;6345.0482427636225;6362.745490180433;6399.104125422445;6403.135576697179;5172.482751724872;5212.739363031833;5224.16413373866;5233.476652334813;5272.92090618641;4568.063546972929;4578.244589642738;4581.770911454414;4586.403767510203;4627.444706635225;5930.55520835939;5979.641221526722;6063.6664983426335;6105.511559075263;6108.267007958984;6337.397947195009;6370.394812492806;6410.326915673881;6411.262704789009;6421.325760795143;6051.969740151271;6087.57386982907;6093.69531523422;6097.390260973867;6114.277451171044;5704.401037890962;5713.22871412354;5714.771409143671;5719.971400142973;5732.570057245724;6444.967775161094;6466.1270728452;6618.503612229199;6667.833304167435;6680.432163266177;6430.582012169194;6457.4188323049275;6472.093906853139;6474.546781725422;6490.837729056811;6648.933510665025;6714.127163468718;6732.427743641845;6746.190457145174;6749.156355455568;6271;6326.905096423649;6350.079238510482;6357.23713154406;6429.678516074271;6323.588966717149;6471.4822814175695;6478.578892371981;6484.092227088306;6512.446442052289;6351.237851457855;6358.1734374531015;6371.203599550056;6395.328490508429;6401.583897046863;6177.752889884534;6217.316095229431;6279.811605651837;6301.369863013845;6312.621242725268;6257.43683068526;6284.214473190851;6293.716782744696;6304.18045654062;6307.936920630735;6357.332480089524;6372.808815735535;6373.5857169283845;6382.266039405342;6406.903896441464;6018;6121.663308518075;6131.254333328352;6186.257649081959;6234.1583886174785 02:38:07 INFO - PROCESS | 1647 | |1;ext.html;732.4030914804434;766.7124828189429;788.1094363369392;844.3161039557958;856.4176360075146;27316.590251146223;39721.60278397215;40184.79907600465;40230;40240.79277621676;35135.121621959464;41679.374809377885;41685;41730.374044389355;42058.579414205924;7366.668499917517;9057.81884362314;9206.355555111146;9218.308626852979;9226.769330766718;7348.632568371587;10829;11010.669679909615;11022.44887755609;11024.614138505154;9201.907980920205;10995.945020274887;11024.007839294462;11026.889731102707;11049.226554141213;10954;10984.176186785942;11007.559697612085;11020;11028.117750579975;9345.439273643535;10623.150147988143;10730.570777168903;10784.622538211204;10798.298110622783;38940;39098.60901390978;39227.01932451683;39245.80377098125;39319;3768.176733780765;3836.635519625631;3847.826847791856;3872.7869967151796;3898.941515877264;27019.45961080787;28497.145085647462;28915.710842891513;29083.272918177005;29144;2537.2134638262196;2834.8440835754027;2846.1746093632823;2848.9145325640266;2872.7127287271314;364711;365452.9999999973;365612;365880;366398;52415.47584524106;52487.212691809655;52510.474895251326;52539.94920101653;52543.474565254626;47729;54715;54944;55020.44979550153;55072.17391739127;14555.78166327506;14582.270886455635;14596.416143354147;14617.853821461862;14672.339744711513;10941.507632156572;11112.944270294309;12690.80963785544;12699.428525716181;12706.237625742486;10300.639477618397;11596.652100437;11806;11834.644960651194;12055.457504412323;5629.971850140723;5637.943620563824;5670.801521946794;5686.175504551899;5695.943040569541;908.4276905549461;1065.2874731560505;1084.7450849050467;1115.8326251062242;1120.1207052463776;25385.873070634527;27476.763545640493;27556.72443275582;27692.86153569219;27798.861005694842;1153.423288355822;1159.1943599198644;1165.3299352872132;1209.5041033176356;1211.6970757310673;13938.581842544538;14022.85977140236;14412;14440.277986100653;14455.27723613815;443373;444636;445480;445809;446018;55510.72244638832;55675.44324556783;55706;55717.72141139268;55831.72084139553;1265.5570550307502;1431.198528823862;1480.4152359818027;1487.9851201488063;1488.047649504318;26141.346466338495;26386.604200937385;26869.328266793487;26961;27032.459350813268;41979.58020419757;42150.15699685928;42174.78912605417;42181.78909105435;42188.367174491796;413746;413817.9309103556;413971;414211;416021;33101.67593296241;33662.99011029673;33815;33820.83089584536;33865.66134338625;43284.567154329314;44069.77965110154;44094.559054410325;44225.33661995137;44226.778866105466;26450.603240951015;28537.28656783597;29323.853380733814;30172.396552069276;30412.3273219975;22166.66749998719;27858.721412786424;27971.440571188054;28268.43463130767;28468.85765571159;32531.674683252866;32907.848225312;33026.00921972345;33237.50143747894;33244.667553325125;27983.174453045598;28008.71991280061;28146.01488947877;28428.147155585364;29118;22984.195553156227;23058.192963246205;23503.88248058817;23518.88240558786;23524.176653817034 02:38:07 INFO - PROCESS | 1647 | |2;jquery.html;368.8561461030198;389.5500696695314;410.22467536356294;426.8356682677169;452.15447113897;12768.936155319221;14751.262436878167;20979.47551311216;21427.785722142773;21598.3520494385;22539;22639.660405093935;22962.88518557405;23184.188553400636;23486.177983770573;6503.252126005499;7490.400767938552;7826.608669566499;7922.9603851980655;7947.960260198691;7690.1540830508875;7932;7933.722319718811;8004.599770011505;8009.399295052865;7592.392608591299;7956.602169891511;8005.039395272575;8014;8020.077691065544;7540.849183016309;7932.246436588515;8014.198580141998;8151.510909345399;8156.306713929293;7589.165191828894;7882.330001949841;7895.605219739047;7903.683852645887;7903.683852645887;27524.44951100987;28391.716082839113;28825.855870720723;30148.397032059234;30510.237244068852;2988.9402211955858;2997.250687328168;3000.744936680385;3033.226527235563;3033.9089827305215;16993.91503042489;17498.912505437518;17655.558611034827;17740.556486087822;18605.348812791486;2015;2052.0765655454957;2059.34101087652;2069.886156261405;2073.2950796729033;136711.63288367089;147059;161934.95327102803;163190;175686.24313756826;19110.61778764417;24027.639585406232;24154.87922560376;24693.382665433506;24791.628125578132;43901;44294;45062;45342.63972080777;45344.54655453469;11509.136814738928;11774.116941229442;11802.173847830742;11813.854267485804;11827.112966527546;9443.338966272295;9468.431894086378;9472.763180920394;9640.566174522164;9642.855357169648;9130.543472826332;11320.943395282971;11322.660320190407;11490.655280341603;11491.712707182387;3870.670992965573;4463.24124898768;4579.771011449414;4625.676202665848;4628;1020.403064207447;1047.2355180718118;1047.6542740895486;1051.3429106808244;1058.962936297226;14885;20484.590308193754;21188.89405552993;21271.149154033974;21280.255191068238;982.5922242269378;1053.7839742852698;1062.0600768319937;1088.4122573810055;1090.4711215060688;10358.430286334324;10627.84058239127;10973.28673636227;11027.669169924915;11077.612283570037;713669;726554;732095;737615;747304;134231.3288433571;134553.3272333632;149589.50410495972;151742;162688.49246030027;1153.7231064544617;1157.2709193208225;1179.3749312864195;1183.219075410225;1187.5606025770694;17966.730499042787;18465.446036618923;18757.531061723566;18773.90613046926;18803;52318;56632.30103096914;62165.75668486515;62880;63701.725965479505;82792;87892.5605371969;89243;90937;90981.09018909726;9706.6117355305;11018.614348497764;11284.774304514029;11597.304161750322;11889.940550297193;12105.213161144497;12566.874331256937;12583.87416125827;12727.363631818187;12849.678758031125;10786.838197426889;10997.945010274898;11139.997400234019;11216.046636036028;11264.493097810455;9571.1864491519;10158.695239142542;10216.744581385525;10474.21443391764;10506.054455099078;11025.228234023542;11152.609658661908;11274.098072154373;11366.602168924048;11398.031167350868;11558.537658493566;11571.710707232387;11805;11897.881021189678;11939.104567157325;10669.679909602724;11130.944345278545;11165.330080195214;11172.944135279271;11174 02:38:07 INFO - PROCESS | 1647 | |3;mootools.html;1334.9933250333747;1503.2859391788909;1513.8940274180804;1552.2626752292663;1553.3708847916598;10688.251822372436;11314.660560183187;11321.660350189497;11332.490037948292;11349.20555561111;10599.523021464034;11000.669979900596;11164.274322169053;11180.832287515694;11181.105511559075;2374.916877909278;2410.18053861687;2413.1071503543712;2414;2424.75752424757;2034.6235946349898;2381.595128828095;2408.1451084864884;2408.2895545814026;2408.385861605297;2327.7904988551027;2376.738558758535;2388.10446082719;2408.4460574067975;2436.2812970173754;2319.988400058006;2377.7146742391024;2377.857328560292;2381.5475059738596;2387.2957477544164;2330.4406942333717;2351.8236132289976;2359.398353419867;2361.7874391304863;2362.2795047510676;10945.781084378348;11161.776764464747;11207.719807004809;11230;11240.718982025515;5769.307683078057;5937.465628093449;5938.495227905633;5953.821385358447;5955;3414.146463384154;3563.732720045981;3588.318219538279;3624.9093772655633;3629.94555081674;1141.520561364229;1146.5700362364114;1172.4255114993678;1186.4423720851187;1201.2732296960387;13131;13534.25561594112;14041.368138433794;14312.785308220386;15236.771448428282;4019.437278780973;4743.146233677971;4743.573078422958;4758.928616070762;4766.6901651392545;10239.897601024044;11870.406479676154;11872.050235981102;11872.287662740091;11879.76240475203;4903.779329930162;5530.142827861688;5683.715814209273;5699.458551437607;5728.656280623092;5103.566196873233;5207.062728708793;5209.7916083356995;5294.49702278283;5305.973470132624;3283.6223834259367;3856.537215534154;3866.5553461352306;3910.2570511602703;3948.1511475033008;1017.3539802225492;1022.99488502557;1036.0778906772978;1036.6941752183125;1037.8443233515034;799.6641410607559;811.067272636461;820.8686610142468;829.340674164029;833.4249367936104;6463;6521.739130434825;6571.34286571339;6723;6799.5920244785475;967.7902621722847;980.2745967983641;990.4404011733347;1006.6879267427121;1033.8914413986574;4109.630133288018;4159.376093585925;4163.2298024864895;4285.521495084191;4291.163223171452;13479.797803032963;14151.646208844655;14156.221407822668;14158.858411415755;14208.07647502909;14000.579982600539;14031;14033.1580105194;14060.945429092859;14080;9357.20463760588;9693.127618514367;9859.063388978035;9878.555465004092;9884.555195016097;6933.653317334012;6983.580985140908;7083.724929512738;7091.290870913073;7140.036489965301;12256.877431225574;12478.563250286197;12594.748105038032;12770.04224683171;12800.679983000127;9646.855297170689;12583.811242831547;12902.80645790295;12926;12949.676258093623;9353;9381.953090234505;9446.905530944603;9526.666566670134;9577.185939195246;10286.897131028893;10329.535170917177;10470.633527826489;10479.214058945458;10479.58081676755;8864;8935.865962010444;8938.50838203905;9080.636774528944;9193.908060919573;8631.964164300323;8741.781455463664;8828.205461508494;8829.867551986854;8996.235319997873;8003.559804210824;8051.758447246592;8179.795505112182;8202;8203.83592328162;5358.464153584766;5711.286089238845;5917.319508256433;5953;6087.269527656709;5930.318013428338;6113.388661133851;6143.754249829957;6211.2857021441305;6270.968645156928 02:38:07 INFO - PROCESS | 1647 | |4;prototype.html;369.8539077064558;384.7479900665064;389.0875896023825;398.4163200910666;401.1695789715289;5221.634485586011;5242.7640756165965;5269.973650131744;5327.840164795052;5376.059189641816;4446.822127114911;4957.611868676768;5119.539241468267;5184.455632158626;5205.7136857472915;4260.8721738347895;4390.363397307388;4398.780060996937;4407.845725399612;4463.330500424938;3818.599047100056;4388.319810429372;4399.560043995591;4456.90805752591;4465.330200469939;3604.6395360464007;3725.1618385863235;3920.1571662092642;3927.725059245854;4447.399601053865;4280.614744672963;4325.351197320396;4441.644668426518;4452.17634737573;4492.707973981681;3420.9486857697157;3533.9646603533893;3820.9301395609273;3989.261986532501;4065.3698676705158;4015.5382131054716;4347.152615037156;4420.557944205586;5047.778977249998;5126.307948426952;4299.957000429987;4729.196036673774;4993.827684957056;5051.81282398636;5097.037640508393;4564.589186973155;5068.746562671851;5206.37523497179;5488.984537860469;5659.292588426447;4034.031832360242;4208.473940757405;4490.797914093873;4491.6182124519455;4516.390287311204;4771.880702982418;5171.456997015334;5721.628094173865;5978.611390259619;6005.3394126646035;3400.42192827215;4123.783483872265;4128.38074288853;4378.1541997701115;4396.230659634552;4712.269598212282;4727.454409193105;4733.254712266877;4745.4577262389175;4747.525247475294;4338.110687309094;4926.975365123151;4993.626752643052;5014.373203349582;5073.771680274396;5365.8000219909645;5588.3573389060775;5616.9327827712605;5656.321241451053;5806.2451881255165;1352.1481466676128;1474.1499418230267;1565.6399028223573;1575.2911189964561;1587.484067677986;1039.838824982129;1125.1729978465821;1176.8823117688162;1191.7497325561555;1205.1019644060639;4745.810167593327;4994.751312171957;5084.720340381315;5264.763085661154;5347.598930080259;5101.913342427852;5434.711999240481;5470.56235501159;5722.685252311163;5831.271091113611;4796.496367881322;4805.663603547788;5165.845024649267;5222.869428264324;5307.840764777063;2531.9240422787348;2604.4140068484817;2657.5615023520904;2829.63214782077;3011.472992226352;5598.50866364472;5813.8662960723095;6074.385740484405;6090.786822461193;6107.358727333566;5593.909187708357;5603.842520629505;5823.272090988627;5990.7304171311525;6048.401201285228;5287.995280896617;5475.890482190334;5545.201536947204;5930.495907847881;5960.612560183574;5037.496250374933;5410.864728381822;5646.322441307069;5693.9430605693415;5724.885502289848;4957.702537847741;5255.185446255837;5519.151084386775;5537.373020671466;5715.742536641932;5184.714840683799;5323.26229655599;5413.729313534386;5436.510714035756;6125.571210015256;4389.934150987801;4503.2569626012655;4523.168116912656;4561.429821272341;4563.863084107482;4929.531694489017;4939.481354457874;5491.716461160301;5831.504322132497;5876.030454974882;4646.558576935186;5374.946250537601;5559.944400555943;5579.497845193952;5649.887002260014;4795.424549054136;5034.219695947134;5441.5374693151525;5565.16522521625;5602.355729091207;4595.494495605548;4740;5156.20078887773;5487.780488780404;5495.966492397679;4650.488446270975;5588.217649528988;5637.3235211774845;5684.403137670485;5699.746055867702;5234.947650523598;5301.655392399482;5386.730663466889;5522.420145884785;5541.030319694118 02:38:07 INFO - PROCESS | 1647 | |5;yui.html;748.2741740511701;758.5031804168268;773.1997382708897;818.2186012358196;822.1572887790014;14588.854111458882;15296.770548441767;15311.310991005405;17199.570010749736;18023.36918207863;16491;16541.83458165418;16579.00525968443;16595.751063734053;16650.83349166508;930.9255259579252;962.9807403851954;969.8351280282369;970.9854352184723;972.9318947673665;778.3889646627399;953.0897992417259;953.1469334945219;954.1889394015101;955.6655170690237;934.728928610702;945.5438624518268;1000.3797645459825;1018.3024628129755;1021.101430740947;1012.5190534496129;1012.6607586458546;1014.6043043213152;1015.5886865819355;1020.1991436722175;754.2758951406656;759.092884003616;759.142169348638;762.0246085011195;766.486454075765;15723.606909827231;15728.213589320489;15982.041077535385;16108.75836862448;16120.032798032156;14047.43810247589;14081.366338514792;14151.644583626512;14177.929110354382;14208.644783880382;11749.412529373583;11823.11326650496;11906.58326958561;12045.518179272818;12153.453094610764;3602.1534939289245;3643.333680034474;3644.1982763791925;3653.799041052741;3713.7771733695795;110565.44717276443;112841;112876.43561782221;115902;117106;10778.461076946121;10994.395308257963;11021.338719676685;11155.163362747826;11160.16298777595;21932.561348772935;22575.43561410945;22596.887015565146;22640.433989150402;22641.886790565943;7638;7721.6139193041245;7728.690852365955;7899.091604465445;8000.119986801447;4235.839721741934;4269.338252570857;4291.399204111427;4383.64589584996;4415.227335216325;3462.4113900636953;3552.626974167675;3563.429851223792;3564.3762341590636;3566.037169964095;549.365482867289;630.6027202862167;640.5388120553195;647.5758378262152;648.1315037849305;534.4281618667987;645.3675398109879;669.7120238297481;676.6481429656551;695.7356204642305;9149.862752058725;9485.430874147572;10022.599096036223;10144.391336519684;10660.200484963503;528.3395755305868;537.2398056749734;582.4175824175824;628.7893555658814;689.269285427798;5245.921311180336;5363.919541206885;5384.246205531229;5557.305336832896;5588.776448941997;101110;104249.95750042555;105373.47313263538;105991.4700426493;108645.45677271562;89468.10531894727;92441.07558924459;94885.5255723717;95991;96009.55985660221;78111.4377712454;80444.78336498272;81151.97120072045;81425;82868.58565707132;10606.204534660086;10851.240413171003;11184.273022253528;11185.552577896793;11216.66350009501;47997.520024799305;51485.45543633697;51698.74150629373;51763.48236517586;52780.20829687634;49858;66744.6662766683;66769.66615166893;67015.65968680696;67602;11972.820407693718;12104.515819367127;12238.986598418482;12267.509299627915;12330.938345308576;11880;14195.858041419453;14337.85662143407;14633.487827925972;15122.848771512585;13413.195208287534;13769.449222031408;14149.646258843612;14216.502422415166;14461.638459038608;13482.595522134352;13580.100094148118;13671.589852304447;14449;14471.638209044859;13248.668783280495;13402.463901443833;13742.93128534351;13747.656308592364;13950.720985580434;8160.30637395828;9188.264938805014;9216.30877684163;9357.485338306458;9783.510824458606;11069.225154239448;11375.033122184706;11429.485673144894;11466.2546934449;11655.30068195911 02:38:07 INFO - PROCESS | 1647 | __end_tp_report 02:38:07 INFO - PROCESS | 1647 | __start_cc_report 02:38:07 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1535 02:38:07 INFO - PROCESS | 1647 | __end_cc_report 02:38:07 INFO - PROCESS | 1647 | __startTimestamp1491730687466__endTimestamp 02:38:07 INFO - PROCESS | 1647 | 02:38:07 INFO - PROCESS | 1647 | ------- Summary: start ------- 02:38:07 INFO - PROCESS | 1647 | Number of tests: 6 02:38:07 INFO - PROCESS | 1647 | 02:38:07 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:6000.39 Median:6241.39 stddev:659.59 (10.6%) stddev-sans-first:629.72 02:38:07 INFO - PROCESS | 1647 | Values: 3417.5 3422.5 3433.8 3453.4 3483.3 6083.8 6085.3 6094.0 6117.8 6129.2 6071.0 6115.5 6139.7 6141.9 6149.1 5449.9 5487.0 5513.6 5539.6 5613.3 4530.3 5322.6 5403.4 5600.9 5644.7 5464.4 5497.3 5503.8 5504.0 5505.4 5478.4 5481.6 5493.3 5501.3 5504.5 5330.5 5452.6 5456.0 5456.6 5485.4 6064.8 6078.3 6078.7 6080.8 6096.7 6338.9 6349.8 6354.6 6370.6 6378.4 6325.2 6334.9 6397.9 6409.1 6410.6 6073.8 6123.4 6129.2 6141.4 6150.7 6289.5 6659.6 6659.8 6665.9 6668.1 6297.1 6313.1 6330.5 6366.7 6425.3 6451.3 6454.5 6456.9 6461.4 6470.5 6241.4 6388.5 6391.3 6391.8 6397.5 6304.4 6345.0 6362.7 6399.1 6403.1 5172.5 5212.7 5224.2 5233.5 5272.9 4568.1 4578.2 4581.8 4586.4 4627.4 5930.6 5979.6 6063.7 6105.5 6108.3 6337.4 6370.4 6410.3 6411.3 6421.3 6052.0 6087.6 6093.7 6097.4 6114.3 5704.4 5713.2 5714.8 5720.0 5732.6 6445.0 6466.1 6618.5 6667.8 6680.4 6430.6 6457.4 6472.1 6474.5 6490.8 6648.9 6714.1 6732.4 6746.2 6749.2 6271.0 6326.9 6350.1 6357.2 6429.7 6323.6 6471.5 6478.6 6484.1 6512.4 6351.2 6358.2 6371.2 6395.3 6401.6 6177.8 6217.3 6279.8 6301.4 6312.6 6257.4 6284.2 6293.7 6304.2 6307.9 6357.3 6372.8 6373.6 6382.3 6406.9 6018.0 6121.7 6131.3 6186.3 6234.2 02:38:07 INFO - PROCESS | 1647 | 02:38:07 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:54637.59 Median:30292.36 stddev:108534.81 (358.3%) stddev-sans-first:108763.82 02:38:07 INFO - PROCESS | 1647 | Values: 732.4 766.7 788.1 844.3 856.4 27316.6 39721.6 40184.8 40230.0 40240.8 35135.1 41679.4 41685.0 41730.4 42058.6 7366.7 9057.8 9206.4 9218.3 9226.8 7348.6 10829.0 11010.7 11022.4 11024.6 9201.9 10995.9 11024.0 11026.9 11049.2 10954.0 10984.2 11007.6 11020.0 11028.1 9345.4 10623.2 10730.6 10784.6 10798.3 38940.0 39098.6 39227.0 39245.8 39319.0 3768.2 3836.6 3847.8 3872.8 3898.9 27019.5 28497.1 28915.7 29083.3 29144.0 2537.2 2834.8 2846.2 2848.9 2872.7 364711.0 365453.0 365612.0 365880.0 366398.0 52415.5 52487.2 52510.5 52539.9 52543.5 47729.0 54715.0 54944.0 55020.4 55072.2 14555.8 14582.3 14596.4 14617.9 14672.3 10941.5 11112.9 12690.8 12699.4 12706.2 10300.6 11596.7 11806.0 11834.6 12055.5 5630.0 5637.9 5670.8 5686.2 5695.9 908.4 1065.3 1084.7 1115.8 1120.1 25385.9 27476.8 27556.7 27692.9 27798.9 1153.4 1159.2 1165.3 1209.5 1211.7 13938.6 14022.9 14412.0 14440.3 14455.3 443373.0 444636.0 445480.0 445809.0 446018.0 55510.7 55675.4 55706.0 55717.7 55831.7 1265.6 1431.2 1480.4 1488.0 1488.0 26141.3 26386.6 26869.3 26961.0 27032.5 41979.6 42150.2 42174.8 42181.8 42188.4 413746.0 413817.9 413971.0 414211.0 416021.0 33101.7 33663.0 33815.0 33820.8 33865.7 43284.6 44069.8 44094.6 44225.3 44226.8 26450.6 28537.3 29323.9 30172.4 30412.3 22166.7 27858.7 27971.4 28268.4 28468.9 32531.7 32907.8 33026.0 33237.5 33244.7 27983.2 28008.7 28146.0 28428.1 29118.0 22984.2 23058.2 23503.9 23518.9 23524.2 02:38:07 INFO - PROCESS | 1647 | 02:38:07 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:43105.00 Median:11526.39 stddev:122027.00 (1058.7%) stddev-sans-first:122327.12 02:38:07 INFO - PROCESS | 1647 | Values: 368.9 389.6 410.2 426.8 452.2 12768.9 14751.3 20979.5 21427.8 21598.4 22539.0 22639.7 22962.9 23184.2 23486.2 6503.3 7490.4 7826.6 7923.0 7948.0 7690.2 7932.0 7933.7 8004.6 8009.4 7592.4 7956.6 8005.0 8014.0 8020.1 7540.8 7932.2 8014.2 8151.5 8156.3 7589.2 7882.3 7895.6 7903.7 7903.7 27524.4 28391.7 28825.9 30148.4 30510.2 2988.9 2997.3 3000.7 3033.2 3033.9 16993.9 17498.9 17655.6 17740.6 18605.3 2015.0 2052.1 2059.3 2069.9 2073.3 136711.6 147059.0 161935.0 163190.0 175686.2 19110.6 24027.6 24154.9 24693.4 24791.6 43901.0 44294.0 45062.0 45342.6 45344.5 11509.1 11774.1 11802.2 11813.9 11827.1 9443.3 9468.4 9472.8 9640.6 9642.9 9130.5 11320.9 11322.7 11490.7 11491.7 3870.7 4463.2 4579.8 4625.7 4628.0 1020.4 1047.2 1047.7 1051.3 1059.0 14885.0 20484.6 21188.9 21271.1 21280.3 982.6 1053.8 1062.1 1088.4 1090.5 10358.4 10627.8 10973.3 11027.7 11077.6 713669.0 726554.0 732095.0 737615.0 747304.0 134231.3 134553.3 149589.5 151742.0 162688.5 1153.7 1157.3 1179.4 1183.2 1187.6 17966.7 18465.4 18757.5 18773.9 18803.0 52318.0 56632.3 62165.8 62880.0 63701.7 82792.0 87892.6 89243.0 90937.0 90981.1 9706.6 11018.6 11284.8 11597.3 11889.9 12105.2 12566.9 12583.9 12727.4 12849.7 10786.8 10997.9 11140.0 11216.0 11264.5 9571.2 10158.7 10216.7 10474.2 10506.1 11025.2 11152.6 11274.1 11366.6 11398.0 11558.5 11571.7 11805.0 11897.9 11939.1 10669.7 11130.9 11165.3 11172.9 11174.0 02:38:07 INFO - PROCESS | 1647 | 02:38:07 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:6753.21 Median:2859.95 stddev:4227.09 (147.8%) stddev-sans-first:4219.34 02:38:07 INFO - PROCESS | 1647 | Values: 1335.0 1503.3 1513.9 1552.3 1553.4 10688.3 11314.7 11321.7 11332.5 11349.2 10599.5 11000.7 11164.3 11180.8 11181.1 2374.9 2410.2 2413.1 2414.0 2424.8 2034.6 2381.6 2408.1 2408.3 2408.4 2327.8 2376.7 2388.1 2408.4 2436.3 2320.0 2377.7 2377.9 2381.5 2387.3 2330.4 2351.8 2359.4 2361.8 2362.3 10945.8 11161.8 11207.7 11230.0 11240.7 5769.3 5937.5 5938.5 5953.8 5955.0 3414.1 3563.7 3588.3 3624.9 3629.9 1141.5 1146.6 1172.4 1186.4 1201.3 13131.0 13534.3 14041.4 14312.8 15236.8 4019.4 4743.1 4743.6 4758.9 4766.7 10239.9 11870.4 11872.1 11872.3 11879.8 4903.8 5530.1 5683.7 5699.5 5728.7 5103.6 5207.1 5209.8 5294.5 5306.0 3283.6 3856.5 3866.6 3910.3 3948.2 1017.4 1023.0 1036.1 1036.7 1037.8 799.7 811.1 820.9 829.3 833.4 6463.0 6521.7 6571.3 6723.0 6799.6 967.8 980.3 990.4 1006.7 1033.9 4109.6 4159.4 4163.2 4285.5 4291.2 13479.8 14151.6 14156.2 14158.9 14208.1 14000.6 14031.0 14033.2 14060.9 14080.0 9357.2 9693.1 9859.1 9878.6 9884.6 6933.7 6983.6 7083.7 7091.3 7140.0 12256.9 12478.6 12594.7 12770.0 12800.7 9646.9 12583.8 12902.8 12926.0 12949.7 9353.0 9382.0 9446.9 9526.7 9577.2 10286.9 10329.5 10470.6 10479.2 10479.6 8864.0 8935.9 8938.5 9080.6 9193.9 8632.0 8741.8 8828.2 8829.9 8996.2 8003.6 8051.8 8179.8 8202.0 8203.8 5358.5 5711.3 5917.3 5953.0 6087.3 5930.3 6113.4 6143.8 6211.3 6271.0 02:38:07 INFO - PROCESS | 1647 | 02:38:07 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4627.28 Median:5024.30 stddev:1305.95 (26.0%) stddev-sans-first:1269.92 02:38:07 INFO - PROCESS | 1647 | Values: 369.9 384.7 389.1 398.4 401.2 5221.6 5242.8 5270.0 5327.8 5376.1 4446.8 4957.6 5119.5 5184.5 5205.7 4260.9 4390.4 4398.8 4407.8 4463.3 3818.6 4388.3 4399.6 4456.9 4465.3 3604.6 3725.2 3920.2 3927.7 4447.4 4280.6 4325.4 4441.6 4452.2 4492.7 3420.9 3534.0 3820.9 3989.3 4065.4 4015.5 4347.2 4420.6 5047.8 5126.3 4300.0 4729.2 4993.8 5051.8 5097.0 4564.6 5068.7 5206.4 5489.0 5659.3 4034.0 4208.5 4490.8 4491.6 4516.4 4771.9 5171.5 5721.6 5978.6 6005.3 3400.4 4123.8 4128.4 4378.2 4396.2 4712.3 4727.5 4733.3 4745.5 4747.5 4338.1 4927.0 4993.6 5014.4 5073.8 5365.8 5588.4 5616.9 5656.3 5806.2 1352.1 1474.1 1565.6 1575.3 1587.5 1039.8 1125.2 1176.9 1191.7 1205.1 4745.8 4994.8 5084.7 5264.8 5347.6 5101.9 5434.7 5470.6 5722.7 5831.3 4796.5 4805.7 5165.8 5222.9 5307.8 2531.9 2604.4 2657.6 2829.6 3011.5 5598.5 5813.9 6074.4 6090.8 6107.4 5593.9 5603.8 5823.3 5990.7 6048.4 5288.0 5475.9 5545.2 5930.5 5960.6 5037.5 5410.9 5646.3 5693.9 5724.9 4957.7 5255.2 5519.2 5537.4 5715.7 5184.7 5323.3 5413.7 5436.5 6125.6 4389.9 4503.3 4523.2 4561.4 4563.9 4929.5 4939.5 5491.7 5831.5 5876.0 4646.6 5374.9 5559.9 5579.5 5649.9 4795.4 5034.2 5441.5 5565.2 5602.4 4595.5 4740.0 5156.2 5487.8 5496.0 4650.5 5588.2 5637.3 5684.4 5699.7 5234.9 5301.7 5386.7 5522.4 5541.0 02:38:07 INFO - PROCESS | 1647 | 02:38:07 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:21045.11 Median:13052.15 stddev:30528.37 (233.9%) stddev-sans-first:30575.97 02:38:07 INFO - PROCESS | 1647 | Values: 748.3 758.5 773.2 818.2 822.2 14588.9 15296.8 15311.3 17199.6 18023.4 16491.0 16541.8 16579.0 16595.8 16650.8 930.9 963.0 969.8 971.0 972.9 778.4 953.1 953.1 954.2 955.7 934.7 945.5 1000.4 1018.3 1021.1 1012.5 1012.7 1014.6 1015.6 1020.2 754.3 759.1 759.1 762.0 766.5 15723.6 15728.2 15982.0 16108.8 16120.0 14047.4 14081.4 14151.6 14177.9 14208.6 11749.4 11823.1 11906.6 12045.5 12153.5 3602.2 3643.3 3644.2 3653.8 3713.8 110565.4 112841.0 112876.4 115902.0 117106.0 10778.5 10994.4 11021.3 11155.2 11160.2 21932.6 22575.4 22596.9 22640.4 22641.9 7638.0 7721.6 7728.7 7899.1 8000.1 4235.8 4269.3 4291.4 4383.6 4415.2 3462.4 3552.6 3563.4 3564.4 3566.0 549.4 630.6 640.5 647.6 648.1 534.4 645.4 669.7 676.6 695.7 9149.9 9485.4 10022.6 10144.4 10660.2 528.3 537.2 582.4 628.8 689.3 5245.9 5363.9 5384.2 5557.3 5588.8 101110.0 104250.0 105373.5 105991.5 108645.5 89468.1 92441.1 94885.5 95991.0 96009.6 78111.4 80444.8 81152.0 81425.0 82868.6 10606.2 10851.2 11184.3 11185.6 11216.7 47997.5 51485.5 51698.7 51763.5 52780.2 49858.0 66744.7 66769.7 67015.7 67602.0 11972.8 12104.5 12239.0 12267.5 12330.9 11880.0 14195.9 14337.9 14633.5 15122.8 13413.2 13769.4 14149.6 14216.5 14461.6 13482.6 13580.1 13671.6 14449.0 14471.6 13248.7 13402.5 13742.9 13747.7 13950.7 8160.3 9188.3 9216.3 9357.5 9783.5 11069.2 11375.0 11429.5 11466.3 11655.3 02:38:07 INFO - PROCESS | 1647 | -------- Summary: end -------- 02:38:07 INFO - PROCESS | 1647 | 02:38:08 INFO - TEST-INFO | 1647: exit 0 02:38:08 INFO - TEST-OK | dromaeo_css | took 1120119ms 02:38:08 INFO - TEST-START | kraken 02:38:08 INFO - operating with platform_type : mac_ 02:38:08 INFO - Initialising browser for kraken test... 02:38:08 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpvtMLEU/profile http://localhost:49239/getInfo.html) 02:38:10 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 02:38:10 INFO - PROCESS | 1653 | colorDepth:24 02:38:10 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 02:38:10 INFO - PROCESS | 1653 | __metrics 02:38:10 INFO - PROCESS | 1653 | 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" 02:38:10 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:38:11 INFO - TEST-INFO | 1653: exit 0 02:38:11 INFO - Browser initialized. 02:38:11 INFO - Running cycle 1/1 for kraken test... 02:38:11 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpvtMLEU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:38:23 INFO - PROCESS | 1655 | RSS: Main: 210485248 02:38:23 INFO - PROCESS | 1655 | 02:38:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 02:38:27 INFO - PROCESS | 1655 | RSS: Main: 263991296 02:38:27 INFO - PROCESS | 1655 | 02:38:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 02:38:30 INFO - PROCESS | 1655 | RSS: Main: 277417984 02:38:30 INFO - PROCESS | 1655 | 02:38:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 02:38:34 INFO - PROCESS | 1655 | RSS: Main: 373698560 02:38:34 INFO - PROCESS | 1655 | 02:38:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 02:38:36 INFO - PROCESS | 1655 | RSS: Main: 278130688 02:38:36 INFO - PROCESS | 1655 | 02:38:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:38:39 INFO - PROCESS | 1655 | RSS: Main: 279445504 02:38:39 INFO - PROCESS | 1655 | 02:38:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 02:38:42 INFO - PROCESS | 1655 | RSS: Main: 319533056 02:38:42 INFO - PROCESS | 1655 | 02:38:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 02:38:46 INFO - PROCESS | 1655 | RSS: Main: 322154496 02:38:46 INFO - PROCESS | 1655 | 02:38:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 02:38:50 INFO - PROCESS | 1655 | RSS: Main: 316932096 02:38:50 INFO - PROCESS | 1655 | 02:38:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:38:52 INFO - PROCESS | 1655 | RSS: Main: 296022016 02:38:52 INFO - PROCESS | 1655 | 02:38:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:38:54 INFO - PROCESS | 1655 | RSS: Main: 299544576 02:38:54 INFO - PROCESS | 1655 | 02:38:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:38:57 INFO - PROCESS | 1655 | RSS: Main: 302276608 02:38:57 INFO - PROCESS | 1655 | 02:39:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:39:01 INFO - PROCESS | 1655 | RSS: Main: 319012864 02:39:01 INFO - PROCESS | 1655 | 02:39:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:39:04 INFO - PROCESS | 1655 | RSS: Main: 311484416 02:39:04 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 02:39:06 INFO - PROCESS | 1655 | RSS: Main: 355975168 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | __start_tp_report 02:39:06 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 02:39:06 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 02:39:06 INFO - PROCESS | 1655 | |i|pagename|runs| 02:39:06 INFO - PROCESS | 1655 | |0;ai-astar;106;115;117;115;114;115;115;116;116;114 02:39:06 INFO - PROCESS | 1655 | |1;audio-beat-detection;143;108;108;140;111;109;115;107;127;109 02:39:06 INFO - PROCESS | 1655 | |2;audio-dft;188;185;184;184;180;182;182;193;260;206 02:39:06 INFO - PROCESS | 1655 | |3;audio-fft;83;59;59;91;61;63;60;58;76;59 02:39:06 INFO - PROCESS | 1655 | |4;audio-oscillator;88;68;88;70;69;68;81;68;68;82 02:39:06 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;87;88;86;86;87;87;87;86;86;86 02:39:06 INFO - PROCESS | 1655 | |6;imaging-darkroom;94;94;95;95;92;95;94;95;93;94 02:39:06 INFO - PROCESS | 1655 | |7;imaging-desaturate;85;84;84;80;82;86;83;96;114;86 02:39:06 INFO - PROCESS | 1655 | |8;json-parse-financial;65;62;62;62;65;64;65;64;64;64 02:39:06 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;64;65;56;63;60;57;59;59;58;59 02:39:06 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;82;85;82;80;82;82;81;82;79;79 02:39:06 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;138;137;137;132;133;144;152;140;184;137 02:39:06 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;166;167;165;166;165;164;176;162;161;164 02:39:06 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;62;62;65;64;65;64;63;66;64;63 02:39:06 INFO - PROCESS | 1655 | __end_tp_report 02:39:06 INFO - PROCESS | 1655 | __start_cc_report 02:39:06 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2112 02:39:06 INFO - PROCESS | 1655 | __end_cc_report 02:39:06 INFO - PROCESS | 1655 | __startTimestamp1491730746601__endTimestamp 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | ------- Summary: start ------- 02:39:06 INFO - PROCESS | 1655 | Number of tests: 14 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:3.06 (2.7%) stddev-sans-first:0.97 02:39:06 INFO - PROCESS | 1655 | Values: 106.0 115.0 117.0 115.0 114.0 115.0 115.0 116.0 116.0 114.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:117.70 Median:113.00 stddev:13.86 (12.3%) stddev-sans-first:11.29 02:39:06 INFO - PROCESS | 1655 | Values: 143.0 108.0 108.0 140.0 111.0 109.0 115.0 107.0 127.0 109.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:194.40 Median:186.50 stddev:24.27 (13.0%) stddev-sans-first:25.63 02:39:06 INFO - PROCESS | 1655 | Values: 188.0 185.0 184.0 184.0 180.0 182.0 182.0 193.0 260.0 206.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:66.90 Median:62.00 stddev:11.96 (19.3%) stddev-sans-first:11.17 02:39:06 INFO - PROCESS | 1655 | Values: 83.0 59.0 59.0 91.0 61.0 63.0 60.0 58.0 76.0 59.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:75.50 stddev:8.69 (11.5%) stddev-sans-first:7.84 02:39:06 INFO - PROCESS | 1655 | Values: 88.0 68.0 88.0 70.0 69.0 68.0 81.0 68.0 68.0 82.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 02:39:06 INFO - PROCESS | 1655 | Values: 87.0 88.0 86.0 86.0 87.0 87.0 87.0 86.0 86.0 86.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.50 stddev:0.99 (1.1%) stddev-sans-first:1.05 02:39:06 INFO - PROCESS | 1655 | Values: 94.0 94.0 95.0 95.0 92.0 95.0 94.0 95.0 93.0 94.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:88.00 Median:84.50 stddev:10.08 (11.9%) stddev-sans-first:10.63 02:39:06 INFO - PROCESS | 1655 | Values: 85.0 84.0 84.0 80.0 82.0 86.0 83.0 96.0 114.0 86.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:63.70 Median:64.00 stddev:1.25 (2.0%) stddev-sans-first:1.24 02:39:06 INFO - PROCESS | 1655 | Values: 65.0 62.0 62.0 62.0 65.0 64.0 65.0 64.0 64.0 64.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:59.50 stddev:3.02 (5.1%) stddev-sans-first:2.83 02:39:06 INFO - PROCESS | 1655 | Values: 64.0 65.0 56.0 63.0 60.0 57.0 59.0 59.0 58.0 59.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:82.00 stddev:1.78 (2.2%) stddev-sans-first:1.87 02:39:06 INFO - PROCESS | 1655 | Values: 82.0 85.0 82.0 80.0 82.0 82.0 81.0 82.0 79.0 79.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:139.00 stddev:15.36 (11.1%) stddev-sans-first:16.17 02:39:06 INFO - PROCESS | 1655 | Values: 138.0 137.0 137.0 132.0 133.0 144.0 152.0 140.0 184.0 137.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:165.50 stddev:4.09 (2.5%) stddev-sans-first:4.33 02:39:06 INFO - PROCESS | 1655 | Values: 166.0 167.0 165.0 166.0 165.0 164.0 176.0 162.0 161.0 164.0 02:39:06 INFO - PROCESS | 1655 | 02:39:06 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:64.00 stddev:1.32 (2.1%) stddev-sans-first:1.22 02:39:06 INFO - PROCESS | 1655 | Values: 62.0 62.0 65.0 64.0 65.0 64.0 63.0 66.0 64.0 63.0 02:39:06 INFO - PROCESS | 1655 | -------- Summary: end -------- 02:39:06 INFO - PROCESS | 1655 | 02:39:07 INFO - TEST-INFO | 1655: exit 0 02:39:07 INFO - TEST-OK | kraken | took 59361ms 02:39:07 INFO - SUITE-END | took 1179s 02:39:07 INFO - Completed test suite (00:19:39) 02:39:07 INFO - javascript benchmark 02:39:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7785.262224182189, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3417.4702921047246, 3422.537957375754, 3433.7939723616587, 3453.412919803633, 3483.2859263850914, 6083.847903802407, 6085.300190478097, 6093.969530152349, 6117.755289788403, 6129.233845769279, 6070.998285282934, 6115.48018418435, 6139.72371243294, 6141.877162456759, 6149.13912052313, 5449.891002179954, 5487.0, 5513.641613295143, 5539.556835453174, 5613.326400831906, 4530.275155975045, 5322.574194064485, 5403.405625381205, 5600.887982240352, 5644.717764111799, 5464.371597266306, 5497.340319161706, 5503.752331145088, 5503.972480137593, 5505.394406615269, 5478.369987451454, 5481.588880833933, 5493.285872836505, 5501.312335958005, 5504.532114770249, 5330.493603107738, 5452.59105567084, 5456.017916774979, 5456.590755693299, 5485.424030476783, 6064.787732429388, 6078.331383547807, 6078.696065196723, 6080.787172448987, 6096.69516524172, 6338.87322253557, 6349.777757778502, 6354.618722876596, 6370.585911915757, 6378.362163783585, 6325.240971083454, 6334.873302533923, 6397.872042559169, 6409.102725618417, 6410.583312084699, 6073.787417440412, 6123.387661233885, 6129.20320358355, 6141.4472697456995, 6150.723217455181, 6289.465395441347, 6659.600423974577, 6659.800205993828, 6665.900011499832, 6668.133142691516, 6297.086922396318, 6313.05304204373, 6330.493560515148, 6366.745330186741, 6425.293217746044, 6451.29035806061, 6454.483641308684, 6456.9354306456335, 6461.418472337418, 6470.54706170573, 6241.3856132949895, 6388.488920886318, 6391.296957334689, 6391.840203994844, 6397.488200943913, 6304.369563043659, 6345.0482427636225, 6362.745490180433, 6399.104125422445, 6403.135576697179, 5172.482751724872, 5212.739363031833, 5224.16413373866, 5233.476652334813, 5272.92090618641, 4568.063546972929, 4578.244589642738, 4581.770911454414, 4586.403767510203, 4627.444706635225, 5930.55520835939, 5979.641221526722, 6063.6664983426335, 6105.511559075263, 6108.267007958984, 6337.397947195009, 6370.394812492806, 6410.326915673881, 6411.262704789009, 6421.325760795143, 6051.969740151271, 6087.57386982907, 6093.69531523422, 6097.390260973867, 6114.277451171044, 5704.401037890962, 5713.22871412354, 5714.771409143671, 5719.971400142973, 5732.570057245724, 6444.967775161094, 6466.1270728452, 6618.503612229199, 6667.833304167435, 6680.432163266177, 6430.582012169194, 6457.4188323049275, 6472.093906853139, 6474.546781725422, 6490.837729056811, 6648.933510665025, 6714.127163468718, 6732.427743641845, 6746.190457145174, 6749.156355455568, 6271.0, 6326.905096423649, 6350.079238510482, 6357.23713154406, 6429.678516074271, 6323.588966717149, 6471.4822814175695, 6478.578892371981, 6484.092227088306, 6512.446442052289, 6351.237851457855, 6358.1734374531015, 6371.203599550056, 6395.328490508429, 6401.583897046863, 6177.752889884534, 6217.316095229431, 6279.811605651837, 6301.369863013845, 6312.621242725268, 6257.43683068526, 6284.214473190851, 6293.716782744696, 6304.18045654062, 6307.936920630735, 6357.332480089524, 6372.808815735535, 6373.5857169283845, 6382.266039405342, 6406.903896441464, 6018.0, 6121.663308518075, 6131.254333328352, 6186.257649081959, 6234.1583886174785], "value": 5957.146991955766, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.4030914804434, 766.7124828189429, 788.1094363369392, 844.3161039557958, 856.4176360075146, 27316.590251146223, 39721.60278397215, 40184.79907600465, 40230.0, 40240.79277621676, 35135.121621959464, 41679.374809377885, 41685.0, 41730.374044389355, 42058.579414205924, 7366.668499917517, 9057.81884362314, 9206.355555111146, 9218.308626852979, 9226.769330766718, 7348.632568371587, 10829.0, 11010.669679909615, 11022.44887755609, 11024.614138505154, 9201.907980920205, 10995.945020274887, 11024.007839294462, 11026.889731102707, 11049.226554141213, 10954.0, 10984.176186785942, 11007.559697612085, 11020.0, 11028.117750579975, 9345.439273643535, 10623.150147988143, 10730.570777168903, 10784.622538211204, 10798.298110622783, 38940.0, 39098.60901390978, 39227.01932451683, 39245.80377098125, 39319.0, 3768.176733780765, 3836.635519625631, 3847.826847791856, 3872.7869967151796, 3898.941515877264, 27019.45961080787, 28497.145085647462, 28915.710842891513, 29083.272918177005, 29144.0, 2537.2134638262196, 2834.8440835754027, 2846.1746093632823, 2848.9145325640266, 2872.7127287271314, 364711.0, 365452.9999999973, 365612.0, 365880.0, 366398.0, 52415.47584524106, 52487.212691809655, 52510.474895251326, 52539.94920101653, 52543.474565254626, 47729.0, 54715.0, 54944.0, 55020.44979550153, 55072.17391739127, 14555.78166327506, 14582.270886455635, 14596.416143354147, 14617.853821461862, 14672.339744711513, 10941.507632156572, 11112.944270294309, 12690.80963785544, 12699.428525716181, 12706.237625742486, 10300.639477618397, 11596.652100437, 11806.0, 11834.644960651194, 12055.457504412323, 5629.971850140723, 5637.943620563824, 5670.801521946794, 5686.175504551899, 5695.943040569541, 908.4276905549461, 1065.2874731560505, 1084.7450849050467, 1115.8326251062242, 1120.1207052463776, 25385.873070634527, 27476.763545640493, 27556.72443275582, 27692.86153569219, 27798.861005694842, 1153.423288355822, 1159.1943599198644, 1165.3299352872132, 1209.5041033176356, 1211.6970757310673, 13938.581842544538, 14022.85977140236, 14412.0, 14440.277986100653, 14455.27723613815, 443373.0, 444636.0, 445480.0, 445809.0, 446018.0, 55510.72244638832, 55675.44324556783, 55706.0, 55717.72141139268, 55831.72084139553, 1265.5570550307502, 1431.198528823862, 1480.4152359818027, 1487.9851201488063, 1488.047649504318, 26141.346466338495, 26386.604200937385, 26869.328266793487, 26961.0, 27032.459350813268, 41979.58020419757, 42150.15699685928, 42174.78912605417, 42181.78909105435, 42188.367174491796, 413746.0, 413817.9309103556, 413971.0, 414211.0, 416021.0, 33101.67593296241, 33662.99011029673, 33815.0, 33820.83089584536, 33865.66134338625, 43284.567154329314, 44069.77965110154, 44094.559054410325, 44225.33661995137, 44226.778866105466, 26450.603240951015, 28537.28656783597, 29323.853380733814, 30172.396552069276, 30412.3273219975, 22166.66749998719, 27858.721412786424, 27971.440571188054, 28268.43463130767, 28468.85765571159, 32531.674683252866, 32907.848225312, 33026.00921972345, 33237.50143747894, 33244.667553325125, 27983.174453045598, 28008.71991280061, 28146.01488947877, 28428.147155585364, 29118.0, 22984.195553156227, 23058.192963246205, 23503.88248058817, 23518.88240558786, 23524.176653817034], "value": 18457.86418595149, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.8561461030198, 389.5500696695314, 410.22467536356294, 426.8356682677169, 452.15447113897, 12768.936155319221, 14751.262436878167, 20979.47551311216, 21427.785722142773, 21598.3520494385, 22539.0, 22639.660405093935, 22962.88518557405, 23184.188553400636, 23486.177983770573, 6503.252126005499, 7490.400767938552, 7826.608669566499, 7922.9603851980655, 7947.960260198691, 7690.1540830508875, 7932.0, 7933.722319718811, 8004.599770011505, 8009.399295052865, 7592.392608591299, 7956.602169891511, 8005.039395272575, 8014.0, 8020.077691065544, 7540.849183016309, 7932.246436588515, 8014.198580141998, 8151.510909345399, 8156.306713929293, 7589.165191828894, 7882.330001949841, 7895.605219739047, 7903.683852645887, 7903.683852645887, 27524.44951100987, 28391.716082839113, 28825.855870720723, 30148.397032059234, 30510.237244068852, 2988.9402211955858, 2997.250687328168, 3000.744936680385, 3033.226527235563, 3033.9089827305215, 16993.91503042489, 17498.912505437518, 17655.558611034827, 17740.556486087822, 18605.348812791486, 2015.0, 2052.0765655454957, 2059.34101087652, 2069.886156261405, 2073.2950796729033, 136711.63288367089, 147059.0, 161934.95327102803, 163190.0, 175686.24313756826, 19110.61778764417, 24027.639585406232, 24154.87922560376, 24693.382665433506, 24791.628125578132, 43901.0, 44294.0, 45062.0, 45342.63972080777, 45344.54655453469, 11509.136814738928, 11774.116941229442, 11802.173847830742, 11813.854267485804, 11827.112966527546, 9443.338966272295, 9468.431894086378, 9472.763180920394, 9640.566174522164, 9642.855357169648, 9130.543472826332, 11320.943395282971, 11322.660320190407, 11490.655280341603, 11491.712707182387, 3870.670992965573, 4463.24124898768, 4579.771011449414, 4625.676202665848, 4628.0, 1020.403064207447, 1047.2355180718118, 1047.6542740895486, 1051.3429106808244, 1058.962936297226, 14885.0, 20484.590308193754, 21188.89405552993, 21271.149154033974, 21280.255191068238, 982.5922242269378, 1053.7839742852698, 1062.0600768319937, 1088.4122573810055, 1090.4711215060688, 10358.430286334324, 10627.84058239127, 10973.28673636227, 11027.669169924915, 11077.612283570037, 713669.0, 726554.0, 732095.0, 737615.0, 747304.0, 134231.3288433571, 134553.3272333632, 149589.50410495972, 151742.0, 162688.49246030027, 1153.7231064544617, 1157.2709193208225, 1179.3749312864195, 1183.219075410225, 1187.5606025770694, 17966.730499042787, 18465.446036618923, 18757.531061723566, 18773.90613046926, 18803.0, 52318.0, 56632.30103096914, 62165.75668486515, 62880.0, 63701.725965479505, 82792.0, 87892.5605371969, 89243.0, 90937.0, 90981.09018909726, 9706.6117355305, 11018.614348497764, 11284.774304514029, 11597.304161750322, 11889.940550297193, 12105.213161144497, 12566.874331256937, 12583.87416125827, 12727.363631818187, 12849.678758031125, 10786.838197426889, 10997.945010274898, 11139.997400234019, 11216.046636036028, 11264.493097810455, 9571.1864491519, 10158.695239142542, 10216.744581385525, 10474.21443391764, 10506.054455099078, 11025.228234023542, 11152.609658661908, 11274.098072154373, 11366.602168924048, 11398.031167350868, 11558.537658493566, 11571.710707232387, 11805.0, 11897.881021189678, 11939.104567157325, 10669.679909602724, 11130.944345278545, 11165.330080195214, 11172.944135279271, 11174.0], "value": 12103.787795920523, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.9933250333747, 1503.2859391788909, 1513.8940274180804, 1552.2626752292663, 1553.3708847916598, 10688.251822372436, 11314.660560183187, 11321.660350189497, 11332.490037948292, 11349.20555561111, 10599.523021464034, 11000.669979900596, 11164.274322169053, 11180.832287515694, 11181.105511559075, 2374.916877909278, 2410.18053861687, 2413.1071503543712, 2414.0, 2424.75752424757, 2034.6235946349898, 2381.595128828095, 2408.1451084864884, 2408.2895545814026, 2408.385861605297, 2327.7904988551027, 2376.738558758535, 2388.10446082719, 2408.4460574067975, 2436.2812970173754, 2319.988400058006, 2377.7146742391024, 2377.857328560292, 2381.5475059738596, 2387.2957477544164, 2330.4406942333717, 2351.8236132289976, 2359.398353419867, 2361.7874391304863, 2362.2795047510676, 10945.781084378348, 11161.776764464747, 11207.719807004809, 11230.0, 11240.718982025515, 5769.307683078057, 5937.465628093449, 5938.495227905633, 5953.821385358447, 5955.0, 3414.146463384154, 3563.732720045981, 3588.318219538279, 3624.9093772655633, 3629.94555081674, 1141.520561364229, 1146.5700362364114, 1172.4255114993678, 1186.4423720851187, 1201.2732296960387, 13131.0, 13534.25561594112, 14041.368138433794, 14312.785308220386, 15236.771448428282, 4019.437278780973, 4743.146233677971, 4743.573078422958, 4758.928616070762, 4766.6901651392545, 10239.897601024044, 11870.406479676154, 11872.050235981102, 11872.287662740091, 11879.76240475203, 4903.779329930162, 5530.142827861688, 5683.715814209273, 5699.458551437607, 5728.656280623092, 5103.566196873233, 5207.062728708793, 5209.7916083356995, 5294.49702278283, 5305.973470132624, 3283.6223834259367, 3856.537215534154, 3866.5553461352306, 3910.2570511602703, 3948.1511475033008, 1017.3539802225492, 1022.99488502557, 1036.0778906772978, 1036.6941752183125, 1037.8443233515034, 799.6641410607559, 811.067272636461, 820.8686610142468, 829.340674164029, 833.4249367936104, 6463.0, 6521.739130434825, 6571.34286571339, 6723.0, 6799.5920244785475, 967.7902621722847, 980.2745967983641, 990.4404011733347, 1006.6879267427121, 1033.8914413986574, 4109.630133288018, 4159.376093585925, 4163.2298024864895, 4285.521495084191, 4291.163223171452, 13479.797803032963, 14151.646208844655, 14156.221407822668, 14158.858411415755, 14208.07647502909, 14000.579982600539, 14031.0, 14033.1580105194, 14060.945429092859, 14080.0, 9357.20463760588, 9693.127618514367, 9859.063388978035, 9878.555465004092, 9884.555195016097, 6933.653317334012, 6983.580985140908, 7083.724929512738, 7091.290870913073, 7140.036489965301, 12256.877431225574, 12478.563250286197, 12594.748105038032, 12770.04224683171, 12800.679983000127, 9646.855297170689, 12583.811242831547, 12902.80645790295, 12926.0, 12949.676258093623, 9353.0, 9381.953090234505, 9446.905530944603, 9526.666566670134, 9577.185939195246, 10286.897131028893, 10329.535170917177, 10470.633527826489, 10479.214058945458, 10479.58081676755, 8864.0, 8935.865962010444, 8938.50838203905, 9080.636774528944, 9193.908060919573, 8631.964164300323, 8741.781455463664, 8828.205461508494, 8829.867551986854, 8996.235319997873, 8003.559804210824, 8051.758447246592, 8179.795505112182, 8202.0, 8203.83592328162, 5358.464153584766, 5711.286089238845, 5917.319508256433, 5953.0, 6087.269527656709, 5930.318013428338, 6113.388661133851, 6143.754249829957, 6211.2857021441305, 6270.968645156928], "value": 5117.828453476838, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.8539077064558, 384.7479900665064, 389.0875896023825, 398.4163200910666, 401.1695789715289, 5221.634485586011, 5242.7640756165965, 5269.973650131744, 5327.840164795052, 5376.059189641816, 4446.822127114911, 4957.611868676768, 5119.539241468267, 5184.455632158626, 5205.7136857472915, 4260.8721738347895, 4390.363397307388, 4398.780060996937, 4407.845725399612, 4463.330500424938, 3818.599047100056, 4388.319810429372, 4399.560043995591, 4456.90805752591, 4465.330200469939, 3604.6395360464007, 3725.1618385863235, 3920.1571662092642, 3927.725059245854, 4447.399601053865, 4280.614744672963, 4325.351197320396, 4441.644668426518, 4452.17634737573, 4492.707973981681, 3420.9486857697157, 3533.9646603533893, 3820.9301395609273, 3989.261986532501, 4065.3698676705158, 4015.5382131054716, 4347.152615037156, 4420.557944205586, 5047.778977249998, 5126.307948426952, 4299.957000429987, 4729.196036673774, 4993.827684957056, 5051.81282398636, 5097.037640508393, 4564.589186973155, 5068.746562671851, 5206.37523497179, 5488.984537860469, 5659.292588426447, 4034.031832360242, 4208.473940757405, 4490.797914093873, 4491.6182124519455, 4516.390287311204, 4771.880702982418, 5171.456997015334, 5721.628094173865, 5978.611390259619, 6005.3394126646035, 3400.42192827215, 4123.783483872265, 4128.38074288853, 4378.1541997701115, 4396.230659634552, 4712.269598212282, 4727.454409193105, 4733.254712266877, 4745.4577262389175, 4747.525247475294, 4338.110687309094, 4926.975365123151, 4993.626752643052, 5014.373203349582, 5073.771680274396, 5365.8000219909645, 5588.3573389060775, 5616.9327827712605, 5656.321241451053, 5806.2451881255165, 1352.1481466676128, 1474.1499418230267, 1565.6399028223573, 1575.2911189964561, 1587.484067677986, 1039.838824982129, 1125.1729978465821, 1176.8823117688162, 1191.7497325561555, 1205.1019644060639, 4745.810167593327, 4994.751312171957, 5084.720340381315, 5264.763085661154, 5347.598930080259, 5101.913342427852, 5434.711999240481, 5470.56235501159, 5722.685252311163, 5831.271091113611, 4796.496367881322, 4805.663603547788, 5165.845024649267, 5222.869428264324, 5307.840764777063, 2531.9240422787348, 2604.4140068484817, 2657.5615023520904, 2829.63214782077, 3011.472992226352, 5598.50866364472, 5813.8662960723095, 6074.385740484405, 6090.786822461193, 6107.358727333566, 5593.909187708357, 5603.842520629505, 5823.272090988627, 5990.7304171311525, 6048.401201285228, 5287.995280896617, 5475.890482190334, 5545.201536947204, 5930.495907847881, 5960.612560183574, 5037.496250374933, 5410.864728381822, 5646.322441307069, 5693.9430605693415, 5724.885502289848, 4957.702537847741, 5255.185446255837, 5519.151084386775, 5537.373020671466, 5715.742536641932, 5184.714840683799, 5323.26229655599, 5413.729313534386, 5436.510714035756, 6125.571210015256, 4389.934150987801, 4503.2569626012655, 4523.168116912656, 4561.429821272341, 4563.863084107482, 4929.531694489017, 4939.481354457874, 5491.716461160301, 5831.504322132497, 5876.030454974882, 4646.558576935186, 5374.946250537601, 5559.944400555943, 5579.497845193952, 5649.887002260014, 4795.424549054136, 5034.219695947134, 5441.5374693151525, 5565.16522521625, 5602.355729091207, 4595.494495605548, 4740.0, 5156.20078887773, 5487.780488780404, 5495.966492397679, 4650.488446270975, 5588.217649528988, 5637.3235211774845, 5684.403137670485, 5699.746055867702, 5234.947650523598, 5301.655392399482, 5386.730663466889, 5522.420145884785, 5541.030319694118], "value": 4246.955712926698, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.2741740511701, 758.5031804168268, 773.1997382708897, 818.2186012358196, 822.1572887790014, 14588.854111458882, 15296.770548441767, 15311.310991005405, 17199.570010749736, 18023.36918207863, 16491.0, 16541.83458165418, 16579.00525968443, 16595.751063734053, 16650.83349166508, 930.9255259579252, 962.9807403851954, 969.8351280282369, 970.9854352184723, 972.9318947673665, 778.3889646627399, 953.0897992417259, 953.1469334945219, 954.1889394015101, 955.6655170690237, 934.728928610702, 945.5438624518268, 1000.3797645459825, 1018.3024628129755, 1021.101430740947, 1012.5190534496129, 1012.6607586458546, 1014.6043043213152, 1015.5886865819355, 1020.1991436722175, 754.2758951406656, 759.092884003616, 759.142169348638, 762.0246085011195, 766.486454075765, 15723.606909827231, 15728.213589320489, 15982.041077535385, 16108.75836862448, 16120.032798032156, 14047.43810247589, 14081.366338514792, 14151.644583626512, 14177.929110354382, 14208.644783880382, 11749.412529373583, 11823.11326650496, 11906.58326958561, 12045.518179272818, 12153.453094610764, 3602.1534939289245, 3643.333680034474, 3644.1982763791925, 3653.799041052741, 3713.7771733695795, 110565.44717276443, 112841.0, 112876.43561782221, 115902.0, 117106.0, 10778.461076946121, 10994.395308257963, 11021.338719676685, 11155.163362747826, 11160.16298777595, 21932.561348772935, 22575.43561410945, 22596.887015565146, 22640.433989150402, 22641.886790565943, 7638.0, 7721.6139193041245, 7728.690852365955, 7899.091604465445, 8000.119986801447, 4235.839721741934, 4269.338252570857, 4291.399204111427, 4383.64589584996, 4415.227335216325, 3462.4113900636953, 3552.626974167675, 3563.429851223792, 3564.3762341590636, 3566.037169964095, 549.365482867289, 630.6027202862167, 640.5388120553195, 647.5758378262152, 648.1315037849305, 534.4281618667987, 645.3675398109879, 669.7120238297481, 676.6481429656551, 695.7356204642305, 9149.862752058725, 9485.430874147572, 10022.599096036223, 10144.391336519684, 10660.200484963503, 528.3395755305868, 537.2398056749734, 582.4175824175824, 628.7893555658814, 689.269285427798, 5245.921311180336, 5363.919541206885, 5384.246205531229, 5557.305336832896, 5588.776448941997, 101110.0, 104249.95750042555, 105373.47313263538, 105991.4700426493, 108645.45677271562, 89468.10531894727, 92441.07558924459, 94885.5255723717, 95991.0, 96009.55985660221, 78111.4377712454, 80444.78336498272, 81151.97120072045, 81425.0, 82868.58565707132, 10606.204534660086, 10851.240413171003, 11184.273022253528, 11185.552577896793, 11216.66350009501, 47997.520024799305, 51485.45543633697, 51698.74150629373, 51763.48236517586, 52780.20829687634, 49858.0, 66744.6662766683, 66769.66615166893, 67015.65968680696, 67602.0, 11972.820407693718, 12104.515819367127, 12238.986598418482, 12267.509299627915, 12330.938345308576, 11880.0, 14195.858041419453, 14337.85662143407, 14633.487827925972, 15122.848771512585, 13413.195208287534, 13769.449222031408, 14149.646258843612, 14216.502422415166, 14461.638459038608, 13482.595522134352, 13580.100094148118, 13671.589852304447, 14449.0, 14471.638209044859, 13248.668783280495, 13402.463901443833, 13742.93128534351, 13747.656308592364, 13950.720985580434, 8160.30637395828, 9188.264938805014, 9216.30877684163, 9357.485338306458, 9783.510824458606, 11069.225154239448, 11375.033122184706, 11429.485673144894, 11466.2546934449, 11655.30068195911], "value": 7696.509442868739, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1414.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 117.0, 115.0, 114.0, 115.0, 115.0, 116.0, 116.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 108.0, 140.0, 111.0, 109.0, 115.0, 107.0, 127.0, 109.0], "value": 117.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 185.0, 184.0, 184.0, 180.0, 182.0, 182.0, 193.0, 260.0, 206.0], "value": 194.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 91.0, 61.0, 63.0, 60.0, 58.0, 76.0, 59.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 88.0, 70.0, 69.0, 68.0, 81.0, 68.0, 68.0, 82.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 86.0, 86.0, 87.0, 87.0, 87.0, 86.0, 86.0, 86.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 92.0, 95.0, 94.0, 95.0, 93.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 80.0, 82.0, 86.0, 83.0, 96.0, 114.0, 86.0], "value": 88.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 62.0, 62.0, 65.0, 64.0, 65.0, 64.0, 64.0, 64.0], "value": 63.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 56.0, 63.0, 60.0, 57.0, 59.0, 59.0, 58.0, 59.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 82.0, 80.0, 82.0, 82.0, 81.0, 82.0, 79.0, 79.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 137.0, 132.0, 133.0, 144.0, 152.0, 140.0, 184.0, 137.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 165.0, 166.0, 165.0, 164.0, 176.0, 162.0, 161.0, 164.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 65.0, 64.0, 65.0, 64.0, 63.0, 66.0, 64.0, 63.0], "value": 63.8, "unit": "score"}]}]} 02:39:07 INFO - Return code: 0 02:39:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:39:07 ERROR - # TBPL SUCCESS # 02:39:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:39:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:39:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:39:07 INFO - 2017-04-09 02:39:07.906 screenresolution[1659:10070] starting screenresolution argv=screenresolution get 02:39:07 INFO - 2017-04-09 02:39:07.909 screenresolution[1659:10070] Display 0: 1600x1200x32@60 02:39:07 INFO - 2017-04-09 02:39:07.915 screenresolution[1660:10073] starting screenresolution argv=screenresolution list 02:39:07 INFO - Available Modes on Display 0 02:39:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:39:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:39:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:39:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:39:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:39:08 INFO - Intel Iris: 02:39:08 INFO - Chipset Model: Intel Iris 02:39:08 INFO - Type: GPU 02:39:08 INFO - Bus: Built-In 02:39:08 INFO - VRAM (Dynamic, Max): 1536 MB 02:39:08 INFO - Vendor: Intel (0x8086) 02:39:08 INFO - Device ID: 0x0a2e 02:39:08 INFO - Revision ID: 0x0009 02:39:08 INFO - Displays: 02:39:08 INFO - Display: 02:39:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:39:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:39:08 INFO - Display Serial Number: 1600x1200 60 02:39:08 INFO - Main Display: Yes 02:39:08 INFO - Mirror: Off 02:39:08 INFO - Online: Yes 02:39:08 INFO - Rotation: Supported 02:39:08 INFO - Return code: 0 02:39:08 INFO - Running post-action listener: _resource_record_post_action 02:39:08 INFO - [mozharness: 2017-04-09 09:39:08.147659Z] Finished run-tests step (success) 02:39:08 INFO - Running post-run listener: _resource_record_post_run 02:39:08 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 32738304; Write bytes: 512306176; Read time: 196; Write time: 1648 02:39:08 INFO - TinderboxPrint: CPU usage
53.9% 02:39:08 INFO - TinderboxPrint: I/O read bytes / time
32,738,304 / 196 02:39:08 INFO - TinderboxPrint: I/O write bytes / time
512,306,176 / 1,648 02:39:08 INFO - TinderboxPrint: CPU idle
2,215.8 (46.1%) 02:39:08 INFO - TinderboxPrint: CPU system
955.5 (19.9%) 02:39:08 INFO - TinderboxPrint: CPU user
1,630.5 (34.0%) 02:39:08 INFO - TinderboxPrint: Swap in / out
236,625,920 / 0 02:39:08 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203823616; Write bytes: 199286784; Read time: 15629; Write time: 444 02:39:08 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30755840; Write bytes: 302226432; Read time: 177; Write time: 1172 02:39:08 INFO - Running post-run listener: _upload_blobber_files 02:39:08 INFO - Blob upload gear active. 02:39:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:39:08 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 02:39:08 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:39:08 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:39:09 INFO - (blobuploader) - INFO - Open directory for files ... 02:39:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:39:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:39:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:10 INFO - (blobuploader) - INFO - Done attempting. 02:39:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:39:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:39:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:11 INFO - (blobuploader) - INFO - Done attempting. 02:39:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:39:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:39:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:12 INFO - (blobuploader) - INFO - Done attempting. 02:39:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:39:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:39:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:14 INFO - (blobuploader) - INFO - Done attempting. 02:39:14 INFO - (blobuploader) - INFO - Iteration through files over. 02:39:14 INFO - Return code: 0 02:39:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:39:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:39:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3da3f636ad5fbf223763a20370d4e2eb22f3b91ac0f572059321f9455452f04595ae8601aeaa00e16eeee09d934691d820e898f99baa1816693f815c4cad4d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ccd220a714392ac9f3af493b06e08365a30d490087a90b75af07d4f96f4cc5c6edc14a22269993a66136c1781e5664fb1f8ac915e6693c3caf6f076837c6e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3e43b4b19b48508f9c790d706fe5066eecc07d1244120caae4ee8efe4604865ee30137528607f8838634c96185004e83f52a6bab8c16b3350aa2155db9f3859", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daefbabd2c41d8c0b83d125069499ccdfdc14643d3187e6f69524b69860bc1ba0b76156437e540a701fd0f2cfae4537544d7bd5796bf967e2d2bf13921bbd4bc"} 02:39:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:39:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:39:14 INFO - Contents: 02:39:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3da3f636ad5fbf223763a20370d4e2eb22f3b91ac0f572059321f9455452f04595ae8601aeaa00e16eeee09d934691d820e898f99baa1816693f815c4cad4d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ccd220a714392ac9f3af493b06e08365a30d490087a90b75af07d4f96f4cc5c6edc14a22269993a66136c1781e5664fb1f8ac915e6693c3caf6f076837c6e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3e43b4b19b48508f9c790d706fe5066eecc07d1244120caae4ee8efe4604865ee30137528607f8838634c96185004e83f52a6bab8c16b3350aa2155db9f3859", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daefbabd2c41d8c0b83d125069499ccdfdc14643d3187e6f69524b69860bc1ba0b76156437e540a701fd0f2cfae4537544d7bd5796bf967e2d2bf13921bbd4bc"} 02:39:14 INFO - Running post-run listener: copy_logs_to_upload_dir 02:39:14 INFO - Copying logs to upload dir... 02:39:14 INFO - mkdir: /builds/slave/test/build/upload/logs 02:39:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1301.790123 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 41 secs) (at 2017-04-09 02:39:14.437392) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-09 02:39:14.440977) ========= 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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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/Mozilla-Aurora/sha512/d3da3f636ad5fbf223763a20370d4e2eb22f3b91ac0f572059321f9455452f04595ae8601aeaa00e16eeee09d934691d820e898f99baa1816693f815c4cad4d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ccd220a714392ac9f3af493b06e08365a30d490087a90b75af07d4f96f4cc5c6edc14a22269993a66136c1781e5664fb1f8ac915e6693c3caf6f076837c6e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3e43b4b19b48508f9c790d706fe5066eecc07d1244120caae4ee8efe4604865ee30137528607f8838634c96185004e83f52a6bab8c16b3350aa2155db9f3859", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daefbabd2c41d8c0b83d125069499ccdfdc14643d3187e6f69524b69860bc1ba0b76156437e540a701fd0f2cfae4537544d7bd5796bf967e2d2bf13921bbd4bc"} build_url:https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011205 build_url: 'https://queue.taskcluster.net/v1/task/c14B8KBcQ5OkHjnrXCqrbw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3da3f636ad5fbf223763a20370d4e2eb22f3b91ac0f572059321f9455452f04595ae8601aeaa00e16eeee09d934691d820e898f99baa1816693f815c4cad4d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ccd220a714392ac9f3af493b06e08365a30d490087a90b75af07d4f96f4cc5c6edc14a22269993a66136c1781e5664fb1f8ac915e6693c3caf6f076837c6e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c3e43b4b19b48508f9c790d706fe5066eecc07d1244120caae4ee8efe4604865ee30137528607f8838634c96185004e83f52a6bab8c16b3350aa2155db9f3859", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daefbabd2c41d8c0b83d125069499ccdfdc14643d3187e6f69524b69860bc1ba0b76156437e540a701fd0f2cfae4537544d7bd5796bf967e2d2bf13921bbd4bc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-09 02:39:14.475476) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 02:39:14.475860) ========= 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.aJ8PnfxsPi/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.KnhxwiIwkj/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.005201 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 02:39:14.524776) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-09 02:39:14.525147) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-09 02:39:14.525581) ========= ========= Total master_lag: 1.05 =========