builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0123 starttime: 1486387185.1 results: success (0) buildid: 20170206035550 builduid: 2f9275bb877d41eb957bf3029f968927 revision: c5621cb6f9075d731ccb28dcf20dd29deae2bddd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:45.097890) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:45.098346) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:45.098626) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:45.125033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:45.125411) ========= 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.gHyPyQyWiK/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.3nUOcVkq49/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-06 05:19:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 151M=0s 2017-02-06 05:19:45 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.172691 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:45.322323) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:45.322635) ========= 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.gHyPyQyWiK/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.3nUOcVkq49/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.026393 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:45.371353) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-06 05:19:45.371698) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c5621cb6f9075d731ccb28dcf20dd29deae2bddd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c5621cb6f9075d731ccb28dcf20dd29deae2bddd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gHyPyQyWiK/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.3nUOcVkq49/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-06 05:19:45,475 truncating revision to first 12 chars 2017-02-06 05:19:45,475 Setting DEBUG logging. 2017-02-06 05:19:45,475 attempt 1/10 2017-02-06 05:19:45,475 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c5621cb6f907?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-06 05:19:47,187 unpacking tar archive at: mozilla-central-c5621cb6f907/testing/mozharness/ program finished with exit code 0 elapsedTime=1.980670 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-06 05:19:47.376247) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:47.376573) ========= script_repo_revision: c5621cb6f9075d731ccb28dcf20dd29deae2bddd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:47.376986) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:47.377264) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 05:19:47.397632) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-06 05:19:47.398154) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gHyPyQyWiK/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.3nUOcVkq49/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 05:19:47 INFO - MultiFileLogger online at 20170206 05:19:47 in /builds/slave/test 05:19:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:19:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:19:47 INFO - {'append_to_log': False, 05:19:47 INFO - 'base_work_dir': '/builds/slave/test', 05:19:47 INFO - 'blob_upload_branch': 'Firefox', 05:19:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:19:47 INFO - 'branch': 'Firefox', 05:19:47 INFO - 'buildbot_json_path': 'buildprops.json', 05:19:47 INFO - 'config_files': ('talos/mac_config.py',), 05:19:47 INFO - 'default_actions': ('clobber', 05:19:47 INFO - 'read-buildbot-config', 05:19:47 INFO - 'download-and-extract', 05:19:47 INFO - 'populate-webroot', 05:19:47 INFO - 'create-virtualenv', 05:19:47 INFO - 'install', 05:19:47 INFO - 'run-tests'), 05:19:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:19:47 INFO - 'download_minidump_stackwalk': True, 05:19:47 INFO - 'download_symbols': 'ondemand', 05:19:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:19:47 INFO - 'tooltool.py': '/tools/tooltool.py', 05:19:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:19:47 INFO - '/tools/misc-python/virtualenv.py')}, 05:19:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:19:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:19:47 INFO - 'installer_path': 'installer.exe', 05:19:47 INFO - 'log_level': 'info', 05:19:47 INFO - 'log_name': 'talos', 05:19:47 INFO - 'log_to_console': True, 05:19:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:19:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:19:47 INFO - 'opt_config_files': (), 05:19:47 INFO - 'pip_index': False, 05:19:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:19:47 INFO - 'cmd': ('bash', 05:19:47 INFO - '-c', 05:19:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:19:47 INFO - 'enabled': True, 05:19:47 INFO - 'halt_on_failure': False, 05:19:47 INFO - 'name': 'check_screen_resolution'},), 05:19:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:19:47 INFO - 'cmd': ('bash', 05:19:47 INFO - '-c', 05:19:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:19:47 INFO - 'enabled': True, 05:19:47 INFO - 'halt_on_failure': False, 05:19:47 INFO - 'name': 'check_screen_resolution'},), 05:19:47 INFO - 'run_cmd_checks_enabled': True, 05:19:47 INFO - 'sps_profile': False, 05:19:47 INFO - 'sps_profile_interval': 0, 05:19:47 INFO - 'suite': 'dromaeojs', 05:19:47 INFO - 'system_bits': '32', 05:19:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:19:47 INFO - 'title': 't-yosemite-r7-0123', 05:19:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:19:47 INFO - 'use_talos_json': True, 05:19:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:19:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:19:47 INFO - 'work_dir': 'build'} 05:19:47 INFO - [mozharness: 2017-02-06 13:19:47.616056Z] Running clobber step. 05:19:47 INFO - Running pre-action listener: _resource_record_pre_action 05:19:47 INFO - Running main action method: clobber 05:19:47 INFO - rmtree: /builds/slave/test/build 05:19:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:19:50 INFO - Running post-action listener: _resource_record_post_action 05:19:50 INFO - [mozharness: 2017-02-06 13:19:50.141565Z] Finished clobber step (success) 05:19:50 INFO - [mozharness: 2017-02-06 13:19:50.141713Z] Running read-buildbot-config step. 05:19:50 INFO - Running pre-action listener: _resource_record_pre_action 05:19:50 INFO - Running main action method: read_buildbot_config 05:19:50 INFO - Using buildbot properties: 05:19:50 INFO - { 05:19:50 INFO - "project": "", 05:19:50 INFO - "product": "firefox", 05:19:50 INFO - "script_repo_revision": "production", 05:19:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:19:50 INFO - "repository": "", 05:19:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:19:50 INFO - "buildid": "20170206035550", 05:19:50 INFO - "pgo_build": "False", 05:19:50 INFO - "basedir": "/builds/slave/test", 05:19:50 INFO - "buildnumber": 29, 05:19:50 INFO - "slavename": "t-yosemite-r7-0123", 05:19:50 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:19:50 INFO - "platform": "yosemite_r7", 05:19:50 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:19:50 INFO - "branch": "mozilla-central", 05:19:50 INFO - "repo_path": "mozilla-central", 05:19:50 INFO - "revision": "c5621cb6f9075d731ccb28dcf20dd29deae2bddd", 05:19:50 INFO - "stage_platform": "macosx64", 05:19:50 INFO - "builduid": "2f9275bb877d41eb957bf3029f968927", 05:19:50 INFO - "slavebuilddir": "test" 05:19:50 INFO - } 05:19:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 05:19:50 INFO - Running post-action listener: _resource_record_post_action 05:19:50 INFO - [mozharness: 2017-02-06 13:19:50.145221Z] Finished read-buildbot-config step (success) 05:19:50 INFO - [mozharness: 2017-02-06 13:19:50.145360Z] Running download-and-extract step. 05:19:50 INFO - Running pre-action listener: _resource_record_pre_action 05:19:50 INFO - Running main action method: download_and_extract 05:19:50 INFO - mkdir: /builds/slave/test/build/tests 05:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:50 INFO - trying https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 05:19:50 INFO - Downloading https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 05:19:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 05:19:51 INFO - Downloaded 1199 bytes. 05:19:51 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 05:19:51 INFO - Using the following test package requirements: 05:19:51 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 05:19:51 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:19:51 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 05:19:51 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:19:51 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 05:19:51 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:19:51 INFO - u'jsshell-mac.zip'], 05:19:51 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:19:51 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 05:19:51 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 05:19:51 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:19:51 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 05:19:51 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:19:51 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 05:19:51 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:19:51 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 05:19:51 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:19:51 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 05:19:51 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:19:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 05:19:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:19:51 INFO - Fetch https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 05:19:52 INFO - Expected file size: 34850966 05:19:52 INFO - Obtained file size: 34850966 05:19:59 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:19:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 05:19:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:19:59 INFO - Fetch https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 05:20:00 INFO - Expected file size: 34850966 05:20:00 INFO - Obtained file size: 34850966 05:20:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 05:20:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:20:07 INFO - Fetch https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 05:20:08 INFO - Expected file size: 14403454 05:20:08 INFO - Obtained file size: 14403454 05:20:08 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')]} 05:20:08 INFO - trying https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:20:08 INFO - Downloading https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 05:20:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 05:20:10 INFO - Downloaded 70251764 bytes. 05:20:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:20:10 INFO - mkdir: /builds/slave/test/properties 05:20:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:20:10 INFO - Writing to file /builds/slave/test/properties/build_url 05:20:10 INFO - Contents: 05:20:10 INFO - build_url:https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:20:10 INFO - Running post-action listener: _resource_record_post_action 05:20:10 INFO - Running post-action listener: set_extra_try_arguments 05:20:10 INFO - [mozharness: 2017-02-06 13:20:10.991397Z] Finished download-and-extract step (success) 05:20:10 INFO - [mozharness: 2017-02-06 13:20:10.991519Z] Running populate-webroot step. 05:20:10 INFO - Running pre-action listener: _resource_record_pre_action 05:20:10 INFO - Running main action method: populate_webroot 05:20:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:20:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:20:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:20:10 INFO - '--tppagecycles', 05:20:11 INFO - '7'], 05:20:11 INFO - 'tests': ['tsvgm']}}, 05:20:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:20:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:20:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:20:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:20:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:20:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:20:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:20:11 INFO - 'talos_options': ['--disable-e10s'], 05:20:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:20:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:20:11 INFO - 'talos_options': ['--disable-e10s'], 05:20:11 INFO - 'tests': ['damp', 'tps']}, 05:20:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:20:11 INFO - 'tests': ['damp', 'tps']}, 05:20:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:20:11 INFO - 'tests': ['dromaeo_dom']}, 05:20:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:20:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:20:11 INFO - 'tests': ['basic_compositor_video']}, 05:20:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:20:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:20:11 INFO - 'tests': ['a11yr', 05:20:11 INFO - 'ts_paint', 05:20:11 INFO - 'tpaint', 05:20:11 INFO - 'sessionrestore', 05:20:11 INFO - 'sessionrestore_no_auto_restore', 05:20:11 INFO - 'tabpaint']}, 05:20:11 INFO - 'other-e10s': {'tests': ['a11yr', 05:20:11 INFO - 'ts_paint', 05:20:11 INFO - 'tpaint', 05:20:11 INFO - 'sessionrestore', 05:20:11 INFO - 'sessionrestore_no_auto_restore', 05:20:11 INFO - 'tabpaint']}, 05:20:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:20:11 INFO - 'ts_paint', 05:20:11 INFO - 'tpaint', 05:20:11 INFO - 'sessionrestore', 05:20:11 INFO - 'sessionrestore_no_auto_restore', 05:20:11 INFO - 'tabpaint']}, 05:20:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:20:11 INFO - 'ts_paint', 05:20:11 INFO - 'tpaint', 05:20:11 INFO - 'sessionrestore', 05:20:11 INFO - 'sessionrestore_no_auto_restore', 05:20:11 INFO - 'tabpaint']}, 05:20:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:20:11 INFO - 'tests': ['a11yr', 05:20:11 INFO - 'ts_paint', 05:20:11 INFO - 'tpaint', 05:20:11 INFO - 'sessionrestore', 05:20:11 INFO - 'sessionrestore_no_auto_restore', 05:20:11 INFO - 'tabpaint']}, 05:20:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:20:11 INFO - 'tests': ['a11yr', 05:20:11 INFO - 'ts_paint', 05:20:11 INFO - 'tpaint', 05:20:11 INFO - 'sessionrestore', 05:20:11 INFO - 'sessionrestore_no_auto_restore', 05:20:11 INFO - 'tabpaint']}, 05:20:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:20:11 INFO - 'tests': ['tsvgx', 05:20:11 INFO - 'tsvgr_opacity', 05:20:11 INFO - 'tart', 05:20:11 INFO - 'tscrollx', 05:20:11 INFO - 'cart', 05:20:11 INFO - 'tsvg_static']}, 05:20:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:20:11 INFO - 'tsvgr_opacity', 05:20:11 INFO - 'tart', 05:20:11 INFO - 'tscrollx', 05:20:11 INFO - 'cart', 05:20:11 INFO - 'tsvg_static']}, 05:20:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:20:11 INFO - 'talos_options': ['--disable-e10s'], 05:20:11 INFO - 'tests': ['tp5o']}, 05:20:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:20:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:20:11 INFO - 'talos_options': ['--disable-e10s', 05:20:11 INFO - '--xperf_path', 05:20:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:11 INFO - 'tests': ['tp5n']}, 05:20:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:20:11 INFO - 'talos_options': ['--xperf_path', 05:20:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:11 INFO - 'tests': ['tp5n']}}, 05:20:11 INFO - 'talos.zip': {'path': '', 05:20:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:20:11 INFO - Running post-action listener: _resource_record_post_action 05:20:11 INFO - [mozharness: 2017-02-06 13:20:11.009984Z] Finished populate-webroot step (success) 05:20:11 INFO - [mozharness: 2017-02-06 13:20:11.010094Z] Running create-virtualenv step. 05:20:11 INFO - Running pre-action listener: _resource_record_pre_action 05:20:11 INFO - Running main action method: create_virtualenv 05:20:11 INFO - Creating virtualenv /builds/slave/test/build/venv 05:20:11 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 05:20:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:20:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:20:11 INFO - Using real prefix '/tools/python27' 05:20:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:20:11 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:14 INFO - Installing pip.................done. 05:20:14 INFO - Return code: 0 05:20:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:20:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:20:15 INFO - Reading from file tmpfile_stdout 05:20:15 INFO - Output received: 05:20:15 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) 05:20:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:20:15 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')]} 05:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:15 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')]} 05:20:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:15 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 0x100933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e156a620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100711d30>, '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 0x100638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100706be8>, '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.gHyPyQyWiK/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.3nUOcVkq49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 05:20:15 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 05:20:15 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 05:20:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHyPyQyWiK/Render', 05:20:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:15 INFO - 'HOME': '/Users/cltbld', 05:20:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:15 INFO - 'LOGNAME': 'cltbld', 05:20:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:15 INFO - 'MOZ_NO_REMOTE': '1', 05:20:15 INFO - 'NO_EM_RESTART': '1', 05:20:15 INFO - 'PAGER': '/bin/cat', 05:20:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:15 INFO - 'PWD': '/builds/slave/test', 05:20:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:15 INFO - 'SHELL': '/bin/bash', 05:20:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3nUOcVkq49/Listeners', 05:20:15 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:20:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:15 INFO - 'USER': 'cltbld', 05:20:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:15 INFO - 'XPC_FLAGS': '0x0', 05:20:15 INFO - 'XPC_SERVICE_NAME': '0', 05:20:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:15 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 05:20:15 INFO - Cleaning up... 05:20:15 INFO - Return code: 0 05:20:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:20:15 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')]} 05:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:15 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')]} 05:20:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:15 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 0x100933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e156a620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100711d30>, '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 0x100638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100706be8>, '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.gHyPyQyWiK/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.3nUOcVkq49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 05:20:15 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 05:20:15 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 05:20:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHyPyQyWiK/Render', 05:20:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:15 INFO - 'HOME': '/Users/cltbld', 05:20:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:15 INFO - 'LOGNAME': 'cltbld', 05:20:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:15 INFO - 'MOZ_NO_REMOTE': '1', 05:20:15 INFO - 'NO_EM_RESTART': '1', 05:20:15 INFO - 'PAGER': '/bin/cat', 05:20:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:15 INFO - 'PWD': '/builds/slave/test', 05:20:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:15 INFO - 'SHELL': '/bin/bash', 05:20:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3nUOcVkq49/Listeners', 05:20:15 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:20:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:15 INFO - 'USER': 'cltbld', 05:20:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:15 INFO - 'XPC_FLAGS': '0x0', 05:20:15 INFO - 'XPC_SERVICE_NAME': '0', 05:20:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:15 INFO - Downloading/unpacking psutil>=3.1.1 05:20:15 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 05:20:15 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 05:20:15 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 05:20:15 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 05:20:15 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 05:20:15 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 05:20:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:19 INFO - Installing collected packages: psutil 05:20:19 INFO - Running setup.py install for psutil 05:20:19 INFO - building 'psutil._psutil_osx' extension 05:20:19 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 05:20:19 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 05:20:19 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 05:20:19 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 05:20:20 INFO - building 'psutil._psutil_posix' extension 05:20:20 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 05:20:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:20:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:20:20 INFO - ^ 05:20:20 INFO - 1 warning generated. 05:20:20 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 05:20:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:20 INFO - Successfully installed psutil 05:20:20 INFO - Cleaning up... 05:20:20 INFO - Return code: 0 05:20:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:20:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e156a620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100711d30>, '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 0x100638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100706be8>, '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.gHyPyQyWiK/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.3nUOcVkq49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 05:20:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:20:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:20:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHyPyQyWiK/Render', 05:20:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:20 INFO - 'HOME': '/Users/cltbld', 05:20:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:20 INFO - 'LOGNAME': 'cltbld', 05:20:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:20 INFO - 'MOZ_NO_REMOTE': '1', 05:20:20 INFO - 'NO_EM_RESTART': '1', 05:20:20 INFO - 'PAGER': '/bin/cat', 05:20:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:20 INFO - 'PWD': '/builds/slave/test', 05:20:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:20 INFO - 'SHELL': '/bin/bash', 05:20:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3nUOcVkq49/Listeners', 05:20:20 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:20:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:20 INFO - 'USER': 'cltbld', 05:20:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:20 INFO - 'XPC_FLAGS': '0x0', 05:20:20 INFO - 'XPC_SERVICE_NAME': '0', 05:20:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:20:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:20:23 INFO - Installing collected packages: mozsystemmonitor 05:20:23 INFO - Running setup.py install for mozsystemmonitor 05:20:23 INFO - Successfully installed mozsystemmonitor 05:20:23 INFO - Cleaning up... 05:20:23 INFO - Return code: 0 05:20:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e156a620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100711d30>, '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 0x100638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100706be8>, '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.gHyPyQyWiK/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.3nUOcVkq49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 05:20:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:20:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHyPyQyWiK/Render', 05:20:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:23 INFO - 'HOME': '/Users/cltbld', 05:20:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:23 INFO - 'LOGNAME': 'cltbld', 05:20:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:23 INFO - 'MOZ_NO_REMOTE': '1', 05:20:23 INFO - 'NO_EM_RESTART': '1', 05:20:23 INFO - 'PAGER': '/bin/cat', 05:20:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:23 INFO - 'PWD': '/builds/slave/test', 05:20:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:23 INFO - 'SHELL': '/bin/bash', 05:20:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3nUOcVkq49/Listeners', 05:20:23 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:20:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:23 INFO - 'USER': 'cltbld', 05:20:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:23 INFO - 'XPC_FLAGS': '0x0', 05:20:23 INFO - 'XPC_SERVICE_NAME': '0', 05:20:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:23 INFO - Downloading/unpacking jsonschema==2.5.1 05:20:23 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 05:20:23 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 05:20:23 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 05:20:23 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 05:20:23 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 05:20:23 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 05:20:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:26 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:26 INFO - warning: no files found matching '*.txt' 05:20:26 INFO - no previously-included directories found matching 'build' 05:20:26 INFO - no previously-included directories found matching 'dist' 05:20:26 INFO - no previously-included directories found matching '.git*' 05:20:26 INFO - Installing collected packages: jsonschema, functools32 05:20:27 INFO - Running setup.py install for functools32 05:20:27 INFO - warning: no files found matching '*.txt' 05:20:27 INFO - no previously-included directories found matching 'build' 05:20:27 INFO - no previously-included directories found matching 'dist' 05:20:27 INFO - no previously-included directories found matching '.git*' 05:20:27 INFO - Successfully installed jsonschema functools32 05:20:27 INFO - Cleaning up... 05:20:27 INFO - Return code: 0 05:20:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:27 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 0x100933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e156a620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100711d30>, '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 0x100638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100706be8>, '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.gHyPyQyWiK/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.3nUOcVkq49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 05:20:27 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 05:20:27 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 05:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHyPyQyWiK/Render', 05:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:27 INFO - 'HOME': '/Users/cltbld', 05:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:27 INFO - 'LOGNAME': 'cltbld', 05:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:27 INFO - 'MOZ_NO_REMOTE': '1', 05:20:27 INFO - 'NO_EM_RESTART': '1', 05:20:27 INFO - 'PAGER': '/bin/cat', 05:20:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:27 INFO - 'PWD': '/builds/slave/test', 05:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:27 INFO - 'SHELL': '/bin/bash', 05:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3nUOcVkq49/Listeners', 05:20:27 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:27 INFO - 'USER': 'cltbld', 05:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:27 INFO - 'XPC_FLAGS': '0x0', 05:20:27 INFO - 'XPC_SERVICE_NAME': '0', 05:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:20:27 INFO - Cleaning up... 05:20:27 INFO - Return code: 0 05:20:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:27 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 0x100933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e156a620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100711d30>, '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 0x100638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100706be8>, '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.gHyPyQyWiK/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.3nUOcVkq49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 05:20:27 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 05:20:27 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 05:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHyPyQyWiK/Render', 05:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:27 INFO - 'HOME': '/Users/cltbld', 05:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:27 INFO - 'LOGNAME': 'cltbld', 05:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:27 INFO - 'MOZ_NO_REMOTE': '1', 05:20:27 INFO - 'NO_EM_RESTART': '1', 05:20:27 INFO - 'PAGER': '/bin/cat', 05:20:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:27 INFO - 'PWD': '/builds/slave/test', 05:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:27 INFO - 'SHELL': '/bin/bash', 05:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3nUOcVkq49/Listeners', 05:20:27 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:27 INFO - 'USER': 'cltbld', 05:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:27 INFO - 'XPC_FLAGS': '0x0', 05:20:27 INFO - 'XPC_SERVICE_NAME': '0', 05:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:27 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:30 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:20:31 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 05:20:31 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 05:20:31 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 05:20:31 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 05:20:31 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 05:20:31 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 05:20:31 INFO - Downloading docopt-0.6.1.tar.gz 05:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:31 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:31 INFO - Running setup.py install for blobuploader 05:20:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:31 INFO - Running setup.py install for requests 05:20:32 INFO - Running setup.py install for docopt 05:20:32 INFO - Successfully installed blobuploader requests docopt 05:20:32 INFO - Cleaning up... 05:20:32 INFO - Return code: 0 05:20:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:32 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')]} 05:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:32 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')]} 05:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:32 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 0x100933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e156a620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100711d30>, '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 0x100638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100706be8>, '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.gHyPyQyWiK/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.3nUOcVkq49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 05:20:32 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 05:20:32 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 05:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHyPyQyWiK/Render', 05:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:32 INFO - 'HOME': '/Users/cltbld', 05:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:32 INFO - 'LOGNAME': 'cltbld', 05:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:32 INFO - 'MOZ_NO_REMOTE': '1', 05:20:32 INFO - 'NO_EM_RESTART': '1', 05:20:32 INFO - 'PAGER': '/bin/cat', 05:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:32 INFO - 'PWD': '/builds/slave/test', 05:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:32 INFO - 'SHELL': '/bin/bash', 05:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3nUOcVkq49/Listeners', 05:20:32 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:32 INFO - 'USER': 'cltbld', 05:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:32 INFO - 'XPC_FLAGS': '0x0', 05:20:32 INFO - 'XPC_SERVICE_NAME': '0', 05:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:32 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-UN1M5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:32 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-lNn4YK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-tXS7Mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-LK0yRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-T_rGhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-_4daep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-_HMJPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-P_Nmz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-6GL4Bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ZdqBJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-muHiQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:34 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-qDzwQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:34 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-XUp_AF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:34 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-rJkj6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:34 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-4fc4Sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:34 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-fH1_Ed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:34 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Zye5e7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:34 INFO - Running setup.py install for manifestparser 05:20:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:34 INFO - Running setup.py install for mozcrash 05:20:34 INFO - Running setup.py install for mozdebug 05:20:35 INFO - Running setup.py install for mozdevice 05:20:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozfile 05:20:35 INFO - Running setup.py install for mozhttpd 05:20:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozinfo 05:20:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozInstall 05:20:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozleak 05:20:35 INFO - Running setup.py install for mozlog 05:20:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Running setup.py install for moznetwork 05:20:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Running setup.py install for mozprocess 05:20:36 INFO - Running setup.py install for mozprofile 05:20:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Running setup.py install for mozrunner 05:20:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Running setup.py install for mozscreenshot 05:20:36 INFO - Running setup.py install for moztest 05:20:37 INFO - Running setup.py install for mozversion 05:20:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:20:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:20:37 INFO - Cleaning up... 05:20:37 INFO - Return code: 0 05:20:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:37 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')]} 05:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:37 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')]} 05:20:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:37 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 0x100933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e156a620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100711d30>, '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 0x100638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100706be8>, '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.gHyPyQyWiK/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.3nUOcVkq49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 05:20:37 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 05:20:37 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 05:20:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHyPyQyWiK/Render', 05:20:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:37 INFO - 'HOME': '/Users/cltbld', 05:20:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:37 INFO - 'LOGNAME': 'cltbld', 05:20:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:37 INFO - 'MOZ_NO_REMOTE': '1', 05:20:37 INFO - 'NO_EM_RESTART': '1', 05:20:37 INFO - 'PAGER': '/bin/cat', 05:20:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:37 INFO - 'PWD': '/builds/slave/test', 05:20:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:37 INFO - 'SHELL': '/bin/bash', 05:20:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3nUOcVkq49/Listeners', 05:20:37 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:20:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:37 INFO - 'USER': 'cltbld', 05:20:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:37 INFO - 'XPC_FLAGS': '0x0', 05:20:37 INFO - 'XPC_SERVICE_NAME': '0', 05:20:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:37 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-hhb9OP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:37 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-VbiJQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:37 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-yRAKY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:37 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-pa9jcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:37 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-88mS8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:38 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-aOn7bM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:38 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-VRfMNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:38 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-C5ySbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:38 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-BFsyE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:38 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-EMXAn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:38 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-j6WYpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:38 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-uOZX8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:38 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-3wEK8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:38 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-JPyKFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:39 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)) 05:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:39 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-AQyieZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:39 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)) 05:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:39 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Yi32St-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:39 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)) 05:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:39 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-sU7zdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:39 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)) 05:20:39 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)) 05:20:39 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)) 05:20:39 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)) 05:20:39 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)) 05:20:39 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)) 05:20:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:39 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 05:20:39 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 05:20:39 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 05:20:39 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 05:20:39 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 05:20:39 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 05:20:41 INFO - Downloading blessings-1.6.tar.gz 05:20:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:20:42 INFO - Installing collected packages: blessings 05:20:42 INFO - Running setup.py install for blessings 05:20:42 INFO - Successfully installed blessings 05:20:42 INFO - Cleaning up... 05:20:42 INFO - Return code: 0 05:20:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:20:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:42 INFO - Reading from file tmpfile_stdout 05:20:42 INFO - Current package versions: 05:20:42 INFO - blessings == 1.6 05:20:42 INFO - blobuploader == 1.2.4 05:20:42 INFO - docopt == 0.6.1 05:20:42 INFO - functools32 == 3.2.3-2 05:20:42 INFO - jsonschema == 2.5.1 05:20:42 INFO - manifestparser == 1.1 05:20:42 INFO - mozInstall == 1.12 05:20:42 INFO - mozcrash == 1.0 05:20:42 INFO - mozdebug == 0.1 05:20:42 INFO - mozdevice == 0.49 05:20:42 INFO - mozfile == 1.2 05:20:42 INFO - mozhttpd == 0.7 05:20:42 INFO - mozinfo == 0.9 05:20:42 INFO - mozleak == 0.1 05:20:42 INFO - mozlog == 3.4 05:20:42 INFO - moznetwork == 0.27 05:20:42 INFO - mozprocess == 0.24 05:20:42 INFO - mozprofile == 0.28 05:20:42 INFO - mozrunner == 6.13 05:20:42 INFO - mozscreenshot == 0.1 05:20:42 INFO - mozsystemmonitor == 0.3 05:20:42 INFO - moztest == 0.8 05:20:42 INFO - mozversion == 1.4 05:20:42 INFO - psutil == 3.1.1 05:20:42 INFO - requests == 1.2.3 05:20:42 INFO - wsgiref == 0.1.2 05:20:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:42 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')]} 05:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:42 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')]} 05:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:43 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 0x100933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e156a620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100711d30>, '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 0x100638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100706be8>, '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.gHyPyQyWiK/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.3nUOcVkq49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 05:20:43 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 05:20:43 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 05:20:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHyPyQyWiK/Render', 05:20:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:43 INFO - 'HOME': '/Users/cltbld', 05:20:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:43 INFO - 'LOGNAME': 'cltbld', 05:20:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:43 INFO - 'MOZ_NO_REMOTE': '1', 05:20:43 INFO - 'NO_EM_RESTART': '1', 05:20:43 INFO - 'PAGER': '/bin/cat', 05:20:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:43 INFO - 'PWD': '/builds/slave/test', 05:20:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:43 INFO - 'SHELL': '/bin/bash', 05:20:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3nUOcVkq49/Listeners', 05:20:43 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:20:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:43 INFO - 'USER': 'cltbld', 05:20:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:43 INFO - 'XPC_FLAGS': '0x0', 05:20:43 INFO - 'XPC_SERVICE_NAME': '0', 05:20:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:43 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)) 05:20:43 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)) 05:20:43 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)) 05:20:43 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)) 05:20:43 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)) 05:20:43 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)) 05:20:43 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)) 05:20:43 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)) 05:20:43 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)) 05:20:43 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)) 05:20:43 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)) 05:20:43 INFO - Cleaning up... 05:20:43 INFO - Return code: 0 05:20:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100933b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100936768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8e156a620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100711d30>, '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 0x100638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100706be8>, '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.gHyPyQyWiK/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.3nUOcVkq49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 05:20: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', 'jsonschema'] in /builds/slave/test/build 05:20: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 jsonschema 05:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHyPyQyWiK/Render', 05:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:44 INFO - 'HOME': '/Users/cltbld', 05:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:44 INFO - 'LOGNAME': 'cltbld', 05:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:44 INFO - 'MOZ_NO_REMOTE': '1', 05:20:44 INFO - 'NO_EM_RESTART': '1', 05:20:44 INFO - 'PAGER': '/bin/cat', 05:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:44 INFO - 'PWD': '/builds/slave/test', 05:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:44 INFO - 'SHELL': '/bin/bash', 05:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3nUOcVkq49/Listeners', 05:20:44 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:44 INFO - 'USER': 'cltbld', 05:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:44 INFO - 'XPC_FLAGS': '0x0', 05:20:44 INFO - 'XPC_SERVICE_NAME': '0', 05:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:20:44 INFO - Cleaning up... 05:20:44 INFO - Return code: 0 05:20:44 INFO - Running post-action listener: _resource_record_post_action 05:20:44 INFO - Running post-action listener: _start_resource_monitoring 05:20:44 INFO - Starting resource monitoring. 05:20:44 INFO - [mozharness: 2017-02-06 13:20:44.300743Z] Finished create-virtualenv step (success) 05:20:44 INFO - [mozharness: 2017-02-06 13:20:44.301680Z] Running install step. 05:20:44 INFO - Running pre-action listener: _resource_record_pre_action 05:20:44 INFO - Running main action method: install 05:20:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:44 INFO - Reading from file tmpfile_stdout 05:20:44 INFO - Detecting whether we're running mozinstall >=1.0... 05:20:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:20:44 INFO - Reading from file tmpfile_stdout 05:20:44 INFO - Output received: 05:20:44 INFO - Usage: mozinstall [options] installer 05:20:44 INFO - Options: 05:20:44 INFO - -h, --help show this help message and exit 05:20:44 INFO - -d DEST, --destination=DEST 05:20:44 INFO - Directory to install application into. [default: 05:20:44 INFO - "/builds/slave/test"] 05:20:44 INFO - --app=APP Application being installed. [default: firefox] 05:20:44 INFO - mkdir: /builds/slave/test/build/application 05:20:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:21:06 INFO - Reading from file tmpfile_stdout 05:21:06 INFO - Output received: 05:21:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:21:06 INFO - Running post-action listener: _resource_record_post_action 05:21:06 INFO - [mozharness: 2017-02-06 13:21:06.771503Z] Finished install step (success) 05:21:06 INFO - [mozharness: 2017-02-06 13:21:06.771712Z] Running run-tests step. 05:21:06 INFO - Running pre-action listener: _resource_record_pre_action 05:21:06 INFO - Running main action method: run_tests 05:21:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:21:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:21:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:21:06 INFO - 2017-02-06 05:21:06.786 screenresolution[1631:7340] starting screenresolution argv=screenresolution get 05:21:06 INFO - 2017-02-06 05:21:06.789 screenresolution[1631:7340] Display 0: 1600x1200x32@60 05:21:06 INFO - 2017-02-06 05:21:06.795 screenresolution[1632:7342] starting screenresolution argv=screenresolution list 05:21:06 INFO - Available Modes on Display 0 05:21:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:21:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:21:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:21:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:21:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:21:07 INFO - Intel Iris: 05:21:07 INFO - Chipset Model: Intel Iris 05:21:07 INFO - Type: GPU 05:21:07 INFO - Bus: Built-In 05:21:07 INFO - VRAM (Dynamic, Max): 1536 MB 05:21:07 INFO - Vendor: Intel (0x8086) 05:21:07 INFO - Device ID: 0x0a2e 05:21:07 INFO - Revision ID: 0x0009 05:21:07 INFO - Displays: 05:21:07 INFO - Display: 05:21:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:21:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:21:07 INFO - Display Serial Number: 1600x1200 60 05:21:07 INFO - Main Display: Yes 05:21:07 INFO - Mirror: Off 05:21:07 INFO - Online: Yes 05:21:07 INFO - Rotation: Supported 05:21:07 INFO - Return code: 0 05:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:21:07 INFO - Python 2.7.3 05:21:07 INFO - Return code: 0 05:21:07 INFO - grabbing minidump binary from tooltool 05:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:07 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 0x100711d30>, '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 0x100638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100706be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:21:07 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 05:21:07 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 05:21:07 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 05:21:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:21:07 INFO - Return code: 0 05:21:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:21:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:21:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:21:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:21:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 05:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0123 --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 05:21:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHyPyQyWiK/Render', 05:21:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:07 INFO - 'HOME': '/Users/cltbld', 05:21:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:07 INFO - 'LOGNAME': 'cltbld', 05:21:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:21:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:07 INFO - 'MOZ_NO_REMOTE': '1', 05:21:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:21:07 INFO - 'NO_EM_RESTART': '1', 05:21:07 INFO - 'PAGER': '/bin/cat', 05:21:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:07 INFO - 'PWD': '/builds/slave/test', 05:21:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:21:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:07 INFO - 'SHELL': '/bin/bash', 05:21:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3nUOcVkq49/Listeners', 05:21:07 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 05:21:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:07 INFO - 'USER': 'cltbld', 05:21:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:07 INFO - 'XPC_FLAGS': '0x0', 05:21:07 INFO - 'XPC_SERVICE_NAME': '0', 05:21:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 05:21:07 INFO - mozversion application_buildid: 20170206035550 05:21:07 INFO - mozversion application_changeset: c5621cb6f9075d731ccb28dcf20dd29deae2bddd 05:21:07 INFO - mozversion application_display_name: Nightly 05:21:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:21:07 INFO - mozversion application_name: Firefox 05:21:07 INFO - mozversion application_remotingname: firefox 05:21:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:21:07 INFO - mozversion application_vendor: Mozilla 05:21:07 INFO - mozversion application_version: 54.0a1 05:21:07 INFO - mozversion platform_buildid: 20170206035550 05:21:07 INFO - mozversion platform_changeset: c5621cb6f9075d731ccb28dcf20dd29deae2bddd 05:21:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:21:07 INFO - mozversion platform_version: 54.0a1 05:21:07 INFO - using testdate: 1486387267 05:21:07 INFO - actual date: 1486387267 05:21:07 INFO - starting webserver on 'localhost:49235' 05:21:07 INFO - SUITE-START | Running 2 tests 05:21:07 INFO - TEST-START | dromaeo_css 05:21:07 INFO - operating with platform_type : mac_ 05:21:07 INFO - Initialising browser for dromaeo_css test... 05:21:07 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpn8qQEy/profile http://localhost:49235/getInfo.html) 05:21:09 INFO - PROCESS | 1642 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 05:21:10 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 05:21:10 INFO - PROCESS | 1642 | colorDepth:24 05:21:10 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 05:21:10 INFO - PROCESS | 1642 | __metrics 05:21:10 INFO - PROCESS | 1642 | 1486387270065 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:21:10 INFO - PROCESS | 1642 | 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" 05:21:10 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:21:10 INFO - TEST-INFO | 1642: exit 0 05:21:10 INFO - Browser initialized. 05:21:10 INFO - Running cycle 1/1 for dromaeo_css test... 05:21:10 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpn8qQEy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:22 INFO - PROCESS | 1644 | RSS: Main: 237023232 05:21:22 INFO - PROCESS | 1644 | 05:24:24 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 05:24:28 INFO - PROCESS | 1644 | RSS: Main: 496873472 05:24:28 INFO - PROCESS | 1644 | 05:26:12 INFO - PROCESS | 1644 | ************************* 05:26:12 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 05:26:12 INFO - PROCESS | 1644 | 05:26:12 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:26:12 INFO - PROCESS | 1644 | Full stack: 05:26:12 INFO - PROCESS | 1644 | ************************* 05:27:31 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 05:27:32 INFO - PROCESS | 1644 | RSS: Main: 375185408 05:27:32 INFO - PROCESS | 1644 | 05:30:34 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 05:30:35 INFO - PROCESS | 1644 | RSS: Main: 365481984 05:30:35 INFO - PROCESS | 1644 | 05:33:37 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 05:33:38 INFO - PROCESS | 1644 | RSS: Main: 338063360 05:33:38 INFO - PROCESS | 1644 | 05:36:41 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 05:36:42 INFO - PROCESS | 1644 | RSS: Main: 345288704 05:36:42 INFO - PROCESS | 1644 | 05:39:45 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 05:39:45 INFO - PROCESS | 1644 | RSS: Main: 582725632 05:39:45 INFO - PROCESS | 1644 | 05:39:45 INFO - PROCESS | 1644 | __start_tp_report 05:39:45 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 05:39:45 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 05:39:45 INFO - PROCESS | 1644 | |i|pagename|runs| 05:39:45 INFO - PROCESS | 1644 | |0;dojo.html;3230.612326520817;3237.3848968695947;3243.172990887324;3245.7727959042877;3294.407006738786;5366.812161574346;5462.344518657756;5477.835664930049;5531.778728850846;5579.330480342354;5217.799906021617;5475.069238229501;5490.588205884555;5530.198121272413;5555.083411237151;3653.9269214615756;4586.97706511467;4928.631942964998;4979.900401991958;5148.176291793314;4131.958680413188;5120.487951204868;5143.665661731976;5147.459516750743;5164.354455693038;4947.985662939107;4962.528559786814;4980.551750342451;4992.40091189056;5037.647364684473;5020.046191223656;5070.467600901926;5076.974615126937;5149.665271757323;5160.845174644766;4987;5014.674046186986;5061.240813877911;5080.923786143211;5098.286239926413;5350.759215835296;5382.838514844561;5578;5580.91628625571;5587.357453892813;5904.852378690567;5935.525157987394;5938.406159384071;6025.969870150621;6107.144999700045;6089.512838972872;6160.045192995048;6183.319834818167;6188.752449901998;6198.659073750942;5696.088625819849;5699.715014249313;5706.714664266811;5708.257926469532;5740.712964351766;6078.0882867569335;6081.543884208702;6105.938940610626;6233.688315584293;6303.684815759194;5839.591228614041;5851.736671849777;5874.706264686749;5915.674637894871;5966.522678185735;5124.743762811844;5611.494965453047;5767.077267637158;6181.907271390933;6183.567150299526;6003.669798161143;6074.362191969868;6083.8479038023515;6088.299845517734;6099.542534309944;5831.825045248649;5849.210356601804;6100.3899610039525;6122.877542449126;6132.294786099566;4770.236762118045;4808.495108013678;4810.759462026885;4829.468758436569;4887.560119589183;4286.164197981426;4329.935050974175;4362.342309922209;4379.364992076131;4405.493368262627;4956.330895329156;5567.498925096761;5612.326520817528;5612.747426365823;5631.915521267184;5950.821475355746;5984.581079324405;6005.978983572716;6222.253329600477;6247.843803904939;5558.082916318762;5577.274954256002;5607.831765047055;5650.802221922213;5674.00704876645;5332.386775520865;5332.866678333073;5335.1730481775385;5337.492938170945;5366.812161574326;6229.657368844757;6267.467265282502;6298.149749783904;6307;6319.905201421982;5937.020391635332;5979.611325263844;5981.431763982415;6153.13856060143;6177.4749146323065;6284.3087260402235;6320.5575609706875;6325.462335701332;6327.936720632735;6362.331955144643;6063.242094738158;6076.908846367396;6082.756689732538;6090.147379366804;6105.755769769159;5904;5933.970330148321;6094.7562097517375;6181.721822518088;6197.070439434121;4886.682365646222;5613.915791263053;5790.565707572033;5797.391273916347;5806.854828629318;5772.682502462405;5784.595078344475;5788.450097240755;5791.189233507228;5795.159701843293;5537.833864984057;5662.150677398423;5672.631278966854;5679.432056794288;5685.061964775931;5791.276090488689;5793.8551536211935;5796.971015144897;5805.796797112081;5816.1857339973285;5661.518770904519;5689.8862022758485;5691.117876729114;5694.886102277848;5696.886062278814 05:39:45 INFO - PROCESS | 1644 | |1;ext.html;455.20112203083585;481.561778781309;490.2278910715624;506.9036882992231;521.0464849325734;37298.627013729856;38648.42027369592;38884;39033.804830975874;40880.38679419811;39771.403428948586;39938.60061399393;40051;40081.799591002;40194.59805401945;7726.8454630907345;7928.960355198216;8848.36305283523;9063.09369063095;9067.183953444188;10528.68413947579;10796.190285728602;10829.350238985686;10864.130869530458;10932.180086493505;10754.677359679223;10825.917408259189;10830.945845270762;10835.29570577914;10854.837177442345;7703.422243331716;7919.802004949865;10670.413127277996;10721.195910306718;10849.783004339908;10631.46842657864;10702.250842441032;10720.731981700443;10734.624288149955;10744.570217191304;37652.058698532484;38239;38257.426138607945;38439.23121537582;38564.80717596422;3853.8651147209994;3877.205172939569;3878.631530004645;3924.0778417071965;4024.959750402517;28783.28041798951;28825.855870720512;28828.567571486445;28864.27839304013;28999.985000525092;2955.5566665000206;3102.2089367211443;3139.4976803711525;3199.440097982844;3208.326251487167;428317;431033;431234;435954.99999999686;442712;48450.5154948446;49540.25689614659;49653.50346496489;57050.42949570451;57174.57063573361;51775.48224517782;51988.74005629947;52076.479235207924;52146;68826.9675954861;15287.38850445992;15501.612459688373;15888.77557142001;15894.682106357808;15905.602359940862;9069.546522673973;12041.759164816654;12057.035437165006;12061.577844775566;12075.75848483043;17341.64438384306;19842.420485336814;19886.403407897786;19887.900560497394;20127.993600319925;7676.884846727304;8327.583620819056;8475.406721529556;8493.617787199591;8557.27263182624;847.1020718038775;851.1233429567556;864.9481031138026;867.3725673531653;885.751989442957;28455.146345609664;28803.855980719964;28824.423511530073;29173.687184076767;29218;1013.7820368620635;1018.3126389686931;1029.6859457865273;1043.118564812732;1182.43243243242;13009.609711708665;14358.641033974025;14868.663949883952;14901.850981490263;15038.54884353471;530115;532607;536889;539242;539733;54808;55224;55255.72372138114;55275.44724552784;55402.445975539726;1339.6449940765706;1374.4845682868925;1402.8527004664772;1404.122423485342;1405.3605609447677;27732.72267277382;28042.85978570094;28044.439111217253;28712.712872871005;28785.136445906657;44777;47174;47608.76195619;47647.80880478016;47793.28310075421;392150;392151.03924480197;394542.0272898617;396436;396977;28332.71667283383;32896.67103329032;33604;37931.86204413872;39425.40861887131;34324.656753432144;43882.34176487419;44235;44502.88742781457;44656.55343446524;33233.501497477075;36107.63892361043;36373.45439818458;37699;37831;30019.3996120072;34705.95882123541;35560.46659300061;36896;37130;34400.655993439745;34745.8262708695;34983.475247871815;35643.28713425665;35957.46063808993;31386.21534461657;32328.676713232566;32411.35177296488;32454.35091298208;32471.538780755396;23207.3037808866;23419.531609367375;23809.642855356837;24109.397265068514;24125.63811542793 05:39:45 INFO - PROCESS | 1644 | |2;jquery.html;357.05558796982;380.2850640795306;381.6202878136255;391.7962659417103;414.2481396265777;14416.855831441682;14483.131012139265;17080;20498.795012049875;20901.163953441883;21165.894170529165;21193.788062119373;21394.14423423065;21687;21894.343169704895;5550.333959924815;5554.527865131468;5630.605857589971;5807.535397168216;5823.737931793079;3856.730028897978;5621;5695.572832037593;5701.429857014306;5705.315362156547;4031.2945234583976;6051.515878729713;6439.195100612424;6450.516211284149;6451;6140.201773769426;6290.496760259191;6397.040443933401;6406.647634380107;6514.706838192293;5675;5686.630369026041;5695.5443564514735;5696.287964004499;5702.144678298247;26020.34949126287;27436.451270974667;27626.447471050466;27634.309142271402;27891.581626275623;2494.95010099797;2578.23941937129;2593.260920637609;2607.6479675243977;2611.190530935416;15650;15723.528294151194;15749.921250393789;15841.60395990098;16619.919705219123;1728.7234042553257;1763.1008185825194;1791.8745687801859;1795.8204179582067;1805.810389909067;138005;142359.86460203104;144467;147790.83137529457;148564;11459.942700286445;11463.656090317303;11558.595449159238;11567.652970410902;11787.115966302561;14492.637684057772;14880.62798430048;14949.551513454613;14952.626184345478;14952.92523537397;7130.215676275606;8352.874706879402;8599.05410404855;8609.784755381166;8690.348223883235;8340.124286949784;8352.540610266493;8367.163283671583;8515.276201522816;8522.019967703793;7209;7280.563166210044;7312.634368281565;7333.669984850694;7361.227071157558;1678.9076600787073;1863.5248011756914;1907.942761717123;1918.5587314917368;1922.356010736391;1008.3445760255746;1035.1253191053547;1057.5346847387125;1061.4374381577777;1072.4503093030496;23275.76724232736;23717.288481345586;24234.272971810875;24272;24372.634410483857;1022.9130523905551;1029.2743615750878;1044.1333693034762;1046.40877903984;1050.2070936442938;9954;11169;11574.421278936019;12346.444410001572;12400.441980110736;438363;631998;643272;658691;659702;142917;143947.28026359802;145361;148401.25799370935;153817;910.2626872233512;1003.909648131657;1018.4856647393167;1022.6369638778275;1035.404642330663;16393.42623008189;18221.726674100166;19648.965568750693;20591.691124632842;20610.278640247518;46245;46384.8403789908;54489.72755136199;55292;58924.41075589189;65559.67220163869;84558.88602784733;85765.57117214374;87448.5627571858;88563.80053396458;8066.435349525477;8067.556284404414;8359.874601881098;8537.573121343783;8592.785180370542;13669.79495307591;13844.238566878515;14101.576952691436;14120.929395352958;14327.57017289483;11423.885761142283;11715.78231836521;11734.530618775154;11785.764284714429;11855.347955862166;11427;11645;11688.298702077902;11956.299112294273;12100.818487722516;11761;11909.40452977365;11947.880521194676;12080.39598020113;12096.939515302367;8019.518828870287;8062.113167551447;8248.257656810916;8316.46347863883;8512.489250644734;11385.487653055798;11400.771984560428;11673;11736.647900562997;11811.527538898348 05:39:45 INFO - PROCESS | 1644 | |3;mootools.html;1426.4222989689174;1432.8208973878266;1446.4214314274282;1460.145814698402;1460.5764328344778;8013.4390592658465;8405.95797021014;8425.83148337033;8436.4094513384;8438.66245350186;8012.879806802888;8168.387370947173;8192.672293108268;8281.130481299468;8379.958100209506;1940.1657287366427;1981.9594712775763;2213.889305534725;2250.909963601462;2254.5265494246228;2237.8321625878125;2268.0360846640197;2268.7844654757773;2270.0238897274166;2272.749997500274;2178.4444966533592;2225.221172589597;2265.716785401825;2266.478709896725;2271.7046783918067;2235.9329220123423;2246.3485589179118;2267.2744721688982;2269.7389800173028;2278.2823410625765;2098.412444515538;2138;2140.9830330593;2143.7641859395453;2147.4094623978376;6689.966550167267;7100.396466300319;8473.576321183979;8476.152384761535;8496.192861678132;4972.925406118911;5119.206522988943;5123.948760512384;5125.847144199445;5140.794368225266;3211.775175737699;3293.1602441377536;3414.556107706007;3479.9652003479896;3496.597891242489;1229.512290128945;1251.186728626391;1653.421302544112;1708.4703741839944;1776.085316062229;9977.30158888878;10266.024727650787;10281.383117013003;10735.570577176904;10850.67447976562;4261.936070958876;4343.565643435694;4399.274119770202;4417.2269852775635;4430.313301438282;8626.654933802703;8789.956050219707;8795.384323097322;8800.735977920673;8808.559572021373;4443.688941774045;4595.747233902101;4598.701084429501;4956.058348913695;4985.925211121836;4269.23153832307;4306.4832220133785;4697.553732395417;4728.810847566127;4761.452432970253;2956.586077949089;3555.0980225579706;3630.4009838376373;3655.3420384330543;3688.7602305850032;1070.3417398300146;1108.8613923259593;1121.0807138146804;1132.7847708935276;1133.144475920691;725.9709611615476;748.3900620993873;810.9797255068553;895.2748273898163;926.3422969691594;4724.913269947931;4908.705477671351;5506.979904669533;5648.181013753065;5695.658260504383;549.7608540284989;553.8227767114565;562.6258538072136;568.6786965364556;571.6541492397123;3169.952450713241;3646.361886669822;3658.048907284125;3742.5134732484603;3834.7699138051807;9389.061093890692;9893.159081478156;9980.351277166961;9985.700428987142;10008.79982400348;9809.852852207223;9837;9850.113489785954;9896.20830333586;10002.299839011199;7613.086429628369;7647.885281720779;7694.576798276148;7727.49771264866;7845.646945887449;5413.133898576209;5502.9174562382395;5523.978064058162;5592.412796656293;5735.426457354231;9298.488583127993;9337.766555836159;9371;9374.531273436165;9380.249580033444;8338.24955753985;8927.598258078531;9298.860517092115;9309.72070837876;9380.812383752424;6559.114519539992;6768.0863083485065;6987.371136597731;7066.893996589953;7149.60677162761;7822.295993360625;7902;7936.325412340016;7987.121416644049;7998.760037198894;6135.86486499999;6998.335158159966;7159.64201789898;7218.963905180441;7231.674574644258;6933.861322773617;6981.232064472802;6983.3714965653335;7049.471289653399;7053.4004609608755;6333.081703152925;6458;6461.773837915651;6462.935370646233;6478.222613286436;4819.277108433623;4905.7792399342825;4931.408231012301;4994.925076123788;5168.53483186515;4965.8013679452415;4985.975070124626;5078;5137.614678899023;5219.608529360237 05:39:45 INFO - PROCESS | 1644 | |4;prototype.html;357.47272772660324;362.233875353627;363.3931334671099;368.28736395075515;369.6543731610944;4296.271867364227;5074.416442109158;5346.27825243592;5397.7031263280505;5424.674519528821;4882.682625629341;5283.735813209343;5303.310569625944;5320.068987927107;5359.517643412092;3764.5106136202326;3987.7208595398333;4015.437838702584;4017.513519997605;4041.312976793952;3944.2111577684436;4004.619561141687;4011.9799401002956;4012.6589239914497;4034.1326614777818;4001.7398869073563;4011.9197616047663;4027.2750904837117;4030.9395359069636;4050.088730035733;3544.7164226862046;3890.474785903895;3915.4729655434403;4051.7163798534116;4052.230076285496;2855.9000434984882;2879.827210367364;3419.794812311244;3435.3988052091036;3454.360943225511;4189.97905010476;5086.160783470723;5112.923306150411;5125.3080834087295;5128.666636668642;3617.674409303149;3811.7522361046445;4396.824127034914;4521.9599492116995;4553.863384098482;4315.4821421429315;4925.2858335541505;5447.719785850322;5563.860903477405;5577.609567330289;3200.9359812803846;3813.6758375537834;3828.770273783582;3830.157365379612;3840.231953609289;4796.880077998043;5519.917201241985;5844.736986835603;5891.263592050994;5950.9702451487465;3597.5143355647174;3717.1078941054493;3717.494414762026;4004.59954004603;4118.217538667704;4156.334986402222;4165.271077561415;4171;4178.289690752518;4180.853670121592;4542.84100056503;4721.905561888743;4726.582025392368;4790.616750659939;4794.7842347094465;5087.465816089258;5568.7215639217875;5585.301837270341;5599.776008959678;5614.6631202128;1170.9590164344206;1179.8289248058984;1210.606552870303;1320.458611969088;1389.2150934722051;824.5258976088653;932.9451487782449;972.4311277902365;978.9036279367155;997.4563862694704;4208.768517731554;4949.609517480465;5019.222020586742;5040.168372218616;5058.772355244023;5580.386157522669;5586.441355864462;5590.580706447033;5594.524465420485;5607.495325420732;3953.7825419601622;4067.491563554556;4963.255511673278;4964.851054468371;5041.319421878073;2388.390960305111;2735.589661550783;2827.3073097091346;2834.957475637867;2889.508783506809;4988.032120888066;5040.454570441903;5449.828286918331;5710.371859095496;5729.742161602738;4622.491525932145;5059.392872855281;5349.679019258857;5671.788002678959;5716.28546431696;4886.017895415225;5101.928594995093;5693.800716974886;5739.16782066598;5741.559837069312;4642.560806150104;5031.698098114125;5419.539339156058;5495.752691128989;5516.724163791714;4611.262198048296;4955.7451359632005;5345.625806193529;5405.378381486022;5501.669899806025;5157.8452646420665;5679.750454899915;5743.511801496752;5749.626274292157;5789.91315130265;3848.5189351331082;3912.784796836201;3962.663173630274;4147.419361289361;4157.376393540993;5155.458676838878;5328.60035497347;5500.614956953135;5768.798092066758;5835.620684655484;4262.378637138199;4696.966667333181;4704.694194877322;5208.932168905441;5485.6982865942755;5208.114620514598;5248.002879452892;5497.780088796403;5503.7798488060025;5523.7790488380015;4791.065742180381;4814.422269327703;4842.854714358575;5200.193969934666;5427.701476418835;5281.8643991542;5495.835124946258;5552.833414997556;5573.996680597369;5598.440155984369;4412.911741765082;4456.732596044248;5416.533418612013;5420.783168673209;5462.399136094947 05:39:45 INFO - PROCESS | 1644 | |5;yui.html;708.4651088428242;750.1485813601561;769.2768797330509;826.1985873702508;832.1844592299547;14154.929225353872;14857.034292770963;14868.925655371735;14886.925565372185;14911.179885106329;13191.538296159611;13410.66473338167;13630.931845340785;13664.9751268655;13912.443502259897;846.7798372423168;875.9386842920998;878.2973621103124;878.9604467798964;879.9736007919772;841.2849078283441;866.2290561400328;868.891388576428;869.3827382558391;870.4298684361753;923.833709932212;925.6158694141957;925.6436272035247;925.773185569533;926.4487629860222;886.5921676029035;899.6986009686763;922.8938672052733;925.0425809287356;926.0322962504132;680.5270337115707;681.3765404654735;681.417388133149;681.5637991685326;682.7917485167022;14652.340644671016;14909.95630305879;15042.247887605576;15044.097354158786;15061.322240499094;13842.238676872768;14036.508722194674;14068;14228.715425691427;14262.57212283633;10850.348979061282;10918.181136414722;11715.179937404386;11785.64643060701;12332.691682707915;3551.485034669984;3629.2197177606677;3643.9271214575824;3703.6296370363016;3777.7355585109053;107746.46126769394;108025.45987270013;109264;110963;111989.44005279921;10505.52725127371;10548.789024219626;10690;10713.410762408143;10864.076553493042;21942.232021879157;22272;22352.441188970082;22359.66460503094;22441.55116897653;7455.589942553212;7477.887831682529;7502.699892004259;7575.545467271982;7712.074551053797;4118.464599602093;4135.400366946776;4139.172165566899;4143.751374917515;4207.284761590537;3559.519464872261;3677.0072080538107;3677.42999835026;3683.3001729671278;3685.1155722626377;647.6276141218818;653.8757636049136;662.8541720821412;670.7048898484651;680.6664734280266;631.857831987799;653.7806989963779;670.4502308107403;701.5334802356376;721.1058287723185;8383.11977242393;9375.812483750287;9969.152622027064;10107.848382274271;10116.291859569907;605.5065121925617;648.8637386148962;684.260998122027;702.899961560167;703.7783098324114;4516.345129956138;4654.557817007379;4873.220284754422;4907.1167189905445;4908.361912951294;88120.6781898272;88809;88881.66677499843;90111;92247.5387623071;80251.59874200591;81677.59161204274;82457.58771206226;84174.89562760858;84926.57536712277;71795;72985.54028919392;73403.63298183582;74293.99999999892;75539;9304.255659547085;9460.574274157818;9465.763355915882;9494.81923555589;9497.952510237405;46681;47693.76153119351;47734.76132619315;47950.040999179124;49536.76158096077;54873.45126548843;60021;61045.38954610397;62695;63719.362806373196;8931;10172.84740728875;10513.316634418738;10529.842052369067;10677.679669609925;10445.268831181744;12120.757584848077;12183.147179697336;12512.37438128108;12666.683332916382;13297.202167869958;13627.591172264847;14097.718045638825;14132.50536231227;14253.857461425254;13240.867591323964;13413.329333533089;14006.719865602834;14118;14152.43390264378;11824.763504729684;12239.755204895675;12614.180078295249;13563.457461701817;14150.575482735534;10583.306670932921;11107.555697772323;11305.674707118922;11343.149263805082;11366;9122.771930701785;9174.357794954289;9257.67598134062;11104.393589502153;11110.666719993653 05:39:45 INFO - PROCESS | 1644 | __end_tp_report 05:39:45 INFO - PROCESS | 1644 | __start_cc_report 05:39:45 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,3209 05:39:45 INFO - PROCESS | 1644 | __end_cc_report 05:39:45 INFO - PROCESS | 1644 | __startTimestamp1486388385870__endTimestamp 05:39:45 INFO - PROCESS | 1644 | 05:39:45 INFO - PROCESS | 1644 | ------- Summary: start ------- 05:39:45 INFO - PROCESS | 1644 | Number of tests: 6 05:39:45 INFO - PROCESS | 1644 | 05:39:45 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5550.27 Median:5694.89 stddev:657.05 (11.5%) stddev-sans-first:633.37 05:39:45 INFO - PROCESS | 1644 | Values: 3230.6 3237.4 3243.2 3245.8 3294.4 5366.8 5462.3 5477.8 5531.8 5579.3 5217.8 5475.1 5490.6 5530.2 5555.1 3653.9 4587.0 4928.6 4979.9 5148.2 4132.0 5120.5 5143.7 5147.5 5164.4 4948.0 4962.5 4980.6 4992.4 5037.6 5020.0 5070.5 5077.0 5149.7 5160.8 4987.0 5014.7 5061.2 5080.9 5098.3 5350.8 5382.8 5578.0 5580.9 5587.4 5904.9 5935.5 5938.4 6026.0 6107.1 6089.5 6160.0 6183.3 6188.8 6198.7 5696.1 5699.7 5706.7 5708.3 5740.7 6078.1 6081.5 6105.9 6233.7 6303.7 5839.6 5851.7 5874.7 5915.7 5966.5 5124.7 5611.5 5767.1 6181.9 6183.6 6003.7 6074.4 6083.8 6088.3 6099.5 5831.8 5849.2 6100.4 6122.9 6132.3 4770.2 4808.5 4810.8 4829.5 4887.6 4286.2 4329.9 4362.3 4379.4 4405.5 4956.3 5567.5 5612.3 5612.7 5631.9 5950.8 5984.6 6006.0 6222.3 6247.8 5558.1 5577.3 5607.8 5650.8 5674.0 5332.4 5332.9 5335.2 5337.5 5366.8 6229.7 6267.5 6298.1 6307.0 6319.9 5937.0 5979.6 5981.4 6153.1 6177.5 6284.3 6320.6 6325.5 6327.9 6362.3 6063.2 6076.9 6082.8 6090.1 6105.8 5904.0 5934.0 6094.8 6181.7 6197.1 4886.7 5613.9 5790.6 5797.4 5806.9 5772.7 5784.6 5788.5 5791.2 5795.2 5537.8 5662.2 5672.6 5679.4 5685.1 5791.3 5793.9 5797.0 5805.8 5816.2 5661.5 5689.9 5691.1 5694.9 5696.9 05:39:45 INFO - PROCESS | 1644 | 05:39:45 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:59629.93 Median:36240.55 stddev:121786.58 (336.1%) stddev-sans-first:122047.18 05:39:45 INFO - PROCESS | 1644 | Values: 455.2 481.6 490.2 506.9 521.0 37298.6 38648.4 38884.0 39033.8 40880.4 39771.4 39938.6 40051.0 40081.8 40194.6 7726.8 7929.0 8848.4 9063.1 9067.2 10528.7 10796.2 10829.4 10864.1 10932.2 10754.7 10825.9 10830.9 10835.3 10854.8 7703.4 7919.8 10670.4 10721.2 10849.8 10631.5 10702.3 10720.7 10734.6 10744.6 37652.1 38239.0 38257.4 38439.2 38564.8 3853.9 3877.2 3878.6 3924.1 4025.0 28783.3 28825.9 28828.6 28864.3 29000.0 2955.6 3102.2 3139.5 3199.4 3208.3 428317.0 431033.0 431234.0 435955.0 442712.0 48450.5 49540.3 49653.5 57050.4 57174.6 51775.5 51988.7 52076.5 52146.0 68827.0 15287.4 15501.6 15888.8 15894.7 15905.6 9069.5 12041.8 12057.0 12061.6 12075.8 17341.6 19842.4 19886.4 19887.9 20128.0 7676.9 8327.6 8475.4 8493.6 8557.3 847.1 851.1 864.9 867.4 885.8 28455.1 28803.9 28824.4 29173.7 29218.0 1013.8 1018.3 1029.7 1043.1 1182.4 13009.6 14358.6 14868.7 14901.9 15038.5 530115.0 532607.0 536889.0 539242.0 539733.0 54808.0 55224.0 55255.7 55275.4 55402.4 1339.6 1374.5 1402.9 1404.1 1405.4 27732.7 28042.9 28044.4 28712.7 28785.1 44777.0 47174.0 47608.8 47647.8 47793.3 392150.0 392151.0 394542.0 396436.0 396977.0 28332.7 32896.7 33604.0 37931.9 39425.4 34324.7 43882.3 44235.0 44502.9 44656.6 33233.5 36107.6 36373.5 37699.0 37831.0 30019.4 34706.0 35560.5 36896.0 37130.0 34400.7 34745.8 34983.5 35643.3 35957.5 31386.2 32328.7 32411.4 32454.4 32471.5 23207.3 23419.5 23809.6 24109.4 24125.6 05:39:45 INFO - PROCESS | 1644 | 05:39:45 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:37097.70 Median:21540.57 stddev:103276.59 (479.5%) stddev-sans-first:103529.46 05:39:45 INFO - PROCESS | 1644 | Values: 357.1 380.3 381.6 391.8 414.2 14416.9 14483.1 17080.0 20498.8 20901.2 21165.9 21193.8 21394.1 21687.0 21894.3 5550.3 5554.5 5630.6 5807.5 5823.7 3856.7 5621.0 5695.6 5701.4 5705.3 4031.3 6051.5 6439.2 6450.5 6451.0 6140.2 6290.5 6397.0 6406.6 6514.7 5675.0 5686.6 5695.5 5696.3 5702.1 26020.3 27436.5 27626.4 27634.3 27891.6 2495.0 2578.2 2593.3 2607.6 2611.2 15650.0 15723.5 15749.9 15841.6 16619.9 1728.7 1763.1 1791.9 1795.8 1805.8 138005.0 142359.9 144467.0 147790.8 148564.0 11459.9 11463.7 11558.6 11567.7 11787.1 14492.6 14880.6 14949.6 14952.6 14952.9 7130.2 8352.9 8599.1 8609.8 8690.3 8340.1 8352.5 8367.2 8515.3 8522.0 7209.0 7280.6 7312.6 7333.7 7361.2 1678.9 1863.5 1907.9 1918.6 1922.4 1008.3 1035.1 1057.5 1061.4 1072.5 23275.8 23717.3 24234.3 24272.0 24372.6 1022.9 1029.3 1044.1 1046.4 1050.2 9954.0 11169.0 11574.4 12346.4 12400.4 438363.0 631998.0 643272.0 658691.0 659702.0 142917.0 143947.3 145361.0 148401.3 153817.0 910.3 1003.9 1018.5 1022.6 1035.4 16393.4 18221.7 19649.0 20591.7 20610.3 46245.0 46384.8 54489.7 55292.0 58924.4 65559.7 84558.9 85765.6 87448.6 88563.8 8066.4 8067.6 8359.9 8537.6 8592.8 13669.8 13844.2 14101.6 14120.9 14327.6 11423.9 11715.8 11734.5 11785.8 11855.3 11427.0 11645.0 11688.3 11956.3 12100.8 11761.0 11909.4 11947.9 12080.4 12096.9 8019.5 8062.1 8248.3 8316.5 8512.5 11385.5 11400.8 11673.0 11736.6 11811.5 05:39:45 INFO - PROCESS | 1644 | 05:39:45 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5312.61 Median:5154.66 stddev:2930.44 (56.9%) stddev-sans-first:2924.11 05:39:45 INFO - PROCESS | 1644 | Values: 1426.4 1432.8 1446.4 1460.1 1460.6 8013.4 8406.0 8425.8 8436.4 8438.7 8012.9 8168.4 8192.7 8281.1 8380.0 1940.2 1982.0 2213.9 2250.9 2254.5 2237.8 2268.0 2268.8 2270.0 2272.7 2178.4 2225.2 2265.7 2266.5 2271.7 2235.9 2246.3 2267.3 2269.7 2278.3 2098.4 2138.0 2141.0 2143.8 2147.4 6690.0 7100.4 8473.6 8476.2 8496.2 4972.9 5119.2 5123.9 5125.8 5140.8 3211.8 3293.2 3414.6 3480.0 3496.6 1229.5 1251.2 1653.4 1708.5 1776.1 9977.3 10266.0 10281.4 10735.6 10850.7 4261.9 4343.6 4399.3 4417.2 4430.3 8626.7 8790.0 8795.4 8800.7 8808.6 4443.7 4595.7 4598.7 4956.1 4985.9 4269.2 4306.5 4697.6 4728.8 4761.5 2956.6 3555.1 3630.4 3655.3 3688.8 1070.3 1108.9 1121.1 1132.8 1133.1 726.0 748.4 811.0 895.3 926.3 4724.9 4908.7 5507.0 5648.2 5695.7 549.8 553.8 562.6 568.7 571.7 3170.0 3646.4 3658.0 3742.5 3834.8 9389.1 9893.2 9980.4 9985.7 10008.8 9809.9 9837.0 9850.1 9896.2 10002.3 7613.1 7647.9 7694.6 7727.5 7845.6 5413.1 5502.9 5524.0 5592.4 5735.4 9298.5 9337.8 9371.0 9374.5 9380.2 8338.2 8927.6 9298.9 9309.7 9380.8 6559.1 6768.1 6987.4 7066.9 7149.6 7822.3 7902.0 7936.3 7987.1 7998.8 6135.9 6998.3 7159.6 7219.0 7231.7 6933.9 6981.2 6983.4 7049.5 7053.4 6333.1 6458.0 6461.8 6462.9 6478.2 4819.3 4905.8 4931.4 4994.9 5168.5 4965.8 4986.0 5078.0 5137.6 5219.6 05:39:45 INFO - PROCESS | 1644 | 05:39:45 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4416.84 Median:4937.45 stddev:1331.66 (27.0%) stddev-sans-first:1300.07 05:39:45 INFO - PROCESS | 1644 | Values: 357.5 362.2 363.4 368.3 369.7 4296.3 5074.4 5346.3 5397.7 5424.7 4882.7 5283.7 5303.3 5320.1 5359.5 3764.5 3987.7 4015.4 4017.5 4041.3 3944.2 4004.6 4012.0 4012.7 4034.1 4001.7 4011.9 4027.3 4030.9 4050.1 3544.7 3890.5 3915.5 4051.7 4052.2 2855.9 2879.8 3419.8 3435.4 3454.4 4190.0 5086.2 5112.9 5125.3 5128.7 3617.7 3811.8 4396.8 4522.0 4553.9 4315.5 4925.3 5447.7 5563.9 5577.6 3200.9 3813.7 3828.8 3830.2 3840.2 4796.9 5519.9 5844.7 5891.3 5951.0 3597.5 3717.1 3717.5 4004.6 4118.2 4156.3 4165.3 4171.0 4178.3 4180.9 4542.8 4721.9 4726.6 4790.6 4794.8 5087.5 5568.7 5585.3 5599.8 5614.7 1171.0 1179.8 1210.6 1320.5 1389.2 824.5 932.9 972.4 978.9 997.5 4208.8 4949.6 5019.2 5040.2 5058.8 5580.4 5586.4 5590.6 5594.5 5607.5 3953.8 4067.5 4963.3 4964.9 5041.3 2388.4 2735.6 2827.3 2835.0 2889.5 4988.0 5040.5 5449.8 5710.4 5729.7 4622.5 5059.4 5349.7 5671.8 5716.3 4886.0 5101.9 5693.8 5739.2 5741.6 4642.6 5031.7 5419.5 5495.8 5516.7 4611.3 4955.7 5345.6 5405.4 5501.7 5157.8 5679.8 5743.5 5749.6 5789.9 3848.5 3912.8 3962.7 4147.4 4157.4 5155.5 5328.6 5500.6 5768.8 5835.6 4262.4 4697.0 4704.7 5208.9 5485.7 5208.1 5248.0 5497.8 5503.8 5523.8 4791.1 4814.4 4842.9 5200.2 5427.7 5281.9 5495.8 5552.8 5574.0 5598.4 4412.9 4456.7 5416.5 5420.8 5462.4 05:39:45 INFO - PROCESS | 1644 | 05:39:45 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19426.63 Median:47187.38 stddev:27759.62 (58.8%) stddev-sans-first:27801.91 05:39:45 INFO - PROCESS | 1644 | Values: 708.5 750.1 769.3 826.2 832.2 14154.9 14857.0 14868.9 14886.9 14911.2 13191.5 13410.7 13630.9 13665.0 13912.4 846.8 875.9 878.3 879.0 880.0 841.3 866.2 868.9 869.4 870.4 923.8 925.6 925.6 925.8 926.4 886.6 899.7 922.9 925.0 926.0 680.5 681.4 681.4 681.6 682.8 14652.3 14910.0 15042.2 15044.1 15061.3 13842.2 14036.5 14068.0 14228.7 14262.6 10850.3 10918.2 11715.2 11785.6 12332.7 3551.5 3629.2 3643.9 3703.6 3777.7 107746.5 108025.5 109264.0 110963.0 111989.4 10505.5 10548.8 10690.0 10713.4 10864.1 21942.2 22272.0 22352.4 22359.7 22441.6 7455.6 7477.9 7502.7 7575.5 7712.1 4118.5 4135.4 4139.2 4143.8 4207.3 3559.5 3677.0 3677.4 3683.3 3685.1 647.6 653.9 662.9 670.7 680.7 631.9 653.8 670.5 701.5 721.1 8383.1 9375.8 9969.2 10107.8 10116.3 605.5 648.9 684.3 702.9 703.8 4516.3 4654.6 4873.2 4907.1 4908.4 88120.7 88809.0 88881.7 90111.0 92247.5 80251.6 81677.6 82457.6 84174.9 84926.6 71795.0 72985.5 73403.6 74294.0 75539.0 9304.3 9460.6 9465.8 9494.8 9498.0 46681.0 47693.8 47734.8 47950.0 49536.8 54873.5 60021.0 61045.4 62695.0 63719.4 8931.0 10172.8 10513.3 10529.8 10677.7 10445.3 12120.8 12183.1 12512.4 12666.7 13297.2 13627.6 14097.7 14132.5 14253.9 13240.9 13413.3 14006.7 14118.0 14152.4 11824.8 12239.8 12614.2 13563.5 14150.6 10583.3 11107.6 11305.7 11343.1 11366.0 9122.8 9174.4 9257.7 11104.4 11110.7 05:39:45 INFO - PROCESS | 1644 | -------- Summary: end -------- 05:39:45 INFO - PROCESS | 1644 | 05:39:46 INFO - TEST-INFO | 1644: exit 0 05:39:46 INFO - TEST-OK | dromaeo_css | took 1119170ms 05:39:46 INFO - TEST-START | kraken 05:39:46 INFO - operating with platform_type : mac_ 05:39:46 INFO - Initialising browser for kraken test... 05:39:46 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmp3w2qN_/profile http://localhost:49235/getInfo.html) 05:39:48 INFO - PROCESS | 1654 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 05:39:48 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 05:39:48 INFO - PROCESS | 1654 | colorDepth:24 05:39:48 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 05:39:48 INFO - PROCESS | 1654 | __metrics 05:39:48 INFO - PROCESS | 1654 | 1486388388857 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:39:48 INFO - PROCESS | 1654 | 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" 05:39:48 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:39:49 INFO - TEST-INFO | 1654: exit 0 05:39:49 INFO - Browser initialized. 05:39:49 INFO - Running cycle 1/1 for kraken test... 05:39:49 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmp3w2qN_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:40:01 INFO - PROCESS | 1655 | RSS: Main: 236851200 05:40:01 INFO - PROCESS | 1655 | 05:40:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 05:40:05 INFO - PROCESS | 1655 | RSS: Main: 288710656 05:40:05 INFO - PROCESS | 1655 | 05:40:08 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 05:40:08 INFO - PROCESS | 1655 | RSS: Main: 290398208 05:40:08 INFO - PROCESS | 1655 | 05:40:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 05:40:12 INFO - PROCESS | 1655 | RSS: Main: 380731392 05:40:12 INFO - PROCESS | 1655 | 05:40:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 05:40:15 INFO - PROCESS | 1655 | RSS: Main: 283283456 05:40:15 INFO - PROCESS | 1655 | 05:40:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:40:18 INFO - PROCESS | 1655 | RSS: Main: 286515200 05:40:18 INFO - PROCESS | 1655 | 05:40:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 05:40:21 INFO - PROCESS | 1655 | RSS: Main: 330309632 05:40:21 INFO - PROCESS | 1655 | 05:40:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 05:40:25 INFO - PROCESS | 1655 | RSS: Main: 333398016 05:40:25 INFO - PROCESS | 1655 | 05:40:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 05:40:28 INFO - PROCESS | 1655 | RSS: Main: 331706368 05:40:28 INFO - PROCESS | 1655 | 05:40:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:40:30 INFO - PROCESS | 1655 | RSS: Main: 305983488 05:40:30 INFO - PROCESS | 1655 | 05:40:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:40:33 INFO - PROCESS | 1655 | RSS: Main: 311226368 05:40:33 INFO - PROCESS | 1655 | 05:40:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:40:36 INFO - PROCESS | 1655 | RSS: Main: 311717888 05:40:36 INFO - PROCESS | 1655 | 05:40:39 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:40:40 INFO - PROCESS | 1655 | RSS: Main: 327483392 05:40:40 INFO - PROCESS | 1655 | 05:40:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:43 INFO - PROCESS | 1655 | RSS: Main: 321912832 05:40:43 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 05:40:45 INFO - PROCESS | 1655 | RSS: Main: 366919680 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | __start_tp_report 05:40:45 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 05:40:45 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 05:40:45 INFO - PROCESS | 1655 | |i|pagename|runs| 05:40:45 INFO - PROCESS | 1655 | |0;ai-astar;114;115;115;114;115;117;115;114;114;115 05:40:45 INFO - PROCESS | 1655 | |1;audio-beat-detection;142;110;109;148;110;109;112;108;131;109 05:40:45 INFO - PROCESS | 1655 | |2;audio-dft;189;256;221;186;186;183;183;185;184;184 05:40:45 INFO - PROCESS | 1655 | |3;audio-fft;78;59;60;88;58;58;59;61;89;82 05:40:45 INFO - PROCESS | 1655 | |4;audio-oscillator;89;71;86;70;66;70;85;67;69;83 05:40:45 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;90;88;89;87;90;86;90;87;120;86 05:40:45 INFO - PROCESS | 1655 | |6;imaging-darkroom;97;98;99;97;97;97;99;97;97;98 05:40:45 INFO - PROCESS | 1655 | |7;imaging-desaturate;88;89;85;87;88;89;86;85;86;85 05:40:45 INFO - PROCESS | 1655 | |8;json-parse-financial;61;59;59;58;59;59;59;59;59;59 05:40:45 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;67;80;79;75;71;68;69;73;100;101 05:40:45 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;92;88;90;88;87;87;86;90;88;90 05:40:45 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;141;140;142;140;137;144;156;151;179;145 05:40:45 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;175;175;174;172;198;227;172;180;235;192 05:40:45 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;67;68;67;69;67;67;67;66;66;77 05:40:45 INFO - PROCESS | 1655 | __end_tp_report 05:40:45 INFO - PROCESS | 1655 | __start_cc_report 05:40:45 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2099 05:40:45 INFO - PROCESS | 1655 | __end_cc_report 05:40:45 INFO - PROCESS | 1655 | __startTimestamp1486388445797__endTimestamp 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | ------- Summary: start ------- 05:40:45 INFO - PROCESS | 1655 | Number of tests: 14 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.00 stddev:0.92 (0.8%) stddev-sans-first:0.93 05:40:45 INFO - PROCESS | 1655 | Values: 114.0 115.0 115.0 114.0 115.0 117.0 115.0 114.0 114.0 115.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:118.80 Median:111.00 stddev:15.44 (13.9%) stddev-sans-first:13.91 05:40:45 INFO - PROCESS | 1655 | Values: 142.0 110.0 109.0 148.0 110.0 109.0 112.0 108.0 131.0 109.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:195.70 Median:186.00 stddev:24.08 (12.9%) stddev-sans-first:25.42 05:40:45 INFO - PROCESS | 1655 | Values: 189.0 256.0 221.0 186.0 186.0 183.0 183.0 185.0 184.0 184.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:69.20 Median:69.50 stddev:13.32 (19.2%) stddev-sans-first:13.75 05:40:45 INFO - PROCESS | 1655 | Values: 78.0 59.0 60.0 88.0 58.0 58.0 59.0 61.0 89.0 82.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:77.00 stddev:8.97 (11.7%) stddev-sans-first:8.10 05:40:45 INFO - PROCESS | 1655 | Values: 89.0 71.0 86.0 70.0 66.0 70.0 85.0 67.0 69.0 83.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:88.50 stddev:10.21 (11.5%) stddev-sans-first:10.82 05:40:45 INFO - PROCESS | 1655 | Values: 90.0 88.0 89.0 87.0 90.0 86.0 90.0 87.0 120.0 86.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:97.60 Median:97.50 stddev:0.84 (0.9%) stddev-sans-first:0.87 05:40:45 INFO - PROCESS | 1655 | Values: 97.0 98.0 99.0 97.0 97.0 97.0 99.0 97.0 97.0 98.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:86.80 Median:87.50 stddev:1.62 (1.9%) stddev-sans-first:1.66 05:40:45 INFO - PROCESS | 1655 | Values: 88.0 89.0 85.0 87.0 88.0 89.0 86.0 85.0 86.0 85.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:59.00 stddev:0.74 (1.3%) stddev-sans-first:0.33 05:40:45 INFO - PROCESS | 1655 | Values: 61.0 59.0 59.0 58.0 59.0 59.0 59.0 59.0 59.0 59.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:78.30 Median:72.00 stddev:12.48 (17.3%) stddev-sans-first:12.55 05:40:45 INFO - PROCESS | 1655 | Values: 67.0 80.0 79.0 75.0 71.0 68.0 69.0 73.0 100.0 101.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:89.00 stddev:1.84 (2.1%) stddev-sans-first:1.48 05:40:45 INFO - PROCESS | 1655 | Values: 92.0 88.0 90.0 88.0 87.0 87.0 86.0 90.0 88.0 90.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:147.50 Median:144.50 stddev:12.43 (8.6%) stddev-sans-first:12.96 05:40:45 INFO - PROCESS | 1655 | Values: 141.0 140.0 142.0 140.0 137.0 144.0 156.0 151.0 179.0 145.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.00 Median:186.00 stddev:23.37 (12.6%) stddev-sans-first:24.15 05:40:45 INFO - PROCESS | 1655 | Values: 175.0 175.0 174.0 172.0 198.0 227.0 172.0 180.0 235.0 192.0 05:40:45 INFO - PROCESS | 1655 | 05:40:45 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:67.00 stddev:3.25 (4.8%) stddev-sans-first:3.42 05:40:45 INFO - PROCESS | 1655 | Values: 67.0 68.0 67.0 69.0 67.0 67.0 67.0 66.0 66.0 77.0 05:40:45 INFO - PROCESS | 1655 | -------- Summary: end -------- 05:40:45 INFO - PROCESS | 1655 | 05:40:46 INFO - TEST-INFO | 1655: exit 0 05:40:46 INFO - TEST-OK | kraken | took 60308ms 05:40:46 INFO - SUITE-END | took 1179s 05:40:46 INFO - Completed test suite (00:19:39) 05:40:46 INFO - javascript benchmark 05:40:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7156.292073748519, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3230.612326520817, 3237.3848968695947, 3243.172990887324, 3245.7727959042877, 3294.407006738786, 5366.812161574346, 5462.344518657756, 5477.835664930049, 5531.778728850846, 5579.330480342354, 5217.799906021617, 5475.069238229501, 5490.588205884555, 5530.198121272413, 5555.083411237151, 3653.9269214615756, 4586.97706511467, 4928.631942964998, 4979.900401991958, 5148.176291793314, 4131.958680413188, 5120.487951204868, 5143.665661731976, 5147.459516750743, 5164.354455693038, 4947.985662939107, 4962.528559786814, 4980.551750342451, 4992.40091189056, 5037.647364684473, 5020.046191223656, 5070.467600901926, 5076.974615126937, 5149.665271757323, 5160.845174644766, 4987.0, 5014.674046186986, 5061.240813877911, 5080.923786143211, 5098.286239926413, 5350.759215835296, 5382.838514844561, 5578.0, 5580.91628625571, 5587.357453892813, 5904.852378690567, 5935.525157987394, 5938.406159384071, 6025.969870150621, 6107.144999700045, 6089.512838972872, 6160.045192995048, 6183.319834818167, 6188.752449901998, 6198.659073750942, 5696.088625819849, 5699.715014249313, 5706.714664266811, 5708.257926469532, 5740.712964351766, 6078.0882867569335, 6081.543884208702, 6105.938940610626, 6233.688315584293, 6303.684815759194, 5839.591228614041, 5851.736671849777, 5874.706264686749, 5915.674637894871, 5966.522678185735, 5124.743762811844, 5611.494965453047, 5767.077267637158, 6181.907271390933, 6183.567150299526, 6003.669798161143, 6074.362191969868, 6083.8479038023515, 6088.299845517734, 6099.542534309944, 5831.825045248649, 5849.210356601804, 6100.3899610039525, 6122.877542449126, 6132.294786099566, 4770.236762118045, 4808.495108013678, 4810.759462026885, 4829.468758436569, 4887.560119589183, 4286.164197981426, 4329.935050974175, 4362.342309922209, 4379.364992076131, 4405.493368262627, 4956.330895329156, 5567.498925096761, 5612.326520817528, 5612.747426365823, 5631.915521267184, 5950.821475355746, 5984.581079324405, 6005.978983572716, 6222.253329600477, 6247.843803904939, 5558.082916318762, 5577.274954256002, 5607.831765047055, 5650.802221922213, 5674.00704876645, 5332.386775520865, 5332.866678333073, 5335.1730481775385, 5337.492938170945, 5366.812161574326, 6229.657368844757, 6267.467265282502, 6298.149749783904, 6307.0, 6319.905201421982, 5937.020391635332, 5979.611325263844, 5981.431763982415, 6153.13856060143, 6177.4749146323065, 6284.3087260402235, 6320.5575609706875, 6325.462335701332, 6327.936720632735, 6362.331955144643, 6063.242094738158, 6076.908846367396, 6082.756689732538, 6090.147379366804, 6105.755769769159, 5904.0, 5933.970330148321, 6094.7562097517375, 6181.721822518088, 6197.070439434121, 4886.682365646222, 5613.915791263053, 5790.565707572033, 5797.391273916347, 5806.854828629318, 5772.682502462405, 5784.595078344475, 5788.450097240755, 5791.189233507228, 5795.159701843293, 5537.833864984057, 5662.150677398423, 5672.631278966854, 5679.432056794288, 5685.061964775931, 5791.276090488689, 5793.8551536211935, 5796.971015144897, 5805.796797112081, 5816.1857339973285, 5661.518770904519, 5689.8862022758485, 5691.117876729114, 5694.886102277848, 5696.886062278814], "value": 5507.836598616854, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.20112203083585, 481.561778781309, 490.2278910715624, 506.9036882992231, 521.0464849325734, 37298.627013729856, 38648.42027369592, 38884.0, 39033.804830975874, 40880.38679419811, 39771.403428948586, 39938.60061399393, 40051.0, 40081.799591002, 40194.59805401945, 7726.8454630907345, 7928.960355198216, 8848.36305283523, 9063.09369063095, 9067.183953444188, 10528.68413947579, 10796.190285728602, 10829.350238985686, 10864.130869530458, 10932.180086493505, 10754.677359679223, 10825.917408259189, 10830.945845270762, 10835.29570577914, 10854.837177442345, 7703.422243331716, 7919.802004949865, 10670.413127277996, 10721.195910306718, 10849.783004339908, 10631.46842657864, 10702.250842441032, 10720.731981700443, 10734.624288149955, 10744.570217191304, 37652.058698532484, 38239.0, 38257.426138607945, 38439.23121537582, 38564.80717596422, 3853.8651147209994, 3877.205172939569, 3878.631530004645, 3924.0778417071965, 4024.959750402517, 28783.28041798951, 28825.855870720512, 28828.567571486445, 28864.27839304013, 28999.985000525092, 2955.5566665000206, 3102.2089367211443, 3139.4976803711525, 3199.440097982844, 3208.326251487167, 428317.0, 431033.0, 431234.0, 435954.99999999686, 442712.0, 48450.5154948446, 49540.25689614659, 49653.50346496489, 57050.42949570451, 57174.57063573361, 51775.48224517782, 51988.74005629947, 52076.479235207924, 52146.0, 68826.9675954861, 15287.38850445992, 15501.612459688373, 15888.77557142001, 15894.682106357808, 15905.602359940862, 9069.546522673973, 12041.759164816654, 12057.035437165006, 12061.577844775566, 12075.75848483043, 17341.64438384306, 19842.420485336814, 19886.403407897786, 19887.900560497394, 20127.993600319925, 7676.884846727304, 8327.583620819056, 8475.406721529556, 8493.617787199591, 8557.27263182624, 847.1020718038775, 851.1233429567556, 864.9481031138026, 867.3725673531653, 885.751989442957, 28455.146345609664, 28803.855980719964, 28824.423511530073, 29173.687184076767, 29218.0, 1013.7820368620635, 1018.3126389686931, 1029.6859457865273, 1043.118564812732, 1182.43243243242, 13009.609711708665, 14358.641033974025, 14868.663949883952, 14901.850981490263, 15038.54884353471, 530115.0, 532607.0, 536889.0, 539242.0, 539733.0, 54808.0, 55224.0, 55255.72372138114, 55275.44724552784, 55402.445975539726, 1339.6449940765706, 1374.4845682868925, 1402.8527004664772, 1404.122423485342, 1405.3605609447677, 27732.72267277382, 28042.85978570094, 28044.439111217253, 28712.712872871005, 28785.136445906657, 44777.0, 47174.0, 47608.76195619, 47647.80880478016, 47793.28310075421, 392150.0, 392151.03924480197, 394542.0272898617, 396436.0, 396977.0, 28332.71667283383, 32896.67103329032, 33604.0, 37931.86204413872, 39425.40861887131, 34324.656753432144, 43882.34176487419, 44235.0, 44502.88742781457, 44656.55343446524, 33233.501497477075, 36107.63892361043, 36373.45439818458, 37699.0, 37831.0, 30019.3996120072, 34705.95882123541, 35560.46659300061, 36896.0, 37130.0, 34400.655993439745, 34745.8262708695, 34983.475247871815, 35643.28713425665, 35957.46063808993, 31386.21534461657, 32328.676713232566, 32411.35177296488, 32454.35091298208, 32471.538780755396, 23207.3037808866, 23419.531609367375, 23809.642855356837, 24109.397265068514, 24125.63811542793], "value": 19192.92932369912, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.05558796982, 380.2850640795306, 381.6202878136255, 391.7962659417103, 414.2481396265777, 14416.855831441682, 14483.131012139265, 17080.0, 20498.795012049875, 20901.163953441883, 21165.894170529165, 21193.788062119373, 21394.14423423065, 21687.0, 21894.343169704895, 5550.333959924815, 5554.527865131468, 5630.605857589971, 5807.535397168216, 5823.737931793079, 3856.730028897978, 5621.0, 5695.572832037593, 5701.429857014306, 5705.315362156547, 4031.2945234583976, 6051.515878729713, 6439.195100612424, 6450.516211284149, 6451.0, 6140.201773769426, 6290.496760259191, 6397.040443933401, 6406.647634380107, 6514.706838192293, 5675.0, 5686.630369026041, 5695.5443564514735, 5696.287964004499, 5702.144678298247, 26020.34949126287, 27436.451270974667, 27626.447471050466, 27634.309142271402, 27891.581626275623, 2494.95010099797, 2578.23941937129, 2593.260920637609, 2607.6479675243977, 2611.190530935416, 15650.0, 15723.528294151194, 15749.921250393789, 15841.60395990098, 16619.919705219123, 1728.7234042553257, 1763.1008185825194, 1791.8745687801859, 1795.8204179582067, 1805.810389909067, 138005.0, 142359.86460203104, 144467.0, 147790.83137529457, 148564.0, 11459.942700286445, 11463.656090317303, 11558.595449159238, 11567.652970410902, 11787.115966302561, 14492.637684057772, 14880.62798430048, 14949.551513454613, 14952.626184345478, 14952.92523537397, 7130.215676275606, 8352.874706879402, 8599.05410404855, 8609.784755381166, 8690.348223883235, 8340.124286949784, 8352.540610266493, 8367.163283671583, 8515.276201522816, 8522.019967703793, 7209.0, 7280.563166210044, 7312.634368281565, 7333.669984850694, 7361.227071157558, 1678.9076600787073, 1863.5248011756914, 1907.942761717123, 1918.5587314917368, 1922.356010736391, 1008.3445760255746, 1035.1253191053547, 1057.5346847387125, 1061.4374381577777, 1072.4503093030496, 23275.76724232736, 23717.288481345586, 24234.272971810875, 24272.0, 24372.634410483857, 1022.9130523905551, 1029.2743615750878, 1044.1333693034762, 1046.40877903984, 1050.2070936442938, 9954.0, 11169.0, 11574.421278936019, 12346.444410001572, 12400.441980110736, 438363.0, 631998.0, 643272.0, 658691.0, 659702.0, 142917.0, 143947.28026359802, 145361.0, 148401.25799370935, 153817.0, 910.2626872233512, 1003.909648131657, 1018.4856647393167, 1022.6369638778275, 1035.404642330663, 16393.42623008189, 18221.726674100166, 19648.965568750693, 20591.691124632842, 20610.278640247518, 46245.0, 46384.8403789908, 54489.72755136199, 55292.0, 58924.41075589189, 65559.67220163869, 84558.88602784733, 85765.57117214374, 87448.5627571858, 88563.80053396458, 8066.435349525477, 8067.556284404414, 8359.874601881098, 8537.573121343783, 8592.785180370542, 13669.79495307591, 13844.238566878515, 14101.576952691436, 14120.929395352958, 14327.57017289483, 11423.885761142283, 11715.78231836521, 11734.530618775154, 11785.764284714429, 11855.347955862166, 11427.0, 11645.0, 11688.298702077902, 11956.299112294273, 12100.818487722516, 11761.0, 11909.40452977365, 11947.880521194676, 12080.39598020113, 12096.939515302367, 8019.518828870287, 8062.113167551447, 8248.257656810916, 8316.46347863883, 8512.489250644734, 11385.487653055798, 11400.771984560428, 11673.0, 11736.647900562997, 11811.527538898348], "value": 10155.803506034226, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.4222989689174, 1432.8208973878266, 1446.4214314274282, 1460.145814698402, 1460.5764328344778, 8013.4390592658465, 8405.95797021014, 8425.83148337033, 8436.4094513384, 8438.66245350186, 8012.879806802888, 8168.387370947173, 8192.672293108268, 8281.130481299468, 8379.958100209506, 1940.1657287366427, 1981.9594712775763, 2213.889305534725, 2250.909963601462, 2254.5265494246228, 2237.8321625878125, 2268.0360846640197, 2268.7844654757773, 2270.0238897274166, 2272.749997500274, 2178.4444966533592, 2225.221172589597, 2265.716785401825, 2266.478709896725, 2271.7046783918067, 2235.9329220123423, 2246.3485589179118, 2267.2744721688982, 2269.7389800173028, 2278.2823410625765, 2098.412444515538, 2138.0, 2140.9830330593, 2143.7641859395453, 2147.4094623978376, 6689.966550167267, 7100.396466300319, 8473.576321183979, 8476.152384761535, 8496.192861678132, 4972.925406118911, 5119.206522988943, 5123.948760512384, 5125.847144199445, 5140.794368225266, 3211.775175737699, 3293.1602441377536, 3414.556107706007, 3479.9652003479896, 3496.597891242489, 1229.512290128945, 1251.186728626391, 1653.421302544112, 1708.4703741839944, 1776.085316062229, 9977.30158888878, 10266.024727650787, 10281.383117013003, 10735.570577176904, 10850.67447976562, 4261.936070958876, 4343.565643435694, 4399.274119770202, 4417.2269852775635, 4430.313301438282, 8626.654933802703, 8789.956050219707, 8795.384323097322, 8800.735977920673, 8808.559572021373, 4443.688941774045, 4595.747233902101, 4598.701084429501, 4956.058348913695, 4985.925211121836, 4269.23153832307, 4306.4832220133785, 4697.553732395417, 4728.810847566127, 4761.452432970253, 2956.586077949089, 3555.0980225579706, 3630.4009838376373, 3655.3420384330543, 3688.7602305850032, 1070.3417398300146, 1108.8613923259593, 1121.0807138146804, 1132.7847708935276, 1133.144475920691, 725.9709611615476, 748.3900620993873, 810.9797255068553, 895.2748273898163, 926.3422969691594, 4724.913269947931, 4908.705477671351, 5506.979904669533, 5648.181013753065, 5695.658260504383, 549.7608540284989, 553.8227767114565, 562.6258538072136, 568.6786965364556, 571.6541492397123, 3169.952450713241, 3646.361886669822, 3658.048907284125, 3742.5134732484603, 3834.7699138051807, 9389.061093890692, 9893.159081478156, 9980.351277166961, 9985.700428987142, 10008.79982400348, 9809.852852207223, 9837.0, 9850.113489785954, 9896.20830333586, 10002.299839011199, 7613.086429628369, 7647.885281720779, 7694.576798276148, 7727.49771264866, 7845.646945887449, 5413.133898576209, 5502.9174562382395, 5523.978064058162, 5592.412796656293, 5735.426457354231, 9298.488583127993, 9337.766555836159, 9371.0, 9374.531273436165, 9380.249580033444, 8338.24955753985, 8927.598258078531, 9298.860517092115, 9309.72070837876, 9380.812383752424, 6559.114519539992, 6768.0863083485065, 6987.371136597731, 7066.893996589953, 7149.60677162761, 7822.295993360625, 7902.0, 7936.325412340016, 7987.121416644049, 7998.760037198894, 6135.86486499999, 6998.335158159966, 7159.64201789898, 7218.963905180441, 7231.674574644258, 6933.861322773617, 6981.232064472802, 6983.3714965653335, 7049.471289653399, 7053.4004609608755, 6333.081703152925, 6458.0, 6461.773837915651, 6462.935370646233, 6478.222613286436, 4819.277108433623, 4905.7792399342825, 4931.408231012301, 4994.925076123788, 5168.53483186515, 4965.8013679452415, 4985.975070124626, 5078.0, 5137.614678899023, 5219.608529360237], "value": 4265.494271590359, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.47272772660324, 362.233875353627, 363.3931334671099, 368.28736395075515, 369.6543731610944, 4296.271867364227, 5074.416442109158, 5346.27825243592, 5397.7031263280505, 5424.674519528821, 4882.682625629341, 5283.735813209343, 5303.310569625944, 5320.068987927107, 5359.517643412092, 3764.5106136202326, 3987.7208595398333, 4015.437838702584, 4017.513519997605, 4041.312976793952, 3944.2111577684436, 4004.619561141687, 4011.9799401002956, 4012.6589239914497, 4034.1326614777818, 4001.7398869073563, 4011.9197616047663, 4027.2750904837117, 4030.9395359069636, 4050.088730035733, 3544.7164226862046, 3890.474785903895, 3915.4729655434403, 4051.7163798534116, 4052.230076285496, 2855.9000434984882, 2879.827210367364, 3419.794812311244, 3435.3988052091036, 3454.360943225511, 4189.97905010476, 5086.160783470723, 5112.923306150411, 5125.3080834087295, 5128.666636668642, 3617.674409303149, 3811.7522361046445, 4396.824127034914, 4521.9599492116995, 4553.863384098482, 4315.4821421429315, 4925.2858335541505, 5447.719785850322, 5563.860903477405, 5577.609567330289, 3200.9359812803846, 3813.6758375537834, 3828.770273783582, 3830.157365379612, 3840.231953609289, 4796.880077998043, 5519.917201241985, 5844.736986835603, 5891.263592050994, 5950.9702451487465, 3597.5143355647174, 3717.1078941054493, 3717.494414762026, 4004.59954004603, 4118.217538667704, 4156.334986402222, 4165.271077561415, 4171.0, 4178.289690752518, 4180.853670121592, 4542.84100056503, 4721.905561888743, 4726.582025392368, 4790.616750659939, 4794.7842347094465, 5087.465816089258, 5568.7215639217875, 5585.301837270341, 5599.776008959678, 5614.6631202128, 1170.9590164344206, 1179.8289248058984, 1210.606552870303, 1320.458611969088, 1389.2150934722051, 824.5258976088653, 932.9451487782449, 972.4311277902365, 978.9036279367155, 997.4563862694704, 4208.768517731554, 4949.609517480465, 5019.222020586742, 5040.168372218616, 5058.772355244023, 5580.386157522669, 5586.441355864462, 5590.580706447033, 5594.524465420485, 5607.495325420732, 3953.7825419601622, 4067.491563554556, 4963.255511673278, 4964.851054468371, 5041.319421878073, 2388.390960305111, 2735.589661550783, 2827.3073097091346, 2834.957475637867, 2889.508783506809, 4988.032120888066, 5040.454570441903, 5449.828286918331, 5710.371859095496, 5729.742161602738, 4622.491525932145, 5059.392872855281, 5349.679019258857, 5671.788002678959, 5716.28546431696, 4886.017895415225, 5101.928594995093, 5693.800716974886, 5739.16782066598, 5741.559837069312, 4642.560806150104, 5031.698098114125, 5419.539339156058, 5495.752691128989, 5516.724163791714, 4611.262198048296, 4955.7451359632005, 5345.625806193529, 5405.378381486022, 5501.669899806025, 5157.8452646420665, 5679.750454899915, 5743.511801496752, 5749.626274292157, 5789.91315130265, 3848.5189351331082, 3912.784796836201, 3962.663173630274, 4147.419361289361, 4157.376393540993, 5155.458676838878, 5328.60035497347, 5500.614956953135, 5768.798092066758, 5835.620684655484, 4262.378637138199, 4696.966667333181, 4704.694194877322, 5208.932168905441, 5485.6982865942755, 5208.114620514598, 5248.002879452892, 5497.780088796403, 5503.7798488060025, 5523.7790488380015, 4791.065742180381, 4814.422269327703, 4842.854714358575, 5200.193969934666, 5427.701476418835, 5281.8643991542, 5495.835124946258, 5552.833414997556, 5573.996680597369, 5598.440155984369, 4412.911741765082, 4456.732596044248, 5416.533418612013, 5420.783168673209, 5462.399136094947], "value": 4015.2264571835785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.4651088428242, 750.1485813601561, 769.2768797330509, 826.1985873702508, 832.1844592299547, 14154.929225353872, 14857.034292770963, 14868.925655371735, 14886.925565372185, 14911.179885106329, 13191.538296159611, 13410.66473338167, 13630.931845340785, 13664.9751268655, 13912.443502259897, 846.7798372423168, 875.9386842920998, 878.2973621103124, 878.9604467798964, 879.9736007919772, 841.2849078283441, 866.2290561400328, 868.891388576428, 869.3827382558391, 870.4298684361753, 923.833709932212, 925.6158694141957, 925.6436272035247, 925.773185569533, 926.4487629860222, 886.5921676029035, 899.6986009686763, 922.8938672052733, 925.0425809287356, 926.0322962504132, 680.5270337115707, 681.3765404654735, 681.417388133149, 681.5637991685326, 682.7917485167022, 14652.340644671016, 14909.95630305879, 15042.247887605576, 15044.097354158786, 15061.322240499094, 13842.238676872768, 14036.508722194674, 14068.0, 14228.715425691427, 14262.57212283633, 10850.348979061282, 10918.181136414722, 11715.179937404386, 11785.64643060701, 12332.691682707915, 3551.485034669984, 3629.2197177606677, 3643.9271214575824, 3703.6296370363016, 3777.7355585109053, 107746.46126769394, 108025.45987270013, 109264.0, 110963.0, 111989.44005279921, 10505.52725127371, 10548.789024219626, 10690.0, 10713.410762408143, 10864.076553493042, 21942.232021879157, 22272.0, 22352.441188970082, 22359.66460503094, 22441.55116897653, 7455.589942553212, 7477.887831682529, 7502.699892004259, 7575.545467271982, 7712.074551053797, 4118.464599602093, 4135.400366946776, 4139.172165566899, 4143.751374917515, 4207.284761590537, 3559.519464872261, 3677.0072080538107, 3677.42999835026, 3683.3001729671278, 3685.1155722626377, 647.6276141218818, 653.8757636049136, 662.8541720821412, 670.7048898484651, 680.6664734280266, 631.857831987799, 653.7806989963779, 670.4502308107403, 701.5334802356376, 721.1058287723185, 8383.11977242393, 9375.812483750287, 9969.152622027064, 10107.848382274271, 10116.291859569907, 605.5065121925617, 648.8637386148962, 684.260998122027, 702.899961560167, 703.7783098324114, 4516.345129956138, 4654.557817007379, 4873.220284754422, 4907.1167189905445, 4908.361912951294, 88120.6781898272, 88809.0, 88881.66677499843, 90111.0, 92247.5387623071, 80251.59874200591, 81677.59161204274, 82457.58771206226, 84174.89562760858, 84926.57536712277, 71795.0, 72985.54028919392, 73403.63298183582, 74293.99999999892, 75539.0, 9304.255659547085, 9460.574274157818, 9465.763355915882, 9494.81923555589, 9497.952510237405, 46681.0, 47693.76153119351, 47734.76132619315, 47950.040999179124, 49536.76158096077, 54873.45126548843, 60021.0, 61045.38954610397, 62695.0, 63719.362806373196, 8931.0, 10172.84740728875, 10513.316634418738, 10529.842052369067, 10677.679669609925, 10445.268831181744, 12120.757584848077, 12183.147179697336, 12512.37438128108, 12666.683332916382, 13297.202167869958, 13627.591172264847, 14097.718045638825, 14132.50536231227, 14253.857461425254, 13240.867591323964, 13413.329333533089, 14006.719865602834, 14118.0, 14152.43390264378, 11824.763504729684, 12239.755204895675, 12614.180078295249, 13563.457461701817, 14150.575482735534, 10583.306670932921, 11107.555697772323, 11305.674707118922, 11343.149263805082, 11366.0, 9122.771930701785, 9174.357794954289, 9257.67598134062, 11104.393589502153, 11110.666719993653], "value": 7304.034123637202, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1481.3999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 114.0, 115.0, 117.0, 115.0, 114.0, 114.0, 115.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 109.0, 148.0, 110.0, 109.0, 112.0, 108.0, 131.0, 109.0], "value": 118.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 256.0, 221.0, 186.0, 186.0, 183.0, 183.0, 185.0, 184.0, 184.0], "value": 195.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 60.0, 88.0, 58.0, 58.0, 59.0, 61.0, 89.0, 82.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 86.0, 70.0, 66.0, 70.0, 85.0, 67.0, 69.0, 83.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 87.0, 90.0, 86.0, 90.0, 87.0, 120.0, 86.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 99.0, 97.0, 97.0, 97.0, 99.0, 97.0, 97.0, 98.0], "value": 97.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 85.0, 87.0, 88.0, 89.0, 86.0, 85.0, 86.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 59.0, 58.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 80.0, 79.0, 75.0, 71.0, 68.0, 69.0, 73.0, 100.0, 101.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 90.0, 88.0, 87.0, 87.0, 86.0, 90.0, 88.0, 90.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 142.0, 140.0, 137.0, 144.0, 156.0, 151.0, 179.0, 145.0], "value": 147.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 174.0, 172.0, 198.0, 227.0, 172.0, 180.0, 235.0, 192.0], "value": 190.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 69.0, 67.0, 67.0, 67.0, 66.0, 66.0, 77.0], "value": 68.1, "unit": "score"}]}]} 05:40:47 INFO - Return code: 0 05:40:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:40:47 ERROR - # TBPL SUCCESS # 05:40:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:40:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:40:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:40:47 INFO - 2017-02-06 05:40:47.114 screenresolution[1659:9723] starting screenresolution argv=screenresolution get 05:40:47 INFO - 2017-02-06 05:40:47.117 screenresolution[1659:9723] Display 0: 1600x1200x32@60 05:40:47 INFO - 2017-02-06 05:40:47.124 screenresolution[1660:9726] starting screenresolution argv=screenresolution list 05:40:47 INFO - Available Modes on Display 0 05:40:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:40:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:40:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:40:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:40:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:40:47 INFO - Intel Iris: 05:40:47 INFO - Chipset Model: Intel Iris 05:40:47 INFO - Type: GPU 05:40:47 INFO - Bus: Built-In 05:40:47 INFO - VRAM (Dynamic, Max): 1536 MB 05:40:47 INFO - Vendor: Intel (0x8086) 05:40:47 INFO - Device ID: 0x0a2e 05:40:47 INFO - Revision ID: 0x0009 05:40:47 INFO - Displays: 05:40:47 INFO - Display: 05:40:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:40:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:40:47 INFO - Display Serial Number: 1600x1200 60 05:40:47 INFO - Main Display: Yes 05:40:47 INFO - Mirror: Off 05:40:47 INFO - Online: Yes 05:40:47 INFO - Rotation: Supported 05:40:47 INFO - Return code: 0 05:40:47 INFO - Running post-action listener: _resource_record_post_action 05:40:47 INFO - [mozharness: 2017-02-06 13:40:47.368243Z] Finished run-tests step (success) 05:40:47 INFO - Running post-run listener: _resource_record_post_run 05:40:47 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 38867968; Write bytes: 554319872; Read time: 377; Write time: 1965 05:40:47 INFO - TinderboxPrint: CPU usage
53.8% 05:40:47 INFO - TinderboxPrint: I/O read bytes / time
38,867,968 / 377 05:40:47 INFO - TinderboxPrint: I/O write bytes / time
554,319,872 / 1,965 05:40:47 INFO - TinderboxPrint: CPU idle
2,220.1 (46.2%) 05:40:47 INFO - TinderboxPrint: CPU system
964.4 (20.1%) 05:40:47 INFO - TinderboxPrint: CPU user
1,625.2 (33.8%) 05:40:47 INFO - TinderboxPrint: Swap in / out
290,406,400 / 0 05:40:47 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244367872; Write bytes: 236564480; Read time: 17827; Write time: 412 05:40:47 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36815872; Write bytes: 300462080; Read time: 354; Write time: 1510 05:40:47 INFO - Running post-run listener: _upload_blobber_files 05:40:47 INFO - Blob upload gear active. 05:40:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:40:47 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 05:40:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:40:48 INFO - (blobuploader) - INFO - Open directory for files ... 05:40:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:40:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:40:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:49 INFO - (blobuploader) - INFO - Done attempting. 05:40:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:40:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:40:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:50 INFO - (blobuploader) - INFO - Done attempting. 05:40:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:40:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:40:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:51 INFO - (blobuploader) - INFO - Done attempting. 05:40:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:40:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:40:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:53 INFO - (blobuploader) - INFO - Done attempting. 05:40:53 INFO - (blobuploader) - INFO - Iteration through files over. 05:40:53 INFO - Return code: 0 05:40:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:40:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:40:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4ec1cca9fe0db0c7424507aa76071de07892a63b9e50e8f009046e104e5e563687df4ccc341ce5d2f1b52c97305f0bc85b2996be21b40fd51e510fa45fe4650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cc182524f90f61734864961dcece10d6dbc3c25718e2e271f804ee08acd518156a52bce2145b301da8746bfb4446fed575c6cfa5abb717cc7639a5359e15efe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85a8f863050ecbe37a085161eaff86eb4895f84734f5b75788b165e6722b5e34587420a11a8f36cb126e7e4eeb5e0364477693c55a3a252e23e2551f623df29", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af3467f39320667ee7249361055aee9e8bf75f24674192d22cd7c38205d2aee6dfc63d3d5e7cb098ce59d6c21b27ca56ea7786dbade747b52fede22cb86284af"} 05:40:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:40:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:40:53 INFO - Contents: 05:40:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4ec1cca9fe0db0c7424507aa76071de07892a63b9e50e8f009046e104e5e563687df4ccc341ce5d2f1b52c97305f0bc85b2996be21b40fd51e510fa45fe4650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cc182524f90f61734864961dcece10d6dbc3c25718e2e271f804ee08acd518156a52bce2145b301da8746bfb4446fed575c6cfa5abb717cc7639a5359e15efe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85a8f863050ecbe37a085161eaff86eb4895f84734f5b75788b165e6722b5e34587420a11a8f36cb126e7e4eeb5e0364477693c55a3a252e23e2551f623df29", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af3467f39320667ee7249361055aee9e8bf75f24674192d22cd7c38205d2aee6dfc63d3d5e7cb098ce59d6c21b27ca56ea7786dbade747b52fede22cb86284af"} 05:40:53 INFO - Running post-run listener: copy_logs_to_upload_dir 05:40:53 INFO - Copying logs to upload dir... 05:40:53 INFO - mkdir: /builds/slave/test/build/upload/logs 05:40:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.082160 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-06 05:40:53.523939) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 05:40:53.527681) ========= 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.gHyPyQyWiK/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.3nUOcVkq49/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4ec1cca9fe0db0c7424507aa76071de07892a63b9e50e8f009046e104e5e563687df4ccc341ce5d2f1b52c97305f0bc85b2996be21b40fd51e510fa45fe4650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cc182524f90f61734864961dcece10d6dbc3c25718e2e271f804ee08acd518156a52bce2145b301da8746bfb4446fed575c6cfa5abb717cc7639a5359e15efe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85a8f863050ecbe37a085161eaff86eb4895f84734f5b75788b165e6722b5e34587420a11a8f36cb126e7e4eeb5e0364477693c55a3a252e23e2551f623df29", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af3467f39320667ee7249361055aee9e8bf75f24674192d22cd7c38205d2aee6dfc63d3d5e7cb098ce59d6c21b27ca56ea7786dbade747b52fede22cb86284af"} build_url:https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010851 build_url: 'https://queue.taskcluster.net/v1/task/HiHEckFCT32vum1bCijW4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4ec1cca9fe0db0c7424507aa76071de07892a63b9e50e8f009046e104e5e563687df4ccc341ce5d2f1b52c97305f0bc85b2996be21b40fd51e510fa45fe4650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cc182524f90f61734864961dcece10d6dbc3c25718e2e271f804ee08acd518156a52bce2145b301da8746bfb4446fed575c6cfa5abb717cc7639a5359e15efe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85a8f863050ecbe37a085161eaff86eb4895f84734f5b75788b165e6722b5e34587420a11a8f36cb126e7e4eeb5e0364477693c55a3a252e23e2551f623df29", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af3467f39320667ee7249361055aee9e8bf75f24674192d22cd7c38205d2aee6dfc63d3d5e7cb098ce59d6c21b27ca56ea7786dbade747b52fede22cb86284af"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 05:40:53.561109) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 05:40:53.561440) ========= 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.gHyPyQyWiK/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.3nUOcVkq49/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005098 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 05:40:53.610101) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 05:40:53.610420) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 05:40:53.610741) ========= ========= Total master_lag: 0.18 =========