builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0342 starttime: 1490306957.67 results: success (0) buildid: 20170323111525 builduid: 2dbf2738ee2041269ad90b35d4aa6174 revision: fde19b9411ee0735e5e66dc02e68c815bdbe66bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:17.667579) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:17.668278) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:17.668766) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:17.697634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:17.698003) ========= 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.zXO0EcZS5T/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.bL1unno0VX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-03-23 15:09:17-- 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% 164M=0s 2017-03-23 15:09:17 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085471 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:17.807593) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:17.807907) ========= 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.zXO0EcZS5T/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.bL1unno0VX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.026278 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:17.855838) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 15:09:17.856203) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev fde19b9411ee0735e5e66dc02e68c815bdbe66bd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev fde19b9411ee0735e5e66dc02e68c815bdbe66bd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zXO0EcZS5T/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.bL1unno0VX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-03-23 15:09:17,918 truncating revision to first 12 chars 2017-03-23 15:09:17,918 Setting DEBUG logging. 2017-03-23 15:09:17,918 attempt 1/10 2017-03-23 15:09:17,918 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/fde19b9411ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-23 15:09:19,581 unpacking tar archive at: pine-fde19b9411ee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.931704 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 15:09:19.811360) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:19.811716) ========= script_repo_revision: fde19b9411ee0735e5e66dc02e68c815bdbe66bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:19.812175) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:19.812446) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 15:09:19.832455) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-23 15:09:19.832820) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zXO0EcZS5T/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.bL1unno0VX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:09:19 INFO - MultiFileLogger online at 20170323 15:09:19 in /builds/slave/test 15:09:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 15:09:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:09:19 INFO - {'append_to_log': False, 15:09:19 INFO - 'base_work_dir': '/builds/slave/test', 15:09:19 INFO - 'blob_upload_branch': 'Pine', 15:09:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:09:19 INFO - 'branch': 'Pine', 15:09:19 INFO - 'buildbot_json_path': 'buildprops.json', 15:09:19 INFO - 'config_files': ('talos/mac_config.py',), 15:09:19 INFO - 'default_actions': ('clobber', 15:09:19 INFO - 'read-buildbot-config', 15:09:19 INFO - 'download-and-extract', 15:09:19 INFO - 'populate-webroot', 15:09:19 INFO - 'create-virtualenv', 15:09:19 INFO - 'install', 15:09:19 INFO - 'run-tests'), 15:09:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:09:19 INFO - 'download_minidump_stackwalk': True, 15:09:19 INFO - 'download_symbols': 'ondemand', 15:09:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:09:19 INFO - 'tooltool.py': '/tools/tooltool.py', 15:09:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:09:19 INFO - '/tools/misc-python/virtualenv.py')}, 15:09:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:09:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:09:19 INFO - 'gecko_profile': False, 15:09:19 INFO - 'gecko_profile_interval': 0, 15:09:19 INFO - 'installer_path': 'installer.exe', 15:09:19 INFO - 'log_level': 'info', 15:09:19 INFO - 'log_name': 'talos', 15:09:19 INFO - 'log_to_console': True, 15:09:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:09:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:09:19 INFO - 'opt_config_files': (), 15:09:20 INFO - 'pip_index': False, 15:09:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:09:20 INFO - 'cmd': ('bash', 15:09:20 INFO - '-c', 15:09:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:09:20 INFO - 'enabled': True, 15:09:20 INFO - 'halt_on_failure': False, 15:09:20 INFO - 'name': 'check_screen_resolution'},), 15:09:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:09:20 INFO - 'cmd': ('bash', 15:09:20 INFO - '-c', 15:09:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:09:20 INFO - 'enabled': True, 15:09:20 INFO - 'halt_on_failure': False, 15:09:20 INFO - 'name': 'check_screen_resolution'},), 15:09:20 INFO - 'run_cmd_checks_enabled': True, 15:09:20 INFO - 'suite': 'dromaeojs', 15:09:20 INFO - 'system_bits': '32', 15:09:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:09:20 INFO - 'title': 't-yosemite-r7-0342', 15:09:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:09:20 INFO - 'use_talos_json': True, 15:09:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:09:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:09:20 INFO - 'work_dir': 'build'} 15:09:20 INFO - [mozharness: 2017-03-23 22:09:20.002509Z] Running clobber step. 15:09:20 INFO - Running pre-action listener: _resource_record_pre_action 15:09:20 INFO - Running main action method: clobber 15:09:20 INFO - rmtree: /builds/slave/test/build 15:09:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:09:22 INFO - Running post-action listener: _resource_record_post_action 15:09:22 INFO - [mozharness: 2017-03-23 22:09:22.433416Z] Finished clobber step (success) 15:09:22 INFO - [mozharness: 2017-03-23 22:09:22.433560Z] Running read-buildbot-config step. 15:09:22 INFO - Running pre-action listener: _resource_record_pre_action 15:09:22 INFO - Running main action method: read_buildbot_config 15:09:22 INFO - Using buildbot properties: 15:09:22 INFO - { 15:09:22 INFO - "project": "", 15:09:22 INFO - "product": "firefox", 15:09:22 INFO - "script_repo_revision": "default", 15:09:22 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 15:09:22 INFO - "repository": "", 15:09:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 15:09:22 INFO - "buildid": "20170323111525", 15:09:22 INFO - "pgo_build": "False", 15:09:22 INFO - "basedir": "/builds/slave/test", 15:09:22 INFO - "buildnumber": 0, 15:09:22 INFO - "slavename": "t-yosemite-r7-0342", 15:09:22 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:09:22 INFO - "platform": "yosemite_r7", 15:09:22 INFO - "scheduler": "tests-pine-macosx64-talos", 15:09:22 INFO - "branch": "pine", 15:09:22 INFO - "repo_path": "projects/pine", 15:09:22 INFO - "revision": "fde19b9411ee0735e5e66dc02e68c815bdbe66bd", 15:09:22 INFO - "stage_platform": "macosx64", 15:09:22 INFO - "builduid": "2dbf2738ee2041269ad90b35d4aa6174", 15:09:22 INFO - "slavebuilddir": "test" 15:09:22 INFO - } 15:09:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 15:09:22 INFO - Running post-action listener: _resource_record_post_action 15:09:22 INFO - [mozharness: 2017-03-23 22:09:22.436970Z] Finished read-buildbot-config step (success) 15:09:22 INFO - [mozharness: 2017-03-23 22:09:22.437077Z] Running download-and-extract step. 15:09:22 INFO - Running pre-action listener: _resource_record_pre_action 15:09:22 INFO - Running main action method: download_and_extract 15:09:22 INFO - mkdir: /builds/slave/test/build/tests 15:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:22 INFO - trying https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:09:22 INFO - Downloading https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:09:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 15:09:23 INFO - Downloaded 1325 bytes. 15:09:23 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:09:23 INFO - Using the following test package requirements: 15:09:23 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:09:23 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:09:23 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:09:23 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:09:23 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:09:23 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:09:23 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:09:23 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:09:23 INFO - u'jsshell-mac.zip'], 15:09:23 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:09:23 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:09:23 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:09:23 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:09:23 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:09:23 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:09:23 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:09:23 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:09:23 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:09:23 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:09:23 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:09:23 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:09:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:09:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:09:23 INFO - Fetch https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:09:25 INFO - Expected file size: 52406549 15:09:25 INFO - Obtained file size: 52406549 15:09:41 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:09:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:09:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:09:41 INFO - Fetch https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:09:43 INFO - Expected file size: 52406549 15:09:43 INFO - Obtained file size: 52406549 15:09:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:09:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:09:58 INFO - Fetch https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:10:00 INFO - Expected file size: 14404171 15:10:00 INFO - Obtained file size: 14404171 15:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:00 INFO - trying https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:10:00 INFO - Downloading https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:10:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 15:10:02 INFO - Downloaded 75533384 bytes. 15:10:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:10:02 INFO - mkdir: /builds/slave/test/properties 15:10:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:10:02 INFO - Writing to file /builds/slave/test/properties/build_url 15:10:02 INFO - Contents: 15:10:02 INFO - build_url:https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:10:03 INFO - Running post-action listener: _resource_record_post_action 15:10:03 INFO - Running post-action listener: set_extra_try_arguments 15:10:03 INFO - [mozharness: 2017-03-23 22:10:03.107465Z] Finished download-and-extract step (success) 15:10:03 INFO - [mozharness: 2017-03-23 22:10:03.107616Z] Running populate-webroot step. 15:10:03 INFO - Running pre-action listener: _resource_record_pre_action 15:10:03 INFO - Running main action method: populate_webroot 15:10:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:10:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:10:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:10:03 INFO - '--tppagecycles', 15:10:03 INFO - '7'], 15:10:03 INFO - 'tests': ['tsvgm']}}, 15:10:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:10:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:10:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:10:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:10:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:10:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:10:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:10:03 INFO - 'talos_options': ['--disable-e10s'], 15:10:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:10:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:10:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:10:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:10:03 INFO - 'talos_options': ['--disable-e10s'], 15:10:03 INFO - 'tests': ['damp', 'tps']}, 15:10:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:10:03 INFO - 'tests': ['damp', 'tps']}, 15:10:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:10:03 INFO - 'tests': ['dromaeo_dom']}, 15:10:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:10:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:10:03 INFO - 'tests': ['basic_compositor_video']}, 15:10:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:10:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:10:03 INFO - 'tests': ['a11yr', 15:10:03 INFO - 'ts_paint', 15:10:03 INFO - 'tpaint', 15:10:03 INFO - 'sessionrestore', 15:10:03 INFO - 'sessionrestore_no_auto_restore', 15:10:03 INFO - 'tabpaint']}, 15:10:03 INFO - 'other-e10s': {'tests': ['a11yr', 15:10:03 INFO - 'ts_paint', 15:10:03 INFO - 'tpaint', 15:10:03 INFO - 'sessionrestore', 15:10:03 INFO - 'sessionrestore_no_auto_restore', 15:10:03 INFO - 'tabpaint']}, 15:10:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:10:03 INFO - 'ts_paint', 15:10:03 INFO - 'tpaint', 15:10:03 INFO - 'sessionrestore', 15:10:03 INFO - 'sessionrestore_no_auto_restore', 15:10:03 INFO - 'tabpaint']}, 15:10:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:10:03 INFO - 'ts_paint', 15:10:03 INFO - 'tpaint', 15:10:03 INFO - 'sessionrestore', 15:10:03 INFO - 'sessionrestore_no_auto_restore', 15:10:03 INFO - 'tabpaint']}, 15:10:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:10:03 INFO - 'tests': ['a11yr', 15:10:03 INFO - 'ts_paint', 15:10:03 INFO - 'tpaint', 15:10:03 INFO - 'sessionrestore', 15:10:03 INFO - 'sessionrestore_no_auto_restore', 15:10:03 INFO - 'tabpaint']}, 15:10:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:10:03 INFO - 'tests': ['a11yr', 15:10:03 INFO - 'ts_paint', 15:10:03 INFO - 'tpaint', 15:10:03 INFO - 'sessionrestore', 15:10:03 INFO - 'sessionrestore_no_auto_restore', 15:10:03 INFO - 'tabpaint']}, 15:10:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:10:03 INFO - 'tests': ['tsvgx', 15:10:03 INFO - 'tsvgr_opacity', 15:10:03 INFO - 'tart', 15:10:03 INFO - 'tscrollx', 15:10:03 INFO - 'cart', 15:10:03 INFO - 'tsvg_static']}, 15:10:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:10:03 INFO - 'tsvgr_opacity', 15:10:03 INFO - 'tart', 15:10:03 INFO - 'tscrollx', 15:10:03 INFO - 'cart', 15:10:03 INFO - 'tsvg_static']}, 15:10:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:10:03 INFO - 'talos_options': ['--disable-e10s'], 15:10:03 INFO - 'tests': ['tp5o']}, 15:10:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:10:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:10:03 INFO - 'talos_options': ['--disable-e10s', 15:10:03 INFO - '--xperf_path', 15:10:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:10:03 INFO - 'tests': ['tp5n']}, 15:10:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:10:03 INFO - 'talos_options': ['--xperf_path', 15:10:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:10:03 INFO - 'tests': ['tp5n']}}, 15:10:03 INFO - 'talos.zip': {'path': '', 15:10:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:10:03 INFO - Running post-action listener: _resource_record_post_action 15:10:03 INFO - [mozharness: 2017-03-23 22:10:03.126061Z] Finished populate-webroot step (success) 15:10:03 INFO - [mozharness: 2017-03-23 22:10:03.126201Z] Running create-virtualenv step. 15:10:03 INFO - Running pre-action listener: _resource_record_pre_action 15:10:03 INFO - Running main action method: create_virtualenv 15:10:03 INFO - Creating virtualenv /builds/slave/test/build/venv 15:10:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:10:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:10:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:10:03 INFO - Using real prefix '/tools/python27' 15:10:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:10:03 INFO - Installing distribute.............................................................................................................................................................................................done. 15:10:06 INFO - Installing pip.................done. 15:10:06 INFO - Return code: 0 15:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:10:07 INFO - Reading from file tmpfile_stdout 15:10:07 INFO - Output received: 15:10:07 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) 15:10:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:10: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')]} 15:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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')]} 15:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101328b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10132b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3935a36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101106d30>, '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 0x10102ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010fbbe8>, '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.zXO0EcZS5T/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.bL1unno0VX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXO0EcZS5T/Render', 15:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:07 INFO - 'HOME': '/Users/cltbld', 15:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:07 INFO - 'LOGNAME': 'cltbld', 15:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:07 INFO - 'MOZ_NO_REMOTE': '1', 15:10:07 INFO - 'NO_EM_RESTART': '1', 15:10:07 INFO - 'PAGER': '/bin/cat', 15:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:07 INFO - 'PWD': '/builds/slave/test', 15:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:07 INFO - 'SHELL': '/bin/bash', 15:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bL1unno0VX/Listeners', 15:10:07 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 15:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:07 INFO - 'USER': 'cltbld', 15:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:07 INFO - 'XPC_FLAGS': '0x0', 15:10:07 INFO - 'XPC_SERVICE_NAME': '0', 15:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:10:07 INFO - Cleaning up... 15:10:07 INFO - Return code: 0 15:10:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:10: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')]} 15:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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')]} 15:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101328b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10132b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3935a36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101106d30>, '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 0x10102ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010fbbe8>, '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.zXO0EcZS5T/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.bL1unno0VX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXO0EcZS5T/Render', 15:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:07 INFO - 'HOME': '/Users/cltbld', 15:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:07 INFO - 'LOGNAME': 'cltbld', 15:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:07 INFO - 'MOZ_NO_REMOTE': '1', 15:10:07 INFO - 'NO_EM_RESTART': '1', 15:10:07 INFO - 'PAGER': '/bin/cat', 15:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:07 INFO - 'PWD': '/builds/slave/test', 15:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:07 INFO - 'SHELL': '/bin/bash', 15:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bL1unno0VX/Listeners', 15:10:07 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 15:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:07 INFO - 'USER': 'cltbld', 15:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:07 INFO - 'XPC_FLAGS': '0x0', 15:10:07 INFO - 'XPC_SERVICE_NAME': '0', 15:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:07 INFO - Downloading/unpacking psutil>=3.1.1 15:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:10:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:10:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:10:11 INFO - Installing collected packages: psutil 15:10:11 INFO - Running setup.py install for psutil 15:10:11 INFO - building 'psutil._psutil_osx' extension 15:10:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:10:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:10:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:10:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:10:12 INFO - building 'psutil._psutil_posix' extension 15:10:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:10:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:10:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:10:12 INFO - ^ 15:10:12 INFO - 1 warning generated. 15:10:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:10:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:10:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:10:12 INFO - Successfully installed psutil 15:10:12 INFO - Cleaning up... 15:10:12 INFO - Return code: 0 15:10:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:10:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101328b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10132b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3935a36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101106d30>, '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 0x10102ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010fbbe8>, '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.zXO0EcZS5T/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.bL1unno0VX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:10:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:10:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:10:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXO0EcZS5T/Render', 15:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:12 INFO - 'HOME': '/Users/cltbld', 15:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:12 INFO - 'LOGNAME': 'cltbld', 15:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:12 INFO - 'MOZ_NO_REMOTE': '1', 15:10:12 INFO - 'NO_EM_RESTART': '1', 15:10:12 INFO - 'PAGER': '/bin/cat', 15:10:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:12 INFO - 'PWD': '/builds/slave/test', 15:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:12 INFO - 'SHELL': '/bin/bash', 15:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bL1unno0VX/Listeners', 15:10:12 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 15:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:12 INFO - 'USER': 'cltbld', 15:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:12 INFO - 'XPC_FLAGS': '0x0', 15:10:12 INFO - 'XPC_SERVICE_NAME': '0', 15:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:10:15 INFO - Installing collected packages: mozsystemmonitor 15:10:15 INFO - Running setup.py install for mozsystemmonitor 15:10:15 INFO - Successfully installed mozsystemmonitor 15:10:15 INFO - Cleaning up... 15:10:15 INFO - Return code: 0 15:10:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:10: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')]} 15:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:16 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 0x101328b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10132b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3935a36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101106d30>, '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 0x10102ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010fbbe8>, '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.zXO0EcZS5T/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.bL1unno0VX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:10:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:10:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXO0EcZS5T/Render', 15:10:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:16 INFO - 'HOME': '/Users/cltbld', 15:10:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:16 INFO - 'LOGNAME': 'cltbld', 15:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:16 INFO - 'MOZ_NO_REMOTE': '1', 15:10:16 INFO - 'NO_EM_RESTART': '1', 15:10:16 INFO - 'PAGER': '/bin/cat', 15:10:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:16 INFO - 'PWD': '/builds/slave/test', 15:10:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:16 INFO - 'SHELL': '/bin/bash', 15:10:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bL1unno0VX/Listeners', 15:10:16 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 15:10:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:16 INFO - 'USER': 'cltbld', 15:10:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:16 INFO - 'XPC_FLAGS': '0x0', 15:10:16 INFO - 'XPC_SERVICE_NAME': '0', 15:10:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:16 INFO - Downloading/unpacking jsonschema==2.5.1 15:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:10:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:20 INFO - Downloading functools32-3.2.3-2.tar.gz 15:10:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:10:20 INFO - warning: no files found matching '*.txt' 15:10:20 INFO - no previously-included directories found matching 'build' 15:10:20 INFO - no previously-included directories found matching 'dist' 15:10:20 INFO - no previously-included directories found matching '.git*' 15:10:20 INFO - Installing collected packages: jsonschema, functools32 15:10:20 INFO - Running setup.py install for functools32 15:10:20 INFO - warning: no files found matching '*.txt' 15:10:20 INFO - no previously-included directories found matching 'build' 15:10:20 INFO - no previously-included directories found matching 'dist' 15:10:20 INFO - no previously-included directories found matching '.git*' 15:10:20 INFO - Successfully installed jsonschema functools32 15:10:20 INFO - Cleaning up... 15:10:20 INFO - Return code: 0 15:10:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:10: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')]} 15:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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')]} 15:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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', '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 0x101328b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10132b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3935a36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101106d30>, '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 0x10102ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010fbbe8>, '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.zXO0EcZS5T/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.bL1unno0VX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:10: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:10: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 functools32==3.2.3-2 15:10:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXO0EcZS5T/Render', 15:10:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:20 INFO - 'HOME': '/Users/cltbld', 15:10:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:20 INFO - 'LOGNAME': 'cltbld', 15:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:20 INFO - 'MOZ_NO_REMOTE': '1', 15:10:20 INFO - 'NO_EM_RESTART': '1', 15:10:20 INFO - 'PAGER': '/bin/cat', 15:10:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:20 INFO - 'PWD': '/builds/slave/test', 15:10:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:20 INFO - 'SHELL': '/bin/bash', 15:10:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bL1unno0VX/Listeners', 15:10:20 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 15:10:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:20 INFO - 'USER': 'cltbld', 15:10:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:20 INFO - 'XPC_FLAGS': '0x0', 15:10:20 INFO - 'XPC_SERVICE_NAME': '0', 15:10:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:10:20 INFO - Cleaning up... 15:10:20 INFO - Return code: 0 15:10:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:10: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')]} 15:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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')]} 15:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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', '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 0x101328b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10132b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3935a36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101106d30>, '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 0x10102ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010fbbe8>, '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.zXO0EcZS5T/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.bL1unno0VX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:10: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:10: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 blobuploader==1.2.4 15:10:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXO0EcZS5T/Render', 15:10:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:20 INFO - 'HOME': '/Users/cltbld', 15:10:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:20 INFO - 'LOGNAME': 'cltbld', 15:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:20 INFO - 'MOZ_NO_REMOTE': '1', 15:10:20 INFO - 'NO_EM_RESTART': '1', 15:10:20 INFO - 'PAGER': '/bin/cat', 15:10:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:20 INFO - 'PWD': '/builds/slave/test', 15:10:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:20 INFO - 'SHELL': '/bin/bash', 15:10:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bL1unno0VX/Listeners', 15:10:20 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 15:10:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:20 INFO - 'USER': 'cltbld', 15:10:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:20 INFO - 'XPC_FLAGS': '0x0', 15:10:20 INFO - 'XPC_SERVICE_NAME': '0', 15:10:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:21 INFO - Downloading/unpacking blobuploader==1.2.4 15:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:23 INFO - Downloading blobuploader-1.2.4.tar.gz 15:10:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:10:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:10: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 15:10: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 15:10: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 15:10: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 15:10: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 15:10: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 15:10:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:10:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:24 INFO - Downloading docopt-0.6.1.tar.gz 15:10:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:10:25 INFO - Installing collected packages: blobuploader, requests, docopt 15:10:25 INFO - Running setup.py install for blobuploader 15:10:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:10:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:10:25 INFO - Running setup.py install for requests 15:10:25 INFO - Running setup.py install for docopt 15:10:25 INFO - Successfully installed blobuploader requests docopt 15:10:25 INFO - Cleaning up... 15:10:25 INFO - Return code: 0 15:10:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:25 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 0x101328b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10132b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3935a36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101106d30>, '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 0x10102ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010fbbe8>, '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.zXO0EcZS5T/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.bL1unno0VX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:10:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:10:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:10:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXO0EcZS5T/Render', 15:10:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:25 INFO - 'HOME': '/Users/cltbld', 15:10:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:25 INFO - 'LOGNAME': 'cltbld', 15:10:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:25 INFO - 'MOZ_NO_REMOTE': '1', 15:10:25 INFO - 'NO_EM_RESTART': '1', 15:10:25 INFO - 'PAGER': '/bin/cat', 15:10:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:25 INFO - 'PWD': '/builds/slave/test', 15:10:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:25 INFO - 'SHELL': '/bin/bash', 15:10:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bL1unno0VX/Listeners', 15:10:25 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 15:10:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:25 INFO - 'USER': 'cltbld', 15:10:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:25 INFO - 'XPC_FLAGS': '0x0', 15:10:25 INFO - 'XPC_SERVICE_NAME': '0', 15:10:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:10:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-cy0mFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:10:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-gVW4Dw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:10:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-98GH0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:10:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-zhUymH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:10:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-naZ7sA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:10:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-2G2And-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:10:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-jAYpxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:10:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-6GUWtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:10:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-w3oCBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:10:27 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-AGfNrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:10:27 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-SzBOik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:10:27 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-PqeyCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:10:27 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-cMLUx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:10:27 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-tD2FHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:10:27 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-8P7cRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:10:27 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-soZjjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:10:27 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-FbPFYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:10:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:10:27 INFO - Running setup.py install for manifestparser 15:10:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:10:28 INFO - Running setup.py install for mozcrash 15:10:28 INFO - Running setup.py install for mozdebug 15:10:28 INFO - Running setup.py install for mozdevice 15:10:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:10:28 INFO - Running setup.py install for mozfile 15:10:28 INFO - Running setup.py install for mozhttpd 15:10:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:10:28 INFO - Running setup.py install for mozinfo 15:10:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:10:28 INFO - Running setup.py install for mozInstall 15:10:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:10:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:10:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:10:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:10:29 INFO - Running setup.py install for mozleak 15:10:29 INFO - Running setup.py install for mozlog 15:10:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:10:29 INFO - Running setup.py install for moznetwork 15:10:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:10:29 INFO - Running setup.py install for mozprocess 15:10:29 INFO - Running setup.py install for mozprofile 15:10:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:10:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:10:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:10:29 INFO - Running setup.py install for mozrunner 15:10:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:10:29 INFO - Running setup.py install for mozscreenshot 15:10:30 INFO - Running setup.py install for moztest 15:10:30 INFO - Running setup.py install for mozversion 15:10:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:10:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:10:30 INFO - Cleaning up... 15:10:30 INFO - Return code: 0 15:10:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:30 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 0x101328b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10132b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3935a36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101106d30>, '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 0x10102ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010fbbe8>, '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.zXO0EcZS5T/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.bL1unno0VX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:10:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:10:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:10:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXO0EcZS5T/Render', 15:10:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:30 INFO - 'HOME': '/Users/cltbld', 15:10:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:30 INFO - 'LOGNAME': 'cltbld', 15:10:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:30 INFO - 'MOZ_NO_REMOTE': '1', 15:10:30 INFO - 'NO_EM_RESTART': '1', 15:10:30 INFO - 'PAGER': '/bin/cat', 15:10:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:30 INFO - 'PWD': '/builds/slave/test', 15:10:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:30 INFO - 'SHELL': '/bin/bash', 15:10:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bL1unno0VX/Listeners', 15:10:30 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 15:10:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:30 INFO - 'USER': 'cltbld', 15:10:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:30 INFO - 'XPC_FLAGS': '0x0', 15:10:30 INFO - 'XPC_SERVICE_NAME': '0', 15:10:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:10:30 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-5IPjQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:10:30 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-X0T4ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:10:30 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)) 15:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:10:30 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-uYh_Sb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:10:31 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-SWFKTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:10:31 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)) 15:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:10:31 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-WUp6Vp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:10:31 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-qwdVQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:10:31 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Cabfw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:10:31 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-72EbgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:10:31 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9tF_al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:10:31 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-lYBVAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:10:31 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)) 15:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:10:31 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-S7GOd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:10:31 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-_V_R8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:10:31 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-FIjns7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:10:32 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-hJgHTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:10:32 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)) 15:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:10:32 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-1j76U5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:10:32 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-YUu7x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:10:32 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)) 15:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:10:32 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-sUz6Ya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:10:32 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)) 15:10:32 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)) 15:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:10:32 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)) 15:10:32 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)) 15:10:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:35 INFO - Downloading blessings-1.6.tar.gz 15:10:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:10:35 INFO - Installing collected packages: blessings 15:10:35 INFO - Running setup.py install for blessings 15:10:35 INFO - Successfully installed blessings 15:10:35 INFO - Cleaning up... 15:10:35 INFO - Return code: 0 15:10:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:10:35 INFO - Reading from file tmpfile_stdout 15:10:35 INFO - Current package versions: 15:10:35 INFO - blessings == 1.6 15:10:35 INFO - blobuploader == 1.2.4 15:10:35 INFO - docopt == 0.6.1 15:10:35 INFO - functools32 == 3.2.3-2 15:10:35 INFO - jsonschema == 2.5.1 15:10:35 INFO - manifestparser == 1.1 15:10:35 INFO - mozInstall == 1.12 15:10:35 INFO - mozcrash == 1.0 15:10:35 INFO - mozdebug == 0.1 15:10:35 INFO - mozdevice == 0.49 15:10:35 INFO - mozfile == 1.2 15:10:35 INFO - mozhttpd == 0.7 15:10:35 INFO - mozinfo == 0.9 15:10:35 INFO - mozleak == 0.1 15:10:35 INFO - mozlog == 3.4 15:10:35 INFO - moznetwork == 0.27 15:10:35 INFO - mozprocess == 0.25 15:10:35 INFO - mozprofile == 0.28 15:10:35 INFO - mozrunner == 6.13 15:10:35 INFO - mozscreenshot == 0.1 15:10:35 INFO - mozsystemmonitor == 0.3 15:10:35 INFO - moztest == 0.8 15:10:35 INFO - mozversion == 1.4 15:10:35 INFO - psutil == 3.1.1 15:10:35 INFO - requests == 1.2.3 15:10:35 INFO - wsgiref == 0.1.2 15:10:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:35 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 0x101328b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10132b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3935a36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101106d30>, '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 0x10102ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010fbbe8>, '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.zXO0EcZS5T/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.bL1unno0VX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:10:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXO0EcZS5T/Render', 15:10:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:35 INFO - 'HOME': '/Users/cltbld', 15:10:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:35 INFO - 'LOGNAME': 'cltbld', 15:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:35 INFO - 'MOZ_NO_REMOTE': '1', 15:10:35 INFO - 'NO_EM_RESTART': '1', 15:10:35 INFO - 'PAGER': '/bin/cat', 15:10:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:35 INFO - 'PWD': '/builds/slave/test', 15:10:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:35 INFO - 'SHELL': '/bin/bash', 15:10:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bL1unno0VX/Listeners', 15:10:35 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 15:10:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:35 INFO - 'USER': 'cltbld', 15:10:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:35 INFO - 'XPC_FLAGS': '0x0', 15:10:35 INFO - 'XPC_SERVICE_NAME': '0', 15:10:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:10:36 INFO - Cleaning up... 15:10:36 INFO - Return code: 0 15:10:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:36 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 0x101328b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10132b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3935a36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101106d30>, '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 0x10102ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010fbbe8>, '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.zXO0EcZS5T/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.bL1unno0VX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:10:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:10:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXO0EcZS5T/Render', 15:10:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:36 INFO - 'HOME': '/Users/cltbld', 15:10:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:36 INFO - 'LOGNAME': 'cltbld', 15:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:36 INFO - 'MOZ_NO_REMOTE': '1', 15:10:36 INFO - 'NO_EM_RESTART': '1', 15:10:36 INFO - 'PAGER': '/bin/cat', 15:10:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:36 INFO - 'PWD': '/builds/slave/test', 15:10:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:36 INFO - 'SHELL': '/bin/bash', 15:10:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bL1unno0VX/Listeners', 15:10:36 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 15:10:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:36 INFO - 'USER': 'cltbld', 15:10:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:36 INFO - 'XPC_FLAGS': '0x0', 15:10:36 INFO - 'XPC_SERVICE_NAME': '0', 15:10:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:10:36 INFO - Cleaning up... 15:10:36 INFO - Return code: 0 15:10:36 INFO - Running post-action listener: _resource_record_post_action 15:10:36 INFO - Running post-action listener: _start_resource_monitoring 15:10:36 INFO - Starting resource monitoring. 15:10:36 INFO - [mozharness: 2017-03-23 22:10:36.389707Z] Finished create-virtualenv step (success) 15:10:36 INFO - [mozharness: 2017-03-23 22:10:36.390647Z] Running install step. 15:10:36 INFO - Running pre-action listener: _resource_record_pre_action 15:10:36 INFO - Running main action method: install 15:10:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:10:36 INFO - Reading from file tmpfile_stdout 15:10:36 INFO - Detecting whether we're running mozinstall >=1.0... 15:10:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:10:36 INFO - Reading from file tmpfile_stdout 15:10:36 INFO - Output received: 15:10:36 INFO - Usage: mozinstall [options] installer 15:10:36 INFO - Options: 15:10:36 INFO - -h, --help show this help message and exit 15:10:36 INFO - -d DEST, --destination=DEST 15:10:36 INFO - Directory to install application into. [default: 15:10:36 INFO - "/builds/slave/test"] 15:10:36 INFO - --app=APP Application being installed. [default: firefox] 15:10:36 INFO - mkdir: /builds/slave/test/build/application 15:10:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:10:56 INFO - Reading from file tmpfile_stdout 15:10:56 INFO - Output received: 15:10:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:10:56 INFO - Running post-action listener: _resource_record_post_action 15:10:56 INFO - [mozharness: 2017-03-23 22:10:56.731882Z] Finished install step (success) 15:10:56 INFO - [mozharness: 2017-03-23 22:10:56.732027Z] Running run-tests step. 15:10:56 INFO - Running pre-action listener: _resource_record_pre_action 15:10:56 INFO - Running main action method: run_tests 15:10:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:10:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:10:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:10:56 INFO - 2017-03-23 15:10:56.746 screenresolution[1623:7483] starting screenresolution argv=screenresolution get 15:10:56 INFO - 2017-03-23 15:10:56.749 screenresolution[1623:7483] Display 0: 1600x1200x32@60 15:10:56 INFO - 2017-03-23 15:10:56.755 screenresolution[1624:7486] starting screenresolution argv=screenresolution list 15:10:56 INFO - Available Modes on Display 0 15:10:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:10:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:10:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:10:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:10:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:10:56 INFO - Intel Iris: 15:10:56 INFO - Chipset Model: Intel Iris 15:10:56 INFO - Type: GPU 15:10:56 INFO - Bus: Built-In 15:10:56 INFO - VRAM (Dynamic, Max): 1536 MB 15:10:56 INFO - Vendor: Intel (0x8086) 15:10:56 INFO - Device ID: 0x0a2e 15:10:56 INFO - Revision ID: 0x0009 15:10:56 INFO - Displays: 15:10:56 INFO - Display: 15:10:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:10:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:10:56 INFO - Display Serial Number: 1600x1200 60 15:10:56 INFO - Main Display: Yes 15:10:56 INFO - Mirror: Off 15:10:56 INFO - Online: Yes 15:10:56 INFO - Rotation: Supported 15:10:56 INFO - Return code: 0 15:10:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:10:56 INFO - Python 2.7.3 15:10:56 INFO - Return code: 0 15:10:56 INFO - grabbing minidump binary from tooltool 15:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:56 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 0x101106d30>, '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 0x10102ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010fbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:10:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:10:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:10:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:10:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:10:57 INFO - Return code: 0 15:10:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:10:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:10:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:10:57 INFO - ENV: RUST_BACKTRACE is now 1 15:10:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:10:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:10:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 15:10:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0342 --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 15:10:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zXO0EcZS5T/Render', 15:10:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:57 INFO - 'HOME': '/Users/cltbld', 15:10:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:57 INFO - 'LOGNAME': 'cltbld', 15:10:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:10:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:57 INFO - 'MOZ_NO_REMOTE': '1', 15:10:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:10:57 INFO - 'NO_EM_RESTART': '1', 15:10:57 INFO - 'PAGER': '/bin/cat', 15:10:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:57 INFO - 'PWD': '/builds/slave/test', 15:10:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:10:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:57 INFO - 'RUST_BACKTRACE': '1', 15:10:57 INFO - 'SHELL': '/bin/bash', 15:10:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bL1unno0VX/Listeners', 15:10:57 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 15:10:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:57 INFO - 'USER': 'cltbld', 15:10:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:57 INFO - 'XPC_FLAGS': '0x0', 15:10:57 INFO - 'XPC_SERVICE_NAME': '0', 15:10:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 15:10:57 INFO - mozversion application_buildid: 20170323111525 15:10:57 INFO - mozversion application_changeset: fde19b9411ee0735e5e66dc02e68c815bdbe66bd 15:10:57 INFO - mozversion application_display_name: Nightly 15:10:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:10:57 INFO - mozversion application_name: Firefox 15:10:57 INFO - mozversion application_remotingname: firefox 15:10:57 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 15:10:57 INFO - mozversion application_vendor: Mozilla 15:10:57 INFO - mozversion application_version: 55.0a1 15:10:57 INFO - mozversion platform_buildid: 20170323111525 15:10:57 INFO - mozversion platform_changeset: fde19b9411ee0735e5e66dc02e68c815bdbe66bd 15:10:57 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 15:10:57 INFO - mozversion platform_version: 55.0a1 15:10:57 INFO - using testdate: 1490307057 15:10:57 INFO - actual date: 1490307057 15:10:57 INFO - starting webserver on 'localhost:49243' 15:10:57 INFO - SUITE-START | Running 2 tests 15:10:57 INFO - TEST-START | dromaeo_css 15:10:57 INFO - operating with platform_type : mac_ 15:10:57 INFO - Initialising browser for dromaeo_css test... 15:10:57 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmp0OSI0D/profile http://localhost:49243/getInfo.html) 15:10:59 INFO - PID 1634 | __metrics Screen width/height:1600/1200 15:10:59 INFO - PID 1634 | colorDepth:24 15:10:59 INFO - PID 1634 | Browser inner width/height: 1024/689 15:10:59 INFO - PID 1634 | __metrics 15:10:59 INFO - PID 1634 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:10:59 INFO - PID 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:11:00 INFO - TEST-INFO | 1634: exit 0 15:11:00 INFO - Browser initialized. 15:11:00 INFO - Running cycle 1/1 for dromaeo_css test... 15:11:00 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmp0OSI0D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:11:13 INFO - PID 1636 | RSS: Main: 253034496 15:11:13 INFO - PID 1636 | 15:14:18 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 15:14:20 INFO - PID 1636 | RSS: Main: 401063936 15:14:20 INFO - PID 1636 | 15:16:02 INFO - PID 1636 | ************************* 15:16:02 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 15:16:02 INFO - PID 1636 | 15:16:02 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:16:02 INFO - PID 1636 | Full stack: 15:16:02 INFO - PID 1636 | ************************* 15:17:23 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 15:17:24 INFO - PID 1636 | RSS: Main: 381693952 15:17:24 INFO - PID 1636 | 15:20:26 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 15:20:27 INFO - PID 1636 | RSS: Main: 369799168 15:20:27 INFO - PID 1636 | 15:23:29 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 15:23:30 INFO - PID 1636 | RSS: Main: 350822400 15:23:30 INFO - PID 1636 | 15:26:34 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 15:26:34 INFO - PID 1636 | RSS: Main: 347414528 15:26:34 INFO - PID 1636 | 15:29:37 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 15:29:38 INFO - PID 1636 | RSS: Main: 639926272 15:29:38 INFO - PID 1636 | 15:29:38 INFO - PID 1636 | __start_tp_report 15:29:38 INFO - PID 1636 | _x_x_mozilla_page_load 15:29:38 INFO - PID 1636 | _x_x_mozilla_page_load_details 15:29:38 INFO - PID 1636 | |i|pagename|runs| 15:29:38 INFO - PID 1636 | |0;dojo.html;3452.2750222453283;3515.0157955772393;3520.419130843411;3530.13511689636;3564;5999.880002399949;6096.315032485248;6147.524594097406;6227.28386235583;6246.718897649605;6043.576949613529;6101.126216676348;6184.505239580834;6185.319614842364;6201.224846894138;5402.297701298826;5553.222548843165;5556.333240011184;5568.888622227553;5661.46216109471;5416.539594134503;5478.890422191554;5583.776648934037;5597.832065038056;5653.434656534334;5440.183972404131;5537.695426751527;5573.637713548606;5626.6624002559565;5639.210510528529;5519.724013799294;5521.420250873641;5535.6955367454775;5544.500994910478;5552.916706249369;5485.780568777244;5535.169724541311;5537.224788529609;5542.972285138589;5571.1086226203615;6009.06859436788;6080.452759251644;6087.512998960073;6126.925569569743;6228.439440450381;6522.249941256769;6536.150300460958;6537.052127441542;6562.376574225441;6564.442022428099;6588.835279118012;6610.173728283965;6648.401643852077;6649.401553860176;6675.6662166891465;5157.762137087072;6146.5390095743;6159.4456498914415;6166.660833654102;6279.96860015697;5931.228940237742;6499.902501462482;6529.412352888168;6596.274409814917;6809.216940051859;6328.335524769925;6331.461825744863;6334.9683251584365;6346.30190679025;6387.425131738166;6505.121808555879;6630.801075967729;6638.336166383323;6653.833654158685;6663.06717059612;6527.967360163264;6533.085368048477;6548.770793022221;6555.246146693191;6561.343865613496;6278.30938596754;6330.620162790247;6342;6568.605883646901;6595.769148079794;5302.443243459458;5322.520973112439;5330.946690533045;5332.120200166929;5358.089124848785;4674.74288914113;4708.246680531099;4721.763911804464;4738.099761045391;4783.1868582341085;5306.204069389622;6252.562320637603;6273.278572964167;6274.152989346379;6285.748570057238;6342;6390.520710946697;6396.328385519547;6590;6598.934010659832;6207.937920620826;6266.185395898504;6287.528435367366;6294.874102517924;6304;5807.332156802022;5839.722298529098;5851.912221316684;5852.180694702745;5895.970520147372;6483;6715.3620406061345;6769.289224631343;6797.864042719019;6820.693068812015;6377.585456945284;6377.713002914973;6588.7364505421165;6646.368594983469;6656.068150458843;6773.051772751917;6809;6813.318668133147;6814.454843612598;6821.2155602106395;6485.3514648534765;6535.085088087577;6551.4103730664465;6557.573757705733;6567.2447668518735;5609.74756135983;6417.197850268716;6468.191476065492;6501.479881609365;6537.28089910119;6338.8732225354315;6375.5218358624215;6376;6380.330065343072;6390.808275751735;6251.593646412968;6280.905786413116;6345.555811093179;6346.90479642815;6363.522735794926;6306.936930630818;6325.841853953688;6330.52521060913;6335.303116657072;6336.556441049082;6253.155823963707;6315;6432.517561182836;6433.356664333529;6523.869522609616;6206.503479721701;6249.187605611241;6260.843478913063;6293.150424692791;6325.4939604832425 15:29:38 INFO - PID 1636 | |1;ext.html;754.8339365339626;796.092454601754;813.4753084260652;1125.510402974706;1146.5814977533203;27619.171424857235;36585;37051.25897482052;37122.329495172715;37269.44095838565;38497.42253866187;38747.806260968726;38766.41850372247;38816.41775373372;38817.80591097048;5549.916751248735;7377.930200120979;8798.472091674488;8808.471491710518;8828.24959878411;6180.351063138373;8374.58127093646;8596.226339629433;8596.699115530957;8602.18279263469;8602.698905538307;8635.69775057873;8664.220220180214;8666.480011199317;8675.826483470326;6131.294901086388;8592.14078592142;8638.481691098554;8650.091740367297;8661.393702440831;6143.232095988002;8290.917090829074;8479.618417171243;8489;8498.362622803254;37591.436128458095;37931;38016.23967520662;38037.23925521474;38102;2658.3088397016722;2928.165472840252;2960.881564737408;2963.3184367595572;2967.5845381646373;27682.307942301602;27701.03046393387;27748.167554973385;28164.43671126566;28213.576796348072;2739.493193759161;2788.776897848167;2823.4612136385717;2859.9857000714865;2861.427714457117;293910;294511;294840.5257973718;295001;295260.52369738015;48309.5169048312;48376;48400;48430.789230269525;48442;51280.23079653808;51387.48612513902;51396;51468.74265628648;51497;14345.426182952566;14633.658614621821;14647.853521464862;14666.046706964013;14679.339429725687;10068.748281293027;10236.744081398025;10831.945840270748;10918.672439826818;11863.881361186277;11608.65174044763;13149.079564430589;13282.800757988638;13402.195868247936;13407.396667150002;5870.033538764476;5985.910211346833;6014.127951447016;6019.247594050744;6076.3316035236085;927.5084205371164;929.2519521785028;935.2891802230354;950.2208189284859;955.2739917662626;22241.332760017227;22289.885505724647;27350.72649273482;28214;28387.858060709565;1011.7470632341915;1020.2450186861818;1062.86182796236;1070.2187403195555;1078.9082927951056;10634.946825265979;12023.74592459823;12087.939560302319;12183.695407614703;12196.743602433578;497196;499957;500136;500705;501337;55622.44377556253;55728.164077538866;55744.21278936037;55927.16109258364;56083.43916560864;1472.1314424489603;1508.0649997001929;1512.9243537823286;1523.0024334061209;1530.7703844423424;21717.782822171575;23461.06155753819;23464.530709385373;23489.295321140395;23516.529669406857;31225.531617026216;31476.685233147375;37961.62038379581;38091.61908380881;38229.61770382372;471941;472046;472095;472795;472838;32540.51189232116;33002.83498582492;33193.502097468074;33240.1689957753;33256.667433325354;31066.378672425973;31091.533626996064;36599.634003660685;38824.6117538821;39576.80211598924;27245.72754272511;27440.588391173747;27842.582361265002;28186.29534261578;28874.27814304659;27743.44513109686;28455.857720711265;28744.856275718488;28813.855930720212;28989;33685.66314336923;33745.83127084349;33997.830010849786;34263;35154.296914061066;24899.75100249047;24911.75441227895;29515;29623.555646664885;29942;18322.488493889796;21593.13627454884;21868.56262874702;22928;23202.071917123125 15:29:38 INFO - PID 1636 | |2;jquery.html;387.4498212538196;428.43660586328974;433.4560127040564;440.20762627270915;455.5194270045103;18723.438296851098;20524.38426847195;21035.263765768163;21322.57354852904;22088.686696995457;17786.911065444656;21200.787992120073;22245.777542224572;22403.887980560074;22467;6013.609115407485;6267.9686601567155;6282.4974002079725;6288.9056664150075;6312.9368706313035;5942.227510423639;6122.418370254819;6157.353477884825;6206.968965155191;6221.720022598994;6167;6175.6294622322575;6178.41305076017;6179.227596550431;6186.907196392058;5904.321003084636;6100.389961003909;6210.975189093794;6214.90677639838;6215.005599104122;5308.256844041799;5374.838754837361;5513.3108361454815;5516.586256030773;5522.475364840334;26335.73664263352;26586.734132658617;26590;26847.194584162506;26917.461650767073;2007.8494112941441;2009.5679428922706;2011.426743378145;2022.6359255334035;2036.6944958256381;15400.845991540053;15509.922450387789;15668.294926728324;15796.684066318609;16088.356465741357;1573.8111426628764;1627.560558649158;1632.7142750018818;1638.6394993101617;1655.606146276628;131977.34011329978;136103.79221558323;136925.31537342348;138477.3076134623;140040.29979850136;11538.423078846192;11756.1182911282;11775.175737698279;11893.108016898766;12090.879091209152;15993.36026558948;16001.679966400607;16010.03939763618;16010.759838602431;16034;7305.159906610807;8354.498730076215;8566.314694824523;8746.606402711894;8881.333899957399;8327.791805204919;8336.374771892011;8495.36284778644;8522.616482258314;8541.583366653264;6113.724882380304;7239.348458638748;7359.705611775469;7420.703171873065;7462.179160292363;2099.3806826986074;2119.0887918195326;2126.6384714598557;2127.2022991378235;2138.743350797883;825.451075035095;832.6169961817511;885.7918389178537;902.4991129922824;903.6159632156307;19362.90318548398;20158.496037598885;20253.08861101254;20254.79745202529;20619.69070463944;927.6104036304769;942.6936245720168;950.353759443585;970.5875003123786;970.8640790289365;10054.594632359616;10290.536925838354;10322.690319290283;10349.896501034893;10368.585256589651;645950;674782;674896;688686;696293;105050.08159782104;122501;131530.34234828764;141048;147335;1095.397531357766;1168.485866218844;1179.3572502985894;1179.9823002654966;1183.355071486042;18513.53716157053;19861.673363906815;20729.585408292052;21006;21151.788482114982;59750;59769.701151493966;59849.72329288967;60407.09389359251;61148.69425653021;84642;84645.15354846373;85209.721854171;85417.32283464567;86261.56869215614;8302.418830681794;8469.872951905603;8496.745097647081;8569.185927336894;8692.39153259291;12366.814497782361;12590.559330423392;12616.55842045524;12689.492420303084;13122;8870.556472176237;11330.036946859609;11339.943300283447;11463.942680286546;11566.653000409664;9438.52807359643;9846.950765246127;9993.550290237103;10201.285909986229;10305.484725763532;9484.905150948402;11193.27243729155;11193.440327983732;11379.317240965569;11494.655160345203;7587;8090.757277281691;8232.958835205785;8439.198276163756;8507.91492085096;8206.835863282582;10180.745481363025;10404.58381664725;10592.258541902302;10642.627508037182 15:29:38 INFO - PID 1636 | |3;mootools.html;1157.525414580022;1433.6845893903337;1453.2951518513526;1453.7819327100926;1454.0475988227709;10463.215258855587;10912.290701104432;10914.727131821708;10933.945330273338;10936.45317734113;10489.895101048987;10896.836547451774;10899.400532970683;10911.672649820499;10912.23614346994;2431.1247950737675;2494.1769216158623;2494.6756921600168;2495.4759500504915;2497.2633073243346;2060.9072591733407;2137.508373074194;2145.495808485005;2377.2749311459997;2415.335782659774;2110.4090854560745;2143.485563464773;2144.04589957469;2144.7211862457857;2145;2089.7283353164144;2366.95266094678;2454.312792418126;2462.630605409185;2466.6793316868784;2411.144043864438;2430.1373012580634;2439.1828737372825;2441.1578005588044;2441.7802397784108;10685.78628427427;10937.945310273397;11130.944345278303;11143.665690029313;11145.944270278596;6158.199434073586;6384.904226436607;6388.64862432566;6388.840278993016;6401.07184458251;3995.7802320872343;4015.4779878615886;4051.6961227908023;4059.939100913489;4089.5092588889224;999.2655398282257;1175.8882906123904;1208.8477366255174;1234.876512348767;1237.7462620162844;13749.831264342443;14138.717225655531;15154.014989025678;15331.923340383226;15380.61548461275;5138.640295179302;5278.788848446096;5336.706481143575;5408.594355423359;5417.756200970965;11531.711707207387;11553.36456494884;11554.306741595532;11565.537378504934;11582.826257606142;6282.246130464374;6284.497240220771;6287.528435367366;6292.307846136921;6301.495880329563;5377.704226267515;5832.270966129234;5887.0580707086665;5890.617109887844;5929.703514824241;4057.1885622875543;4208.537060923296;4239.27932251518;4276.273033584298;4289.549597292302;1074.2694967422228;1232.168286406672;1256.4848412150975;1284.4862055178003;1284.9935750321376;737.9963100184572;742.7883053329775;771.4946709905021;773.2036002917005;805.3476683886071;6326.968365158237;6393.8721225576155;6653.201615806134;6821.624810635462;6876.965615171892;888.1873086126122;908.4957848394073;908.8318661047728;910.886139232596;924.9583768730423;3945.349017412152;4027.476428064333;4074.8590394689536;4088.9591104088577;4146.834126634961;13623.18260904349;14239.43042278318;14250.287485625677;14255.78616320756;14267.286635668175;14117;14140.292985350898;14148.504802331869;14154.575362739135;14167.504137355143;9998.200143988464;10137.797244055078;10242.897571024343;10385.792284154275;10401.855795862297;6253.632222845932;6253.968730156502;6338.58799178052;6416.711247993758;6508.284088750139;12371.628851134481;12384.75230495403;12432.75134497323;12639.304838233986;12716.618501444971;12118.939405302917;12380;12490.437930293341;12597.622071337875;12751.426185821845;8900;8930.86603700932;8999.460032398078;9019.774505637411;9197.356185066981;9803.411795292306;9860.85208721884;9994.20046396301;10019.749506262402;10024.949875250577;7168.4265258780215;8511.787205319917;8619.569021549023;8644.783880402789;8739.912600874164;8231.300339471212;8346.081930987708;8456.957715211385;8524.403291769517;8610.82778344417;7570.242975702386;7720.343770779322;7733.188015258317;7736.381089512938;7745.302922736981;4679.27471241958;5225.81709640176;5410.323709536308;5411.404745478073;5651.971740141273;5867.295924489089;5968.462838344396;6034.39656034393;6113.419225173601;6219.751209951552 15:29:38 INFO - PID 1636 | |4;prototype.html;333.9580508812505;401.2616785115387;404.9534303555092;405.1855769902495;407.42756427219757;4885.535874091965;5080.669756465827;6027;6058.818235452933;6115.969420152904;5637.646964728456;5948.791792287271;5968.940310596893;6005.459508644221;6078.726457309421;4432.889177770566;4582.885427864313;4584.172216199478;4593.724376537401;4599.195140850346;4524.61540769035;4558.635309175274;4562.908741825161;4580.244259697162;4602.14860250853;3702.8518859245596;4308.6858508155;4512.616427603658;4521.027978984518;4531.818727250906;4033.475648165734;4546.749928753918;4554.658400619934;4563.110193512266;4579.67942244043;3240.319532898094;3803.5435747710453;3875.302445559798;3920.862769803072;3926.3521518949624;4536.455625324949;5108.438071812135;5936.257967754031;5965.985782416999;5983.252093488314;4453.665975051884;5058.2412638104215;5312.389075256357;5363.329583802025;5388.892222155535;5724.313082430093;6220.222472190976;6222.595531290452;6407.935920640827;6505.93494065058;4390.8902277443;4394.978025109854;4396.912061758779;4401.361802538646;4420.491643461011;5900.76396944126;6407.967960160169;6428.839279018062;6542.672866356663;6862.965685171592;4411.18393097278;4418.7127836690515;4436.44544431946;4442.911141777146;4456.7548784817145;4489.910201795946;4492.550744925481;4505.279155335131;4505.932411013838;4519.344695019269;4526.524714904954;5025.572826309732;5144.15121504955;5359.732013399314;5382.138857782736;6537.509686773511;6596.637184954778;6609.834754131089;6617.43751781104;6648.16897887764;1389.3817251323267;1454.7817827325987;1540.399244294726;1555.8408985306019;1557.7896983907028;924.1682485762733;949.6248981652209;1002.1682003936861;1014.7412409835445;1021.1626466297565;4960.429550601655;6035.155078289043;6087.634741915499;6088.11722300264;6122.826396629222;5973.731182096816;6741.898871516931;6777.661116944133;6804.400965773039;6806.7957961261245;4832.830850920201;5993.820185394445;6019.518438524908;6028.336882942873;6038.849028774316;3118.7660925430228;3518.7888726676483;3543.9191046730994;3549.290141971616;3584.874529391511;6552.967235163794;6944.569400709711;6966.860662786716;6989.650517474106;7075.787726368218;7037.718491260394;7058;7083.92916070843;7098.36114749675;7114.893276600855;5612.35457922336;6116.938830611637;6921.653917304216;6928.826779330557;6954.165500139914;5661.433856614306;6835.4189893859575;6848.383645471931;6853.314668533107;6860.108185935796;5879.676617786063;5968.80623875218;6758.324167583399;6768.187817461936;6793.5244532882225;6870.3473170048765;6943.895841562279;6986.685599147949;6993.300669932966;7071;4441.7779111044965;4441.888952776207;4457.108578284291;4478.708883922534;4486.035502367007;5239.140105262729;6391.360863913572;6471.473047189199;6626.90059649096;6920.757773477904;5273.655217919406;5458.2904222450825;6016.42843929826;6263.154474145932;6518.413342799171;6342.841428964128;6535.751761355157;6559.836004099936;6567.62079963194;6582.769603064061;5238.71187084714;5573.916391254053;6081.756729730761;6413.326600706859;6422.293547709654;6531.412172904462;6548.574342667712;6557.508186885907;6563.3764792344655;6597.868042639025;6052.636841789506;6253.124562561154;6294.590851594632;6331.841703957253;6391.96804015977 15:29:38 INFO - PID 1636 | |5;yui.html;636.891728406171;712.4834494991132;724.275724275724;725.9637018149091;741.8219627289452;12058.27650340979;15463.768043479356;15637.687246255067;15669.29488173032;15724.754316334584;12245.32650704211;14192.574222773308;14486.348114334854;14665.706685866277;14752.33614487348;936.7049379445469;952.9904700952972;957.707899090777;958.1472489484364;958.4249450329816;927.1331305229598;955.0974329258853;961.4087336288202;963.0850691842743;964.8166848298836;1037.341288281939;1037.8650775399226;1041.6927006533092;1042.213129087538;1042.9009244121796;1034.2450011491578;1035.4822588705647;1035.53400969564;1035.9119474844647;1038.7091614347994;770.2951799103813;772.3782355204058;774.5159275452842;775.1976704111274;776.491398134223;15866.968647037906;16267.674646507003;16391.754123688155;16619.66760664792;16797.58006049846;14436.783448248285;14583.343675074051;14614.27126910792;14711.779323310158;14726.48457304;11520.769584608344;11647.30116193031;11782.587609433715;11899.643010709606;12171.634850954486;3000.264935090917;3005.069507703278;3058;3070.431970085519;3124.6875312468796;105622.47188764007;109391;109590.90409095887;110102;114117.85882141156;10381.325213861075;10853.837192442119;10857.13142948578;10879.456027198608;11415.60045398407;23048;23434.66458705145;23709;23717;23762.287131386085;7773.805654858561;7844.764657060297;8001.079875814239;8123.268905798506;8160.75517734457;3731.1231860512758;3768.660820526166;4110.280700877334;4206.158768246363;4280.293751530962;4060.2894493463527;4168.103857670615;4172.311568591149;4174.7077704561;4176.979115104466;676.462212541031;710.1868360726938;717.1573401253506;731.2139450091173;733.7835338575134;536.9516743493105;556.9192467092249;565.547561950438;567.4042255631653;576.8693361012379;8460.846983619682;9562.364765826766;9756;9853.261005424622;10046.346987445793;522.9111051121318;549.4779959038929;576.1703147467633;580.5616759346666;589.2722487727652;3716.6840818530186;4603.17142914272;4746.501617330199;4766.475687674353;4835.298881662139;93936.53031734798;98178.03643927081;100605;101344;103097.48451257695;65772;81192.59403703062;82644.17355826365;83492;85249;59934.50163745854;70179.94730079053;72751.90872136923;73010.63494682492;74145.25854741491;10808.609945629174;10827.945860270649;10880.564777409132;10909.963313574028;11080.667579972614;35699;37923;38673.80663096667;38855.834324970296;39728.80813575597;40121.59878401295;48470.5152948466;51727.74136129446;52440.73779631077;53911.73044134754;13402.195868247936;13431.86568134306;13725.245111518961;13809.79285310701;13976.510822121583;14517.201553914636;14561.41754329815;14689.559313220621;15139.470118545798;15148.545543633709;13612.931935340655;13621.659458513617;13968.720625587228;14313.069650472686;14608.853911461174;11546.48040838181;11712.414379280832;11770.58802941893;12104.576339828063;12314.059500959607;13054.459744448579;13223.924725812774;13363.26502042397;13373.732525349633;13957.371918263905;9618.326717129734;9715.902840971783;9797.559109840216;9938.45338506389;10033.548490318099;8458.954776280658;10467.685969420625;10596.31123976939;11132.610358637408;11310.660680179608 15:29:38 INFO - PID 1636 | __end_tp_report 15:29:38 INFO - PID 1636 | __start_cc_report 15:29:38 INFO - PID 1636 | _x_x_mozilla_cycle_collect,1744 15:29:38 INFO - PID 1636 | __end_cc_report 15:29:38 INFO - PID 1636 | __startTimestamp1490308178183__endTimestamp 15:29:38 INFO - PID 1636 | 15:29:38 INFO - PID 1636 | ------- Summary: start ------- 15:29:38 INFO - PID 1636 | Number of tests: 6 15:29:38 INFO - PID 1636 | 15:29:38 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6075.63 Median:6293.15 stddev:662.86 (10.5%) stddev-sans-first:632.14 15:29:38 INFO - PID 1636 | Values: 3452.3 3515.0 3520.4 3530.1 3564.0 5999.9 6096.3 6147.5 6227.3 6246.7 6043.6 6101.1 6184.5 6185.3 6201.2 5402.3 5553.2 5556.3 5568.9 5661.5 5416.5 5478.9 5583.8 5597.8 5653.4 5440.2 5537.7 5573.6 5626.7 5639.2 5519.7 5521.4 5535.7 5544.5 5552.9 5485.8 5535.2 5537.2 5543.0 5571.1 6009.1 6080.5 6087.5 6126.9 6228.4 6522.2 6536.2 6537.1 6562.4 6564.4 6588.8 6610.2 6648.4 6649.4 6675.7 5157.8 6146.5 6159.4 6166.7 6280.0 5931.2 6499.9 6529.4 6596.3 6809.2 6328.3 6331.5 6335.0 6346.3 6387.4 6505.1 6630.8 6638.3 6653.8 6663.1 6528.0 6533.1 6548.8 6555.2 6561.3 6278.3 6330.6 6342.0 6568.6 6595.8 5302.4 5322.5 5330.9 5332.1 5358.1 4674.7 4708.2 4721.8 4738.1 4783.2 5306.2 6252.6 6273.3 6274.2 6285.7 6342.0 6390.5 6396.3 6590.0 6598.9 6207.9 6266.2 6287.5 6294.9 6304.0 5807.3 5839.7 5851.9 5852.2 5896.0 6483.0 6715.4 6769.3 6797.9 6820.7 6377.6 6377.7 6588.7 6646.4 6656.1 6773.1 6809.0 6813.3 6814.5 6821.2 6485.4 6535.1 6551.4 6557.6 6567.2 5609.7 6417.2 6468.2 6501.5 6537.3 6338.9 6375.5 6376.0 6380.3 6390.8 6251.6 6280.9 6345.6 6346.9 6363.5 6306.9 6325.8 6330.5 6335.3 6336.6 6253.2 6315.0 6432.5 6433.4 6523.9 6206.5 6249.2 6260.8 6293.2 6325.5 15:29:38 INFO - PID 1636 | 15:29:38 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:54498.26 Median:33248.42 stddev:115289.44 (346.8%) stddev-sans-first:115542.24 15:29:38 INFO - PID 1636 | Values: 754.8 796.1 813.5 1125.5 1146.6 27619.2 36585.0 37051.3 37122.3 37269.4 38497.4 38747.8 38766.4 38816.4 38817.8 5549.9 7377.9 8798.5 8808.5 8828.2 6180.4 8374.6 8596.2 8596.7 8602.2 8602.7 8635.7 8664.2 8666.5 8675.8 6131.3 8592.1 8638.5 8650.1 8661.4 6143.2 8290.9 8479.6 8489.0 8498.4 37591.4 37931.0 38016.2 38037.2 38102.0 2658.3 2928.2 2960.9 2963.3 2967.6 27682.3 27701.0 27748.2 28164.4 28213.6 2739.5 2788.8 2823.5 2860.0 2861.4 293910.0 294511.0 294840.5 295001.0 295260.5 48309.5 48376.0 48400.0 48430.8 48442.0 51280.2 51387.5 51396.0 51468.7 51497.0 14345.4 14633.7 14647.9 14666.0 14679.3 10068.7 10236.7 10831.9 10918.7 11863.9 11608.7 13149.1 13282.8 13402.2 13407.4 5870.0 5985.9 6014.1 6019.2 6076.3 927.5 929.3 935.3 950.2 955.3 22241.3 22289.9 27350.7 28214.0 28387.9 1011.7 1020.2 1062.9 1070.2 1078.9 10634.9 12023.7 12087.9 12183.7 12196.7 497196.0 499957.0 500136.0 500705.0 501337.0 55622.4 55728.2 55744.2 55927.2 56083.4 1472.1 1508.1 1512.9 1523.0 1530.8 21717.8 23461.1 23464.5 23489.3 23516.5 31225.5 31476.7 37961.6 38091.6 38229.6 471941.0 472046.0 472095.0 472795.0 472838.0 32540.5 33002.8 33193.5 33240.2 33256.7 31066.4 31091.5 36599.6 38824.6 39576.8 27245.7 27440.6 27842.6 28186.3 28874.3 27743.4 28455.9 28744.9 28813.9 28989.0 33685.7 33745.8 33997.8 34263.0 35154.3 24899.8 24911.8 29515.0 29623.6 29942.0 18322.5 21593.1 21868.6 22928.0 23202.1 15:29:38 INFO - PID 1636 | 15:29:38 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:38470.50 Median:26588.37 stddev:112738.69 (424.0%) stddev-sans-first:113018.68 15:29:38 INFO - PID 1636 | Values: 387.4 428.4 433.5 440.2 455.5 18723.4 20524.4 21035.3 21322.6 22088.7 17786.9 21200.8 22245.8 22403.9 22467.0 6013.6 6268.0 6282.5 6288.9 6312.9 5942.2 6122.4 6157.4 6207.0 6221.7 6167.0 6175.6 6178.4 6179.2 6186.9 5904.3 6100.4 6211.0 6214.9 6215.0 5308.3 5374.8 5513.3 5516.6 5522.5 26335.7 26586.7 26590.0 26847.2 26917.5 2007.8 2009.6 2011.4 2022.6 2036.7 15400.8 15509.9 15668.3 15796.7 16088.4 1573.8 1627.6 1632.7 1638.6 1655.6 131977.3 136103.8 136925.3 138477.3 140040.3 11538.4 11756.1 11775.2 11893.1 12090.9 15993.4 16001.7 16010.0 16010.8 16034.0 7305.2 8354.5 8566.3 8746.6 8881.3 8327.8 8336.4 8495.4 8522.6 8541.6 6113.7 7239.3 7359.7 7420.7 7462.2 2099.4 2119.1 2126.6 2127.2 2138.7 825.5 832.6 885.8 902.5 903.6 19362.9 20158.5 20253.1 20254.8 20619.7 927.6 942.7 950.4 970.6 970.9 10054.6 10290.5 10322.7 10349.9 10368.6 645950.0 674782.0 674896.0 688686.0 696293.0 105050.1 122501.0 131530.3 141048.0 147335.0 1095.4 1168.5 1179.4 1180.0 1183.4 18513.5 19861.7 20729.6 21006.0 21151.8 59750.0 59769.7 59849.7 60407.1 61148.7 84642.0 84645.2 85209.7 85417.3 86261.6 8302.4 8469.9 8496.7 8569.2 8692.4 12366.8 12590.6 12616.6 12689.5 13122.0 8870.6 11330.0 11339.9 11463.9 11566.7 9438.5 9847.0 9993.6 10201.3 10305.5 9484.9 11193.3 11193.4 11379.3 11494.7 7587.0 8090.8 8233.0 8439.2 8507.9 8206.8 10180.7 10404.6 10592.3 10642.6 15:29:38 INFO - PID 1636 | 15:29:38 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:6758.87 Median:3970.56 stddev:4207.86 (106.0%) stddev-sans-first:4198.61 15:29:38 INFO - PID 1636 | Values: 1157.5 1433.7 1453.3 1453.8 1454.0 10463.2 10912.3 10914.7 10933.9 10936.5 10489.9 10896.8 10899.4 10911.7 10912.2 2431.1 2494.2 2494.7 2495.5 2497.3 2060.9 2137.5 2145.5 2377.3 2415.3 2110.4 2143.5 2144.0 2144.7 2145.0 2089.7 2367.0 2454.3 2462.6 2466.7 2411.1 2430.1 2439.2 2441.2 2441.8 10685.8 10937.9 11130.9 11143.7 11145.9 6158.2 6384.9 6388.6 6388.8 6401.1 3995.8 4015.5 4051.7 4059.9 4089.5 999.3 1175.9 1208.8 1234.9 1237.7 13749.8 14138.7 15154.0 15331.9 15380.6 5138.6 5278.8 5336.7 5408.6 5417.8 11531.7 11553.4 11554.3 11565.5 11582.8 6282.2 6284.5 6287.5 6292.3 6301.5 5377.7 5832.3 5887.1 5890.6 5929.7 4057.2 4208.5 4239.3 4276.3 4289.5 1074.3 1232.2 1256.5 1284.5 1285.0 738.0 742.8 771.5 773.2 805.3 6327.0 6393.9 6653.2 6821.6 6877.0 888.2 908.5 908.8 910.9 925.0 3945.3 4027.5 4074.9 4089.0 4146.8 13623.2 14239.4 14250.3 14255.8 14267.3 14117.0 14140.3 14148.5 14154.6 14167.5 9998.2 10137.8 10242.9 10385.8 10401.9 6253.6 6254.0 6338.6 6416.7 6508.3 12371.6 12384.8 12432.8 12639.3 12716.6 12118.9 12380.0 12490.4 12597.6 12751.4 8900.0 8930.9 8999.5 9019.8 9197.4 9803.4 9860.9 9994.2 10019.7 10024.9 7168.4 8511.8 8619.6 8644.8 8739.9 8231.3 8346.1 8457.0 8524.4 8610.8 7570.2 7720.3 7733.2 7736.4 7745.3 4679.3 5225.8 5410.3 5411.4 5652.0 5867.3 5968.5 6034.4 6113.4 6219.8 15:29:38 INFO - PID 1636 | 15:29:38 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5238.77 Median:5942.52 stddev:1649.71 (27.8%) stddev-sans-first:1612.74 15:29:38 INFO - PID 1636 | Values: 334.0 401.3 405.0 405.2 407.4 4885.5 5080.7 6027.0 6058.8 6116.0 5637.6 5948.8 5968.9 6005.5 6078.7 4432.9 4582.9 4584.2 4593.7 4599.2 4524.6 4558.6 4562.9 4580.2 4602.1 3702.9 4308.7 4512.6 4521.0 4531.8 4033.5 4546.7 4554.7 4563.1 4579.7 3240.3 3803.5 3875.3 3920.9 3926.4 4536.5 5108.4 5936.3 5966.0 5983.3 4453.7 5058.2 5312.4 5363.3 5388.9 5724.3 6220.2 6222.6 6407.9 6505.9 4390.9 4395.0 4396.9 4401.4 4420.5 5900.8 6408.0 6428.8 6542.7 6863.0 4411.2 4418.7 4436.4 4442.9 4456.8 4489.9 4492.6 4505.3 4505.9 4519.3 4526.5 5025.6 5144.2 5359.7 5382.1 6537.5 6596.6 6609.8 6617.4 6648.2 1389.4 1454.8 1540.4 1555.8 1557.8 924.2 949.6 1002.2 1014.7 1021.2 4960.4 6035.2 6087.6 6088.1 6122.8 5973.7 6741.9 6777.7 6804.4 6806.8 4832.8 5993.8 6019.5 6028.3 6038.8 3118.8 3518.8 3543.9 3549.3 3584.9 6553.0 6944.6 6966.9 6989.7 7075.8 7037.7 7058.0 7083.9 7098.4 7114.9 5612.4 6116.9 6921.7 6928.8 6954.2 5661.4 6835.4 6848.4 6853.3 6860.1 5879.7 5968.8 6758.3 6768.2 6793.5 6870.3 6943.9 6986.7 6993.3 7071.0 4441.8 4441.9 4457.1 4478.7 4486.0 5239.1 6391.4 6471.5 6626.9 6920.8 5273.7 5458.3 6016.4 6263.2 6518.4 6342.8 6535.8 6559.8 6567.6 6582.8 5238.7 5573.9 6081.8 6413.3 6422.3 6531.4 6548.6 6557.5 6563.4 6597.9 6052.6 6253.1 6294.6 6331.8 6392.0 15:29:38 INFO - PID 1636 | 15:29:38 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:19223.92 Median:3031.53 stddev:27503.78 (907.3%) stddev-sans-first:27545.52 15:29:38 INFO - PID 1636 | Values: 636.9 712.5 724.3 726.0 741.8 12058.3 15463.8 15637.7 15669.3 15724.8 12245.3 14192.6 14486.3 14665.7 14752.3 936.7 953.0 957.7 958.1 958.4 927.1 955.1 961.4 963.1 964.8 1037.3 1037.9 1041.7 1042.2 1042.9 1034.2 1035.5 1035.5 1035.9 1038.7 770.3 772.4 774.5 775.2 776.5 15867.0 16267.7 16391.8 16619.7 16797.6 14436.8 14583.3 14614.3 14711.8 14726.5 11520.8 11647.3 11782.6 11899.6 12171.6 3000.3 3005.1 3058.0 3070.4 3124.7 105622.5 109391.0 109590.9 110102.0 114117.9 10381.3 10853.8 10857.1 10879.5 11415.6 23048.0 23434.7 23709.0 23717.0 23762.3 7773.8 7844.8 8001.1 8123.3 8160.8 3731.1 3768.7 4110.3 4206.2 4280.3 4060.3 4168.1 4172.3 4174.7 4177.0 676.5 710.2 717.2 731.2 733.8 537.0 556.9 565.5 567.4 576.9 8460.8 9562.4 9756.0 9853.3 10046.3 522.9 549.5 576.2 580.6 589.3 3716.7 4603.2 4746.5 4766.5 4835.3 93936.5 98178.0 100605.0 101344.0 103097.5 65772.0 81192.6 82644.2 83492.0 85249.0 59934.5 70179.9 72751.9 73010.6 74145.3 10808.6 10827.9 10880.6 10910.0 11080.7 35699.0 37923.0 38673.8 38855.8 39728.8 40121.6 48470.5 51727.7 52440.7 53911.7 13402.2 13431.9 13725.2 13809.8 13976.5 14517.2 14561.4 14689.6 15139.5 15148.5 13612.9 13621.7 13968.7 14313.1 14608.9 11546.5 11712.4 11770.6 12104.6 12314.1 13054.5 13223.9 13363.3 13373.7 13957.4 9618.3 9715.9 9797.6 9938.5 10033.5 8459.0 10467.7 10596.3 11132.6 11310.7 15:29:38 INFO - PID 1636 | -------- Summary: end -------- 15:29:38 INFO - PID 1636 | 15:29:38 INFO - TEST-INFO | 1636: exit 0 15:29:38 INFO - TEST-OK | dromaeo_css | took 1121556ms 15:29:38 INFO - TEST-START | kraken 15:29:38 INFO - operating with platform_type : mac_ 15:29:38 INFO - Initialising browser for kraken test... 15:29:38 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpy89Pdj/profile http://localhost:49243/getInfo.html) 15:29:41 INFO - PID 1646 | __metrics Screen width/height:1600/1200 15:29:41 INFO - PID 1646 | colorDepth:24 15:29:41 INFO - PID 1646 | Browser inner width/height: 1024/689 15:29:41 INFO - PID 1646 | __metrics 15:29:41 INFO - PID 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:29:41 INFO - PID 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:29:42 INFO - TEST-INFO | 1646: exit 0 15:29:42 INFO - Browser initialized. 15:29:42 INFO - Running cycle 1/1 for kraken test... 15:29:42 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpy89Pdj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:29:54 INFO - PID 1647 | RSS: Main: 253173760 15:29:54 INFO - PID 1647 | 15:29:57 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 15:29:58 INFO - PID 1647 | RSS: Main: 301723648 15:29:58 INFO - PID 1647 | 15:30:01 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 15:30:01 INFO - PID 1647 | RSS: Main: 304500736 15:30:01 INFO - PID 1647 | 15:30:05 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 15:30:05 INFO - PID 1647 | RSS: Main: 395132928 15:30:05 INFO - PID 1647 | 15:30:07 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 15:30:08 INFO - PID 1647 | RSS: Main: 294838272 15:30:08 INFO - PID 1647 | 15:30:10 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:30:11 INFO - PID 1647 | RSS: Main: 297517056 15:30:11 INFO - PID 1647 | 15:30:13 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 15:30:14 INFO - PID 1647 | RSS: Main: 339435520 15:30:14 INFO - PID 1647 | 15:30:17 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 15:30:18 INFO - PID 1647 | RSS: Main: 350461952 15:30:18 INFO - PID 1647 | 15:30:21 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 15:30:22 INFO - PID 1647 | RSS: Main: 355385344 15:30:22 INFO - PID 1647 | 15:30:23 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:30:24 INFO - PID 1647 | RSS: Main: 314576896 15:30:24 INFO - PID 1647 | 15:30:26 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:30:27 INFO - PID 1647 | RSS: Main: 319459328 15:30:27 INFO - PID 1647 | 15:30:29 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:30:29 INFO - PID 1647 | RSS: Main: 321789952 15:30:29 INFO - PID 1647 | 15:30:32 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:30:33 INFO - PID 1647 | RSS: Main: 340250624 15:30:33 INFO - PID 1647 | 15:30:36 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:30:37 INFO - PID 1647 | RSS: Main: 333799424 15:30:37 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 15:30:39 INFO - PID 1647 | RSS: Main: 395640832 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | __start_tp_report 15:30:39 INFO - PID 1647 | _x_x_mozilla_page_load 15:30:39 INFO - PID 1647 | _x_x_mozilla_page_load_details 15:30:39 INFO - PID 1647 | |i|pagename|runs| 15:30:39 INFO - PID 1647 | |0;ai-astar;112;111;112;111;112;111;111;111;111;112 15:30:39 INFO - PID 1647 | |1;audio-beat-detection;145;112;112;151;121;127;103;110;132;111 15:30:39 INFO - PID 1647 | |2;audio-dft;188;181;182;186;185;184;184;181;184;184 15:30:39 INFO - PID 1647 | |3;audio-fft;84;58;62;88;62;58;58;57;79;64 15:30:39 INFO - PID 1647 | |4;audio-oscillator;92;71;88;71;69;70;84;101;102;119 15:30:39 INFO - PID 1647 | |5;imaging-gaussian-blur;86;87;87;87;88;87;87;85;87;86 15:30:39 INFO - PID 1647 | |6;imaging-darkroom;94;94;96;136;151;152;95;97;93;93 15:30:39 INFO - PID 1647 | |7;imaging-desaturate;85;84;84;86;88;83;84;101;133;131 15:30:39 INFO - PID 1647 | |8;json-parse-financial;65;62;58;59;61;61;62;62;62;61 15:30:39 INFO - PID 1647 | |9;json-stringify-tinderbox;53;54;64;68;62;60;56;66;83;82 15:30:39 INFO - PID 1647 | |10;stanford-crypto-aes;84;84;84;83;84;84;82;80;82;81 15:30:39 INFO - PID 1647 | |11;stanford-crypto-ccm;142;133;147;144;144;146;141;149;204;165 15:30:39 INFO - PID 1647 | |12;stanford-crypto-pbkdf2;189;226;191;193;189;185;186;188;187;184 15:30:39 INFO - PID 1647 | |13;stanford-crypto-sha256-iterative;68;68;69;67;69;66;70;68;67;69 15:30:39 INFO - PID 1647 | __end_tp_report 15:30:39 INFO - PID 1647 | __start_cc_report 15:30:39 INFO - PID 1647 | _x_x_mozilla_cycle_collect,2153 15:30:39 INFO - PID 1647 | __end_cc_report 15:30:39 INFO - PID 1647 | __startTimestamp1490308239382__endTimestamp 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | ------- Summary: start ------- 15:30:39 INFO - PID 1647 | Number of tests: 14 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#0] ai-astar Cycles:10 Average:111.40 Median:111.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 15:30:39 INFO - PID 1647 | Values: 112.0 111.0 112.0 111.0 112.0 111.0 111.0 111.0 111.0 112.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#1] audio-beat-detection Cycles:10 Average:122.40 Median:124.00 stddev:16.06 (12.9%) stddev-sans-first:14.80 15:30:39 INFO - PID 1647 | Values: 145.0 112.0 112.0 151.0 121.0 127.0 103.0 110.0 132.0 111.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:2.18 (1.2%) stddev-sans-first:1.74 15:30:39 INFO - PID 1647 | Values: 188.0 181.0 182.0 186.0 185.0 184.0 184.0 181.0 184.0 184.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#3] audio-fft Cycles:10 Average:67.00 Median:63.00 stddev:11.91 (18.9%) stddev-sans-first:10.93 15:30:39 INFO - PID 1647 | Values: 84.0 58.0 62.0 88.0 62.0 58.0 58.0 57.0 79.0 64.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:71.00 stddev:17.01 (24.0%) stddev-sans-first:17.93 15:30:39 INFO - PID 1647 | Values: 92.0 71.0 88.0 71.0 69.0 70.0 84.0 101.0 102.0 119.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 15:30:39 INFO - PID 1647 | Values: 86.0 87.0 87.0 87.0 88.0 87.0 87.0 85.0 87.0 86.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#6] imaging-darkroom Cycles:10 Average:110.10 Median:94.00 stddev:25.39 (27.0%) stddev-sans-first:26.25 15:30:39 INFO - PID 1647 | Values: 94.0 94.0 96.0 136.0 151.0 152.0 95.0 97.0 93.0 93.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:84.00 stddev:19.73 (23.5%) stddev-sans-first:20.53 15:30:39 INFO - PID 1647 | Values: 85.0 84.0 84.0 86.0 88.0 83.0 84.0 101.0 133.0 131.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#8] json-parse-financial Cycles:10 Average:61.30 Median:62.00 stddev:1.89 (3.0%) stddev-sans-first:1.45 15:30:39 INFO - PID 1647 | Values: 65.0 62.0 58.0 59.0 61.0 61.0 62.0 62.0 62.0 61.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:10.56 (16.2%) stddev-sans-first:10.30 15:30:39 INFO - PID 1647 | Values: 53.0 54.0 64.0 68.0 62.0 60.0 56.0 66.0 83.0 82.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:84.00 stddev:1.48 (1.8%) stddev-sans-first:1.50 15:30:39 INFO - PID 1647 | Values: 84.0 84.0 84.0 83.0 84.0 84.0 82.0 80.0 82.0 81.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:146.50 stddev:20.14 (13.7%) stddev-sans-first:21.07 15:30:39 INFO - PID 1647 | Values: 142.0 133.0 147.0 144.0 144.0 146.0 141.0 149.0 204.0 165.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.80 Median:189.00 stddev:12.32 (6.5%) stddev-sans-first:13.02 15:30:39 INFO - PID 1647 | Values: 189.0 226.0 191.0 193.0 189.0 185.0 186.0 188.0 187.0 184.0 15:30:39 INFO - PID 1647 | 15:30:39 INFO - PID 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:1.20 (1.7%) stddev-sans-first:1.27 15:30:39 INFO - PID 1647 | Values: 68.0 68.0 69.0 67.0 69.0 66.0 70.0 68.0 67.0 69.0 15:30:39 INFO - PID 1647 | -------- Summary: end -------- 15:30:39 INFO - PID 1647 | 15:30:40 INFO - TEST-INFO | 1647: exit 0 15:30:40 INFO - TEST-OK | kraken | took 61702ms 15:30:40 INFO - SUITE-END | took 1183s 15:30:41 INFO - Completed test suite (00:19:43) 15:30:41 INFO - javascript benchmark 15:30:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7559.890959611145, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3452.2750222453283, 3515.0157955772393, 3520.419130843411, 3530.13511689636, 3564.0, 5999.880002399949, 6096.315032485248, 6147.524594097406, 6227.28386235583, 6246.718897649605, 6043.576949613529, 6101.126216676348, 6184.505239580834, 6185.319614842364, 6201.224846894138, 5402.297701298826, 5553.222548843165, 5556.333240011184, 5568.888622227553, 5661.46216109471, 5416.539594134503, 5478.890422191554, 5583.776648934037, 5597.832065038056, 5653.434656534334, 5440.183972404131, 5537.695426751527, 5573.637713548606, 5626.6624002559565, 5639.210510528529, 5519.724013799294, 5521.420250873641, 5535.6955367454775, 5544.500994910478, 5552.916706249369, 5485.780568777244, 5535.169724541311, 5537.224788529609, 5542.972285138589, 5571.1086226203615, 6009.06859436788, 6080.452759251644, 6087.512998960073, 6126.925569569743, 6228.439440450381, 6522.249941256769, 6536.150300460958, 6537.052127441542, 6562.376574225441, 6564.442022428099, 6588.835279118012, 6610.173728283965, 6648.401643852077, 6649.401553860176, 6675.6662166891465, 5157.762137087072, 6146.5390095743, 6159.4456498914415, 6166.660833654102, 6279.96860015697, 5931.228940237742, 6499.902501462482, 6529.412352888168, 6596.274409814917, 6809.216940051859, 6328.335524769925, 6331.461825744863, 6334.9683251584365, 6346.30190679025, 6387.425131738166, 6505.121808555879, 6630.801075967729, 6638.336166383323, 6653.833654158685, 6663.06717059612, 6527.967360163264, 6533.085368048477, 6548.770793022221, 6555.246146693191, 6561.343865613496, 6278.30938596754, 6330.620162790247, 6342.0, 6568.605883646901, 6595.769148079794, 5302.443243459458, 5322.520973112439, 5330.946690533045, 5332.120200166929, 5358.089124848785, 4674.74288914113, 4708.246680531099, 4721.763911804464, 4738.099761045391, 4783.1868582341085, 5306.204069389622, 6252.562320637603, 6273.278572964167, 6274.152989346379, 6285.748570057238, 6342.0, 6390.520710946697, 6396.328385519547, 6590.0, 6598.934010659832, 6207.937920620826, 6266.185395898504, 6287.528435367366, 6294.874102517924, 6304.0, 5807.332156802022, 5839.722298529098, 5851.912221316684, 5852.180694702745, 5895.970520147372, 6483.0, 6715.3620406061345, 6769.289224631343, 6797.864042719019, 6820.693068812015, 6377.585456945284, 6377.713002914973, 6588.7364505421165, 6646.368594983469, 6656.068150458843, 6773.051772751917, 6809.0, 6813.318668133147, 6814.454843612598, 6821.2155602106395, 6485.3514648534765, 6535.085088087577, 6551.4103730664465, 6557.573757705733, 6567.2447668518735, 5609.74756135983, 6417.197850268716, 6468.191476065492, 6501.479881609365, 6537.28089910119, 6338.8732225354315, 6375.5218358624215, 6376.0, 6380.330065343072, 6390.808275751735, 6251.593646412968, 6280.905786413116, 6345.555811093179, 6346.90479642815, 6363.522735794926, 6306.936930630818, 6325.841853953688, 6330.52521060913, 6335.303116657072, 6336.556441049082, 6253.155823963707, 6315.0, 6432.517561182836, 6433.356664333529, 6523.869522609616, 6206.503479721701, 6249.187605611241, 6260.843478913063, 6293.150424692791, 6325.4939604832425], "value": 6033.945917998894, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.8339365339626, 796.092454601754, 813.4753084260652, 1125.510402974706, 1146.5814977533203, 27619.171424857235, 36585.0, 37051.25897482052, 37122.329495172715, 37269.44095838565, 38497.42253866187, 38747.806260968726, 38766.41850372247, 38816.41775373372, 38817.80591097048, 5549.916751248735, 7377.930200120979, 8798.472091674488, 8808.471491710518, 8828.24959878411, 6180.351063138373, 8374.58127093646, 8596.226339629433, 8596.699115530957, 8602.18279263469, 8602.698905538307, 8635.69775057873, 8664.220220180214, 8666.480011199317, 8675.826483470326, 6131.294901086388, 8592.14078592142, 8638.481691098554, 8650.091740367297, 8661.393702440831, 6143.232095988002, 8290.917090829074, 8479.618417171243, 8489.0, 8498.362622803254, 37591.436128458095, 37931.0, 38016.23967520662, 38037.23925521474, 38102.0, 2658.3088397016722, 2928.165472840252, 2960.881564737408, 2963.3184367595572, 2967.5845381646373, 27682.307942301602, 27701.03046393387, 27748.167554973385, 28164.43671126566, 28213.576796348072, 2739.493193759161, 2788.776897848167, 2823.4612136385717, 2859.9857000714865, 2861.427714457117, 293910.0, 294511.0, 294840.5257973718, 295001.0, 295260.52369738015, 48309.5169048312, 48376.0, 48400.0, 48430.789230269525, 48442.0, 51280.23079653808, 51387.48612513902, 51396.0, 51468.74265628648, 51497.0, 14345.426182952566, 14633.658614621821, 14647.853521464862, 14666.046706964013, 14679.339429725687, 10068.748281293027, 10236.744081398025, 10831.945840270748, 10918.672439826818, 11863.881361186277, 11608.65174044763, 13149.079564430589, 13282.800757988638, 13402.195868247936, 13407.396667150002, 5870.033538764476, 5985.910211346833, 6014.127951447016, 6019.247594050744, 6076.3316035236085, 927.5084205371164, 929.2519521785028, 935.2891802230354, 950.2208189284859, 955.2739917662626, 22241.332760017227, 22289.885505724647, 27350.72649273482, 28214.0, 28387.858060709565, 1011.7470632341915, 1020.2450186861818, 1062.86182796236, 1070.2187403195555, 1078.9082927951056, 10634.946825265979, 12023.74592459823, 12087.939560302319, 12183.695407614703, 12196.743602433578, 497196.0, 499957.0, 500136.0, 500705.0, 501337.0, 55622.44377556253, 55728.164077538866, 55744.21278936037, 55927.16109258364, 56083.43916560864, 1472.1314424489603, 1508.0649997001929, 1512.9243537823286, 1523.0024334061209, 1530.7703844423424, 21717.782822171575, 23461.06155753819, 23464.530709385373, 23489.295321140395, 23516.529669406857, 31225.531617026216, 31476.685233147375, 37961.62038379581, 38091.61908380881, 38229.61770382372, 471941.0, 472046.0, 472095.0, 472795.0, 472838.0, 32540.51189232116, 33002.83498582492, 33193.502097468074, 33240.1689957753, 33256.667433325354, 31066.378672425973, 31091.533626996064, 36599.634003660685, 38824.6117538821, 39576.80211598924, 27245.72754272511, 27440.588391173747, 27842.582361265002, 28186.29534261578, 28874.27814304659, 27743.44513109686, 28455.857720711265, 28744.856275718488, 28813.855930720212, 28989.0, 33685.66314336923, 33745.83127084349, 33997.830010849786, 34263.0, 35154.296914061066, 24899.75100249047, 24911.75441227895, 29515.0, 29623.555646664885, 29942.0, 18322.488493889796, 21593.13627454884, 21868.56262874702, 22928.0, 23202.071917123125], "value": 17237.585352988892, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.4498212538196, 428.43660586328974, 433.4560127040564, 440.20762627270915, 455.5194270045103, 18723.438296851098, 20524.38426847195, 21035.263765768163, 21322.57354852904, 22088.686696995457, 17786.911065444656, 21200.787992120073, 22245.777542224572, 22403.887980560074, 22467.0, 6013.609115407485, 6267.9686601567155, 6282.4974002079725, 6288.9056664150075, 6312.9368706313035, 5942.227510423639, 6122.418370254819, 6157.353477884825, 6206.968965155191, 6221.720022598994, 6167.0, 6175.6294622322575, 6178.41305076017, 6179.227596550431, 6186.907196392058, 5904.321003084636, 6100.389961003909, 6210.975189093794, 6214.90677639838, 6215.005599104122, 5308.256844041799, 5374.838754837361, 5513.3108361454815, 5516.586256030773, 5522.475364840334, 26335.73664263352, 26586.734132658617, 26590.0, 26847.194584162506, 26917.461650767073, 2007.8494112941441, 2009.5679428922706, 2011.426743378145, 2022.6359255334035, 2036.6944958256381, 15400.845991540053, 15509.922450387789, 15668.294926728324, 15796.684066318609, 16088.356465741357, 1573.8111426628764, 1627.560558649158, 1632.7142750018818, 1638.6394993101617, 1655.606146276628, 131977.34011329978, 136103.79221558323, 136925.31537342348, 138477.3076134623, 140040.29979850136, 11538.423078846192, 11756.1182911282, 11775.175737698279, 11893.108016898766, 12090.879091209152, 15993.36026558948, 16001.679966400607, 16010.03939763618, 16010.759838602431, 16034.0, 7305.159906610807, 8354.498730076215, 8566.314694824523, 8746.606402711894, 8881.333899957399, 8327.791805204919, 8336.374771892011, 8495.36284778644, 8522.616482258314, 8541.583366653264, 6113.724882380304, 7239.348458638748, 7359.705611775469, 7420.703171873065, 7462.179160292363, 2099.3806826986074, 2119.0887918195326, 2126.6384714598557, 2127.2022991378235, 2138.743350797883, 825.451075035095, 832.6169961817511, 885.7918389178537, 902.4991129922824, 903.6159632156307, 19362.90318548398, 20158.496037598885, 20253.08861101254, 20254.79745202529, 20619.69070463944, 927.6104036304769, 942.6936245720168, 950.353759443585, 970.5875003123786, 970.8640790289365, 10054.594632359616, 10290.536925838354, 10322.690319290283, 10349.896501034893, 10368.585256589651, 645950.0, 674782.0, 674896.0, 688686.0, 696293.0, 105050.08159782104, 122501.0, 131530.34234828764, 141048.0, 147335.0, 1095.397531357766, 1168.485866218844, 1179.3572502985894, 1179.9823002654966, 1183.355071486042, 18513.53716157053, 19861.673363906815, 20729.585408292052, 21006.0, 21151.788482114982, 59750.0, 59769.701151493966, 59849.72329288967, 60407.09389359251, 61148.69425653021, 84642.0, 84645.15354846373, 85209.721854171, 85417.32283464567, 86261.56869215614, 8302.418830681794, 8469.872951905603, 8496.745097647081, 8569.185927336894, 8692.39153259291, 12366.814497782361, 12590.559330423392, 12616.55842045524, 12689.492420303084, 13122.0, 8870.556472176237, 11330.036946859609, 11339.943300283447, 11463.942680286546, 11566.653000409664, 9438.52807359643, 9846.950765246127, 9993.550290237103, 10201.285909986229, 10305.484725763532, 9484.905150948402, 11193.27243729155, 11193.440327983732, 11379.317240965569, 11494.655160345203, 7587.0, 8090.757277281691, 8232.958835205785, 8439.198276163756, 8507.91492085096, 8206.835863282582, 10180.745481363025, 10404.58381664725, 10592.258541902302, 10642.627508037182], "value": 10020.597968909515, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1157.525414580022, 1433.6845893903337, 1453.2951518513526, 1453.7819327100926, 1454.0475988227709, 10463.215258855587, 10912.290701104432, 10914.727131821708, 10933.945330273338, 10936.45317734113, 10489.895101048987, 10896.836547451774, 10899.400532970683, 10911.672649820499, 10912.23614346994, 2431.1247950737675, 2494.1769216158623, 2494.6756921600168, 2495.4759500504915, 2497.2633073243346, 2060.9072591733407, 2137.508373074194, 2145.495808485005, 2377.2749311459997, 2415.335782659774, 2110.4090854560745, 2143.485563464773, 2144.04589957469, 2144.7211862457857, 2145.0, 2089.7283353164144, 2366.95266094678, 2454.312792418126, 2462.630605409185, 2466.6793316868784, 2411.144043864438, 2430.1373012580634, 2439.1828737372825, 2441.1578005588044, 2441.7802397784108, 10685.78628427427, 10937.945310273397, 11130.944345278303, 11143.665690029313, 11145.944270278596, 6158.199434073586, 6384.904226436607, 6388.64862432566, 6388.840278993016, 6401.07184458251, 3995.7802320872343, 4015.4779878615886, 4051.6961227908023, 4059.939100913489, 4089.5092588889224, 999.2655398282257, 1175.8882906123904, 1208.8477366255174, 1234.876512348767, 1237.7462620162844, 13749.831264342443, 14138.717225655531, 15154.014989025678, 15331.923340383226, 15380.61548461275, 5138.640295179302, 5278.788848446096, 5336.706481143575, 5408.594355423359, 5417.756200970965, 11531.711707207387, 11553.36456494884, 11554.306741595532, 11565.537378504934, 11582.826257606142, 6282.246130464374, 6284.497240220771, 6287.528435367366, 6292.307846136921, 6301.495880329563, 5377.704226267515, 5832.270966129234, 5887.0580707086665, 5890.617109887844, 5929.703514824241, 4057.1885622875543, 4208.537060923296, 4239.27932251518, 4276.273033584298, 4289.549597292302, 1074.2694967422228, 1232.168286406672, 1256.4848412150975, 1284.4862055178003, 1284.9935750321376, 737.9963100184572, 742.7883053329775, 771.4946709905021, 773.2036002917005, 805.3476683886071, 6326.968365158237, 6393.8721225576155, 6653.201615806134, 6821.624810635462, 6876.965615171892, 888.1873086126122, 908.4957848394073, 908.8318661047728, 910.886139232596, 924.9583768730423, 3945.349017412152, 4027.476428064333, 4074.8590394689536, 4088.9591104088577, 4146.834126634961, 13623.18260904349, 14239.43042278318, 14250.287485625677, 14255.78616320756, 14267.286635668175, 14117.0, 14140.292985350898, 14148.504802331869, 14154.575362739135, 14167.504137355143, 9998.200143988464, 10137.797244055078, 10242.897571024343, 10385.792284154275, 10401.855795862297, 6253.632222845932, 6253.968730156502, 6338.58799178052, 6416.711247993758, 6508.284088750139, 12371.628851134481, 12384.75230495403, 12432.75134497323, 12639.304838233986, 12716.618501444971, 12118.939405302917, 12380.0, 12490.437930293341, 12597.622071337875, 12751.426185821845, 8900.0, 8930.86603700932, 8999.460032398078, 9019.774505637411, 9197.356185066981, 9803.411795292306, 9860.85208721884, 9994.20046396301, 10019.749506262402, 10024.949875250577, 7168.4265258780215, 8511.787205319917, 8619.569021549023, 8644.783880402789, 8739.912600874164, 8231.300339471212, 8346.081930987708, 8456.957715211385, 8524.403291769517, 8610.82778344417, 7570.242975702386, 7720.343770779322, 7733.188015258317, 7736.381089512938, 7745.302922736981, 4679.27471241958, 5225.81709640176, 5410.323709536308, 5411.404745478073, 5651.971740141273, 5867.295924489089, 5968.462838344396, 6034.39656034393, 6113.419225173601, 6219.751209951552], "value": 5130.1537102643215, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.9580508812505, 401.2616785115387, 404.9534303555092, 405.1855769902495, 407.42756427219757, 4885.535874091965, 5080.669756465827, 6027.0, 6058.818235452933, 6115.969420152904, 5637.646964728456, 5948.791792287271, 5968.940310596893, 6005.459508644221, 6078.726457309421, 4432.889177770566, 4582.885427864313, 4584.172216199478, 4593.724376537401, 4599.195140850346, 4524.61540769035, 4558.635309175274, 4562.908741825161, 4580.244259697162, 4602.14860250853, 3702.8518859245596, 4308.6858508155, 4512.616427603658, 4521.027978984518, 4531.818727250906, 4033.475648165734, 4546.749928753918, 4554.658400619934, 4563.110193512266, 4579.67942244043, 3240.319532898094, 3803.5435747710453, 3875.302445559798, 3920.862769803072, 3926.3521518949624, 4536.455625324949, 5108.438071812135, 5936.257967754031, 5965.985782416999, 5983.252093488314, 4453.665975051884, 5058.2412638104215, 5312.389075256357, 5363.329583802025, 5388.892222155535, 5724.313082430093, 6220.222472190976, 6222.595531290452, 6407.935920640827, 6505.93494065058, 4390.8902277443, 4394.978025109854, 4396.912061758779, 4401.361802538646, 4420.491643461011, 5900.76396944126, 6407.967960160169, 6428.839279018062, 6542.672866356663, 6862.965685171592, 4411.18393097278, 4418.7127836690515, 4436.44544431946, 4442.911141777146, 4456.7548784817145, 4489.910201795946, 4492.550744925481, 4505.279155335131, 4505.932411013838, 4519.344695019269, 4526.524714904954, 5025.572826309732, 5144.15121504955, 5359.732013399314, 5382.138857782736, 6537.509686773511, 6596.637184954778, 6609.834754131089, 6617.43751781104, 6648.16897887764, 1389.3817251323267, 1454.7817827325987, 1540.399244294726, 1555.8408985306019, 1557.7896983907028, 924.1682485762733, 949.6248981652209, 1002.1682003936861, 1014.7412409835445, 1021.1626466297565, 4960.429550601655, 6035.155078289043, 6087.634741915499, 6088.11722300264, 6122.826396629222, 5973.731182096816, 6741.898871516931, 6777.661116944133, 6804.400965773039, 6806.7957961261245, 4832.830850920201, 5993.820185394445, 6019.518438524908, 6028.336882942873, 6038.849028774316, 3118.7660925430228, 3518.7888726676483, 3543.9191046730994, 3549.290141971616, 3584.874529391511, 6552.967235163794, 6944.569400709711, 6966.860662786716, 6989.650517474106, 7075.787726368218, 7037.718491260394, 7058.0, 7083.92916070843, 7098.36114749675, 7114.893276600855, 5612.35457922336, 6116.938830611637, 6921.653917304216, 6928.826779330557, 6954.165500139914, 5661.433856614306, 6835.4189893859575, 6848.383645471931, 6853.314668533107, 6860.108185935796, 5879.676617786063, 5968.80623875218, 6758.324167583399, 6768.187817461936, 6793.5244532882225, 6870.3473170048765, 6943.895841562279, 6986.685599147949, 6993.300669932966, 7071.0, 4441.7779111044965, 4441.888952776207, 4457.108578284291, 4478.708883922534, 4486.035502367007, 5239.140105262729, 6391.360863913572, 6471.473047189199, 6626.90059649096, 6920.757773477904, 5273.655217919406, 5458.2904222450825, 6016.42843929826, 6263.154474145932, 6518.413342799171, 6342.841428964128, 6535.751761355157, 6559.836004099936, 6567.62079963194, 6582.769603064061, 5238.71187084714, 5573.916391254053, 6081.756729730761, 6413.326600706859, 6422.293547709654, 6531.412172904462, 6548.574342667712, 6557.508186885907, 6563.3764792344655, 6597.868042639025, 6052.636841789506, 6253.124562561154, 6294.590851594632, 6331.841703957253, 6391.96804015977], "value": 4723.064860131197, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.891728406171, 712.4834494991132, 724.275724275724, 725.9637018149091, 741.8219627289452, 12058.27650340979, 15463.768043479356, 15637.687246255067, 15669.29488173032, 15724.754316334584, 12245.32650704211, 14192.574222773308, 14486.348114334854, 14665.706685866277, 14752.33614487348, 936.7049379445469, 952.9904700952972, 957.707899090777, 958.1472489484364, 958.4249450329816, 927.1331305229598, 955.0974329258853, 961.4087336288202, 963.0850691842743, 964.8166848298836, 1037.341288281939, 1037.8650775399226, 1041.6927006533092, 1042.213129087538, 1042.9009244121796, 1034.2450011491578, 1035.4822588705647, 1035.53400969564, 1035.9119474844647, 1038.7091614347994, 770.2951799103813, 772.3782355204058, 774.5159275452842, 775.1976704111274, 776.491398134223, 15866.968647037906, 16267.674646507003, 16391.754123688155, 16619.66760664792, 16797.58006049846, 14436.783448248285, 14583.343675074051, 14614.27126910792, 14711.779323310158, 14726.48457304, 11520.769584608344, 11647.30116193031, 11782.587609433715, 11899.643010709606, 12171.634850954486, 3000.264935090917, 3005.069507703278, 3058.0, 3070.431970085519, 3124.6875312468796, 105622.47188764007, 109391.0, 109590.90409095887, 110102.0, 114117.85882141156, 10381.325213861075, 10853.837192442119, 10857.13142948578, 10879.456027198608, 11415.60045398407, 23048.0, 23434.66458705145, 23709.0, 23717.0, 23762.287131386085, 7773.805654858561, 7844.764657060297, 8001.079875814239, 8123.268905798506, 8160.75517734457, 3731.1231860512758, 3768.660820526166, 4110.280700877334, 4206.158768246363, 4280.293751530962, 4060.2894493463527, 4168.103857670615, 4172.311568591149, 4174.7077704561, 4176.979115104466, 676.462212541031, 710.1868360726938, 717.1573401253506, 731.2139450091173, 733.7835338575134, 536.9516743493105, 556.9192467092249, 565.547561950438, 567.4042255631653, 576.8693361012379, 8460.846983619682, 9562.364765826766, 9756.0, 9853.261005424622, 10046.346987445793, 522.9111051121318, 549.4779959038929, 576.1703147467633, 580.5616759346666, 589.2722487727652, 3716.6840818530186, 4603.17142914272, 4746.501617330199, 4766.475687674353, 4835.298881662139, 93936.53031734798, 98178.03643927081, 100605.0, 101344.0, 103097.48451257695, 65772.0, 81192.59403703062, 82644.17355826365, 83492.0, 85249.0, 59934.50163745854, 70179.94730079053, 72751.90872136923, 73010.63494682492, 74145.25854741491, 10808.609945629174, 10827.945860270649, 10880.564777409132, 10909.963313574028, 11080.667579972614, 35699.0, 37923.0, 38673.80663096667, 38855.834324970296, 39728.80813575597, 40121.59878401295, 48470.5152948466, 51727.74136129446, 52440.73779631077, 53911.73044134754, 13402.195868247936, 13431.86568134306, 13725.245111518961, 13809.79285310701, 13976.510822121583, 14517.201553914636, 14561.41754329815, 14689.559313220621, 15139.470118545798, 15148.545543633709, 13612.931935340655, 13621.659458513617, 13968.720625587228, 14313.069650472686, 14608.853911461174, 11546.48040838181, 11712.414379280832, 11770.58802941893, 12104.576339828063, 12314.059500959607, 13054.459744448579, 13223.924725812774, 13363.26502042397, 13373.732525349633, 13957.371918263905, 9618.326717129734, 9715.902840971783, 9797.559109840216, 9938.45338506389, 10033.548490318099, 8458.954776280658, 10467.685969420625, 10596.31123976939, 11132.610358637408, 11310.660680179608], "value": 7391.562251963279, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 112.0, 111.0, 112.0, 111.0, 111.0, 111.0, 111.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 112.0, 151.0, 121.0, 127.0, 103.0, 110.0, 132.0, 111.0], "value": 122.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 181.0, 182.0, 186.0, 185.0, 184.0, 184.0, 181.0, 184.0, 184.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 62.0, 88.0, 62.0, 58.0, 58.0, 57.0, 79.0, 64.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 71.0, 88.0, 71.0, 69.0, 70.0, 84.0, 101.0, 102.0, 119.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 87.0, 88.0, 87.0, 87.0, 85.0, 87.0, 86.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 136.0, 151.0, 152.0, 95.0, 97.0, 93.0, 93.0], "value": 110.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 86.0, 88.0, 83.0, 84.0, 101.0, 133.0, 131.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 58.0, 59.0, 61.0, 61.0, 62.0, 62.0, 62.0, 61.0], "value": 61.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 64.0, 68.0, 62.0, 60.0, 56.0, 66.0, 83.0, 82.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 83.0, 84.0, 84.0, 82.0, 80.0, 82.0, 81.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 133.0, 147.0, 144.0, 144.0, 146.0, 141.0, 149.0, 204.0, 165.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 226.0, 191.0, 193.0, 189.0, 185.0, 186.0, 188.0, 187.0, 184.0], "value": 191.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 67.0, 69.0, 66.0, 70.0, 68.0, 67.0, 69.0], "value": 68.1, "unit": "score"}]}]} 15:30:41 INFO - Return code: 0 15:30:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:30:41 ERROR - # TBPL SUCCESS # 15:30:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:30:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:30:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:30:41 INFO - 2017-03-23 15:30:41.191 screenresolution[1652:9843] starting screenresolution argv=screenresolution get 15:30:41 INFO - 2017-03-23 15:30:41.194 screenresolution[1652:9843] Display 0: 1600x1200x32@60 15:30:41 INFO - 2017-03-23 15:30:41.200 screenresolution[1653:9846] starting screenresolution argv=screenresolution list 15:30:41 INFO - Available Modes on Display 0 15:30:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:30:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:30:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:30:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:30:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:30:41 INFO - Intel Iris: 15:30:41 INFO - Chipset Model: Intel Iris 15:30:41 INFO - Type: GPU 15:30:41 INFO - Bus: Built-In 15:30:41 INFO - VRAM (Dynamic, Max): 1536 MB 15:30:41 INFO - Vendor: Intel (0x8086) 15:30:41 INFO - Device ID: 0x0a2e 15:30:41 INFO - Revision ID: 0x0009 15:30:41 INFO - Displays: 15:30:41 INFO - Display: 15:30:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:30:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:30:41 INFO - Display Serial Number: 1600x1200 60 15:30:41 INFO - Main Display: Yes 15:30:41 INFO - Mirror: Off 15:30:41 INFO - Online: Yes 15:30:41 INFO - Rotation: Supported 15:30:41 INFO - Return code: 0 15:30:41 INFO - Running post-action listener: _resource_record_post_action 15:30:41 INFO - [mozharness: 2017-03-23 22:30:41.435954Z] Finished run-tests step (success) 15:30:41 INFO - Running post-run listener: _resource_record_post_run 15:30:41 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 38208512; Write bytes: 515696640; Read time: 243; Write time: 1596 15:30:41 INFO - TinderboxPrint: CPU usage
53.3% 15:30:41 INFO - TinderboxPrint: I/O read bytes / time
38,208,512 / 243 15:30:41 INFO - TinderboxPrint: I/O write bytes / time
515,696,640 / 1,596 15:30:41 INFO - TinderboxPrint: CPU idle
2,247.5 (46.7%) 15:30:41 INFO - TinderboxPrint: CPU system
957.1 (19.9%) 15:30:41 INFO - TinderboxPrint: CPU user
1,613.0 (33.5%) 15:30:41 INFO - TinderboxPrint: Swap in / out
248,967,168 / 0 15:30:41 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208747008; Write bytes: 204523520; Read time: 15764; Write time: 449 15:30:41 INFO - run-tests - Wall time: 1185s; CPU: 53.0%; Read bytes: 36099072; Write bytes: 300052480; Read time: 222; Write time: 1110 15:30:41 INFO - Running post-run listener: _upload_blobber_files 15:30:41 INFO - Blob upload gear active. 15:30:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:30:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:30:41 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:30:41 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:30:42 INFO - (blobuploader) - INFO - Open directory for files ... 15:30:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:30:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:30:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:30:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:30:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:30:43 INFO - (blobuploader) - INFO - Done attempting. 15:30:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:30:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:30:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:30:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:30:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:30:44 INFO - (blobuploader) - INFO - Done attempting. 15:30:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:30:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:30:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:30:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:30:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:30:45 INFO - (blobuploader) - INFO - Done attempting. 15:30:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:30:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:30:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:30:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:30:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:30:47 INFO - (blobuploader) - INFO - Done attempting. 15:30:47 INFO - (blobuploader) - INFO - Iteration through files over. 15:30:47 INFO - Return code: 0 15:30:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:30:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:30:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/beda4393457f52a8659c3444bcec0e90b19033c657356985b7fbb67c2e84840038005a5fa89c1e8c5a0f4dc500030e4cca1657915b3d37fa3db74e475adb145b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3bf6457e15504f42576f522348786fbee61b398d92e0d87b53d7a3046fbf02371f82b915fe9cd53204c2666e2623f1a98c5dfd92524cf6a928b7300df2fc845a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/559d91d2f80eb68df9835e8c7a3fc70899e60fcb70f88a1b4c2f90047704182138fb6502671158cd647ffbb250ff2abf18f684799106ee4cffb2ded019578ec9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5f2a010ec5b0cb1ff342a301df552945adf3f0ec4888babce0a94973554ecfdbe36559e660a7dfb764af296998dcd653218594152ecbe522a63c3043a6c02a87"} 15:30:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:30:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:30:47 INFO - Contents: 15:30:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/beda4393457f52a8659c3444bcec0e90b19033c657356985b7fbb67c2e84840038005a5fa89c1e8c5a0f4dc500030e4cca1657915b3d37fa3db74e475adb145b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3bf6457e15504f42576f522348786fbee61b398d92e0d87b53d7a3046fbf02371f82b915fe9cd53204c2666e2623f1a98c5dfd92524cf6a928b7300df2fc845a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/559d91d2f80eb68df9835e8c7a3fc70899e60fcb70f88a1b4c2f90047704182138fb6502671158cd647ffbb250ff2abf18f684799106ee4cffb2ded019578ec9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5f2a010ec5b0cb1ff342a301df552945adf3f0ec4888babce0a94973554ecfdbe36559e660a7dfb764af296998dcd653218594152ecbe522a63c3043a6c02a87"} 15:30:47 INFO - Running post-run listener: copy_logs_to_upload_dir 15:30:47 INFO - Copying logs to upload dir... 15:30:47 INFO - mkdir: /builds/slave/test/build/upload/logs 15:30:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.776065 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-23 15:30:47.695918) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 15:30:47.700467) ========= 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.zXO0EcZS5T/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.bL1unno0VX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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/Pine/sha512/beda4393457f52a8659c3444bcec0e90b19033c657356985b7fbb67c2e84840038005a5fa89c1e8c5a0f4dc500030e4cca1657915b3d37fa3db74e475adb145b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3bf6457e15504f42576f522348786fbee61b398d92e0d87b53d7a3046fbf02371f82b915fe9cd53204c2666e2623f1a98c5dfd92524cf6a928b7300df2fc845a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/559d91d2f80eb68df9835e8c7a3fc70899e60fcb70f88a1b4c2f90047704182138fb6502671158cd647ffbb250ff2abf18f684799106ee4cffb2ded019578ec9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5f2a010ec5b0cb1ff342a301df552945adf3f0ec4888babce0a94973554ecfdbe36559e660a7dfb764af296998dcd653218594152ecbe522a63c3043a6c02a87"} build_url:https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011300 build_url: 'https://queue.taskcluster.net/v1/task/AKr-5rxGQLeLP6MA_7FoZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/beda4393457f52a8659c3444bcec0e90b19033c657356985b7fbb67c2e84840038005a5fa89c1e8c5a0f4dc500030e4cca1657915b3d37fa3db74e475adb145b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3bf6457e15504f42576f522348786fbee61b398d92e0d87b53d7a3046fbf02371f82b915fe9cd53204c2666e2623f1a98c5dfd92524cf6a928b7300df2fc845a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/559d91d2f80eb68df9835e8c7a3fc70899e60fcb70f88a1b4c2f90047704182138fb6502671158cd647ffbb250ff2abf18f684799106ee4cffb2ded019578ec9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5f2a010ec5b0cb1ff342a301df552945adf3f0ec4888babce0a94973554ecfdbe36559e660a7dfb764af296998dcd653218594152ecbe522a63c3043a6c02a87"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 15:30:47.738111) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 15:30:47.738516) ========= 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.zXO0EcZS5T/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.bL1unno0VX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.005263 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 15:30:47.787189) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 15:30:47.787528) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 15:30:47.787889) ========= ========= Total master_lag: 0.23 =========