builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0286 starttime: 1485301710.84 results: success (0) buildid: 20170124133147 builduid: 4c719bc4463b4ee680d6d5dfb8f3842c revision: 68b72e1b219543ee47e99ba38e356ed7b7c66349 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:30.844745) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:30.845188) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:30.845483) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:30.872393) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:30.872786) ========= 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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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-01-24 15:48:30-- 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% 187M=0s 2017-01-24 15:48:30 (187 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.076557 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:31.081985) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:31.082468) ========= 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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.026135 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:31.130471) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-24 15:48:31.130823) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 68b72e1b219543ee47e99ba38e356ed7b7c66349 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 68b72e1b219543ee47e99ba38e356ed7b7c66349 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4a3rRXu4Ur/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.c1RDGKFltB/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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-01-24 15:48:31,238 truncating revision to first 12 chars 2017-01-24 15:48:31,238 Setting DEBUG logging. 2017-01-24 15:48:31,238 attempt 1/10 2017-01-24 15:48:31,238 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/68b72e1b2195?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-24 15:48:33,095 unpacking tar archive at: graphics-68b72e1b2195/testing/mozharness/ program finished with exit code 0 elapsedTime=2.124587 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-24 15:48:33.278418) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:33.278750) ========= script_repo_revision: 68b72e1b219543ee47e99ba38e356ed7b7c66349 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:33.279130) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:33.283449) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-24 15:48:33.305787) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-24 15:48:33.306146) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4a3rRXu4Ur/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.c1RDGKFltB/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:48:33 INFO - MultiFileLogger online at 20170124 15:48:33 in /builds/slave/test 15:48:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 15:48:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:48:33 INFO - {'append_to_log': False, 15:48:33 INFO - 'base_work_dir': '/builds/slave/test', 15:48:33 INFO - 'blob_upload_branch': 'Graphics', 15:48:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:48:33 INFO - 'branch': 'Graphics', 15:48:33 INFO - 'buildbot_json_path': 'buildprops.json', 15:48:33 INFO - 'config_files': ('talos/mac_config.py',), 15:48:33 INFO - 'default_actions': ('clobber', 15:48:33 INFO - 'read-buildbot-config', 15:48:33 INFO - 'download-and-extract', 15:48:33 INFO - 'populate-webroot', 15:48:33 INFO - 'create-virtualenv', 15:48:33 INFO - 'install', 15:48:33 INFO - 'run-tests'), 15:48:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:48:33 INFO - 'download_minidump_stackwalk': True, 15:48:33 INFO - 'download_symbols': 'ondemand', 15:48:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:48:33 INFO - 'tooltool.py': '/tools/tooltool.py', 15:48:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:48:33 INFO - '/tools/misc-python/virtualenv.py')}, 15:48:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:48:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:48:33 INFO - 'installer_path': 'installer.exe', 15:48:33 INFO - 'log_level': 'info', 15:48:33 INFO - 'log_name': 'talos', 15:48:33 INFO - 'log_to_console': True, 15:48:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:48:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:48:33 INFO - 'opt_config_files': (), 15:48:33 INFO - 'pip_index': False, 15:48:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:48:33 INFO - 'cmd': ('bash', 15:48:33 INFO - '-c', 15:48:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:48:33 INFO - 'enabled': True, 15:48:33 INFO - 'halt_on_failure': False, 15:48:33 INFO - 'name': 'check_screen_resolution'},), 15:48:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:48:33 INFO - 'cmd': ('bash', 15:48:33 INFO - '-c', 15:48:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:48:33 INFO - 'enabled': True, 15:48:33 INFO - 'halt_on_failure': False, 15:48:33 INFO - 'name': 'check_screen_resolution'},), 15:48:33 INFO - 'run_cmd_checks_enabled': True, 15:48:33 INFO - 'sps_profile': False, 15:48:33 INFO - 'sps_profile_interval': 0, 15:48:33 INFO - 'suite': 'dromaeojs', 15:48:33 INFO - 'system_bits': '32', 15:48:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:48:33 INFO - 'title': 't-yosemite-r7-0286', 15:48:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:48:33 INFO - 'use_talos_json': True, 15:48:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:48:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:48:33 INFO - 'work_dir': 'build'} 15:48:33 INFO - [mozharness: 2017-01-24 23:48:33.517332Z] Running clobber step. 15:48:33 INFO - Running pre-action listener: _resource_record_pre_action 15:48:33 INFO - Running main action method: clobber 15:48:33 INFO - rmtree: /builds/slave/test/build 15:48:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:48:36 INFO - Running post-action listener: _resource_record_post_action 15:48:36 INFO - [mozharness: 2017-01-24 23:48:36.007471Z] Finished clobber step (success) 15:48:36 INFO - [mozharness: 2017-01-24 23:48:36.007651Z] Running read-buildbot-config step. 15:48:36 INFO - Running pre-action listener: _resource_record_pre_action 15:48:36 INFO - Running main action method: read_buildbot_config 15:48:36 INFO - Using buildbot properties: 15:48:36 INFO - { 15:48:36 INFO - "project": "", 15:48:36 INFO - "product": "firefox", 15:48:36 INFO - "script_repo_revision": "default", 15:48:36 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 15:48:36 INFO - "repository": "", 15:48:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 15:48:36 INFO - "buildid": "20170124133147", 15:48:36 INFO - "pgo_build": "False", 15:48:36 INFO - "basedir": "/builds/slave/test", 15:48:36 INFO - "buildnumber": 15, 15:48:36 INFO - "slavename": "t-yosemite-r7-0286", 15:48:36 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 15:48:36 INFO - "platform": "yosemite_r7", 15:48:36 INFO - "scheduler": "tests-graphics-macosx64-talos", 15:48:36 INFO - "branch": "graphics", 15:48:36 INFO - "repo_path": "projects/graphics", 15:48:36 INFO - "revision": "68b72e1b219543ee47e99ba38e356ed7b7c66349", 15:48:36 INFO - "stage_platform": "macosx64", 15:48:36 INFO - "builduid": "4c719bc4463b4ee680d6d5dfb8f3842c", 15:48:36 INFO - "slavebuilddir": "test" 15:48:36 INFO - } 15:48:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 15:48:36 INFO - Running post-action listener: _resource_record_post_action 15:48:36 INFO - [mozharness: 2017-01-24 23:48:36.011350Z] Finished read-buildbot-config step (success) 15:48:36 INFO - [mozharness: 2017-01-24 23:48:36.011485Z] Running download-and-extract step. 15:48:36 INFO - Running pre-action listener: _resource_record_pre_action 15:48:36 INFO - Running main action method: download_and_extract 15:48:36 INFO - mkdir: /builds/slave/test/build/tests 15:48: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:48:36 INFO - trying https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 15:48:36 INFO - Downloading https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 15:48:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 15:48:37 INFO - Downloaded 1199 bytes. 15:48:37 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 15:48:37 INFO - Using the following test package requirements: 15:48:37 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 15:48:37 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:48:37 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 15:48:37 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:48:37 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 15:48:37 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:48:37 INFO - u'jsshell-mac.zip'], 15:48:37 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:48:37 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 15:48:37 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 15:48:37 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:48:37 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 15:48:37 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:48:37 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 15:48:37 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:48:37 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 15:48:37 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:48:37 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 15:48:37 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:48:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 15:48:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:48:37 INFO - Fetch https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 15:48:39 INFO - Expected file size: 16351062 15:48:39 INFO - Obtained file size: 16351062 15:48:46 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:48:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 15:48:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:48:46 INFO - Fetch https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 15:48:46 INFO - Expected file size: 16351062 15:48:46 INFO - Obtained file size: 16351062 15:48:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 15:48:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:48:53 INFO - Fetch https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 15:48:55 INFO - Expected file size: 14403485 15:48:55 INFO - Obtained file size: 14403485 15:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:55 INFO - trying https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 15:48:55 INFO - Downloading https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 15:48:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 15:48:58 INFO - Downloaded 70111545 bytes. 15:48:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 15:48:58 INFO - mkdir: /builds/slave/test/properties 15:48:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:48:58 INFO - Writing to file /builds/slave/test/properties/build_url 15:48:58 INFO - Contents: 15:48:58 INFO - build_url:https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 15:48:59 INFO - Running post-action listener: _resource_record_post_action 15:48:59 INFO - Running post-action listener: set_extra_try_arguments 15:48:59 INFO - [mozharness: 2017-01-24 23:48:59.505473Z] Finished download-and-extract step (success) 15:48:59 INFO - [mozharness: 2017-01-24 23:48:59.505612Z] Running populate-webroot step. 15:48:59 INFO - Running pre-action listener: _resource_record_pre_action 15:48:59 INFO - Running main action method: populate_webroot 15:48:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:48:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:48:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:48:59 INFO - '--tppagecycles', 15:48:59 INFO - '7'], 15:48:59 INFO - 'tests': ['tsvgm']}}, 15:48:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:48:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:48:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:48:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:48:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:48:59 INFO - 'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:48:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:48:59 INFO - 'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['damp', 'tps']}, 15:48:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:48:59 INFO - 'tests': ['damp', 'tps']}, 15:48:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['dromaeo_dom']}, 15:48:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:48:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['basic_compositor_video']}, 15:48:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:48:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'other-e10s': {'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['tsvgx', 15:48:59 INFO - 'tsvgr_opacity', 15:48:59 INFO - 'tart', 15:48:59 INFO - 'tscrollx', 15:48:59 INFO - 'cart', 15:48:59 INFO - 'tsvg_static']}, 15:48:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:48:59 INFO - 'tsvgr_opacity', 15:48:59 INFO - 'tart', 15:48:59 INFO - 'tscrollx', 15:48:59 INFO - 'cart', 15:48:59 INFO - 'tsvg_static']}, 15:48:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:48:59 INFO - 'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['tp5o']}, 15:48:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:48:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:48:59 INFO - 'talos_options': ['--disable-e10s', 15:48:59 INFO - '--xperf_path', 15:48:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:59 INFO - 'tests': ['tp5n']}, 15:48:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:48:59 INFO - 'talos_options': ['--xperf_path', 15:48:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:59 INFO - 'tests': ['tp5n']}}, 15:48:59 INFO - 'talos.zip': {'path': '', 15:48:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:48:59 INFO - Running post-action listener: _resource_record_post_action 15:48:59 INFO - [mozharness: 2017-01-24 23:48:59.526145Z] Finished populate-webroot step (success) 15:48:59 INFO - [mozharness: 2017-01-24 23:48:59.526380Z] Running create-virtualenv step. 15:48:59 INFO - Running pre-action listener: _resource_record_pre_action 15:48:59 INFO - Running main action method: create_virtualenv 15:48:59 INFO - Creating virtualenv /builds/slave/test/build/venv 15:48:59 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:48:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:48:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:48:59 INFO - Using real prefix '/tools/python27' 15:48:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:49:00 INFO - Installing distribute.............................................................................................................................................................................................done. 15:49:03 INFO - Installing pip.................done. 15:49:03 INFO - Return code: 0 15:49:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:49:03 INFO - Reading from file tmpfile_stdout 15:49:03 INFO - Output received: 15:49:03 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:49:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108c59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1805674f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a37d30>, '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 0x10895edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a2cbe8>, '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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:49:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4a3rRXu4Ur/Render', 15:49:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:03 INFO - 'HOME': '/Users/cltbld', 15:49:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:03 INFO - 'LOGNAME': 'cltbld', 15:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:03 INFO - 'MOZ_NO_REMOTE': '1', 15:49:03 INFO - 'NO_EM_RESTART': '1', 15:49:03 INFO - 'PAGER': '/bin/cat', 15:49:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:03 INFO - 'PWD': '/builds/slave/test', 15:49:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:03 INFO - 'SHELL': '/bin/bash', 15:49:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1RDGKFltB/Listeners', 15:49:03 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 15:49:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:03 INFO - 'USER': 'cltbld', 15:49:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:03 INFO - 'XPC_FLAGS': '0x0', 15:49:03 INFO - 'XPC_SERVICE_NAME': '0', 15:49:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:03 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:49:03 INFO - Cleaning up... 15:49:03 INFO - Return code: 0 15:49:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108c59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1805674f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a37d30>, '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 0x10895edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a2cbe8>, '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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:49:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4a3rRXu4Ur/Render', 15:49:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:03 INFO - 'HOME': '/Users/cltbld', 15:49:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:03 INFO - 'LOGNAME': 'cltbld', 15:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:03 INFO - 'MOZ_NO_REMOTE': '1', 15:49:03 INFO - 'NO_EM_RESTART': '1', 15:49:03 INFO - 'PAGER': '/bin/cat', 15:49:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:03 INFO - 'PWD': '/builds/slave/test', 15:49:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:03 INFO - 'SHELL': '/bin/bash', 15:49:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1RDGKFltB/Listeners', 15:49:03 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 15:49:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:03 INFO - 'USER': 'cltbld', 15:49:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:03 INFO - 'XPC_FLAGS': '0x0', 15:49:03 INFO - 'XPC_SERVICE_NAME': '0', 15:49:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:04 INFO - Downloading/unpacking psutil>=3.1.1 15:49:04 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:49:04 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:49:04 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:49:04 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:49:04 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:49:04 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:49:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:49:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:07 INFO - Installing collected packages: psutil 15:49:07 INFO - Running setup.py install for psutil 15:49:07 INFO - building 'psutil._psutil_osx' extension 15:49:07 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:49:08 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:49:08 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:49:08 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:49:08 INFO - building 'psutil._psutil_posix' extension 15:49:08 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:49:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:49:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:49:08 INFO - ^ 15:49:08 INFO - 1 warning generated. 15:49:08 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:49:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:08 INFO - Successfully installed psutil 15:49:08 INFO - Cleaning up... 15:49:08 INFO - Return code: 0 15:49:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:08 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 0x108c59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1805674f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a37d30>, '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 0x10895edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a2cbe8>, '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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:49:08 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:49:08 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:49:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4a3rRXu4Ur/Render', 15:49:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:08 INFO - 'HOME': '/Users/cltbld', 15:49:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:08 INFO - 'LOGNAME': 'cltbld', 15:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:08 INFO - 'MOZ_NO_REMOTE': '1', 15:49:08 INFO - 'NO_EM_RESTART': '1', 15:49:08 INFO - 'PAGER': '/bin/cat', 15:49:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:08 INFO - 'PWD': '/builds/slave/test', 15:49:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:08 INFO - 'SHELL': '/bin/bash', 15:49:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1RDGKFltB/Listeners', 15:49:08 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 15:49:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:08 INFO - 'USER': 'cltbld', 15:49:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:08 INFO - 'XPC_FLAGS': '0x0', 15:49:08 INFO - 'XPC_SERVICE_NAME': '0', 15:49:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:49:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:49:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:49:12 INFO - Installing collected packages: mozsystemmonitor 15:49:12 INFO - Running setup.py install for mozsystemmonitor 15:49:12 INFO - Successfully installed mozsystemmonitor 15:49:12 INFO - Cleaning up... 15:49:12 INFO - Return code: 0 15:49:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:49: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:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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', '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 0x108c59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1805674f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a37d30>, '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 0x10895edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a2cbe8>, '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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:49: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:49: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 jsonschema==2.5.1 15:49:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4a3rRXu4Ur/Render', 15:49:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:12 INFO - 'HOME': '/Users/cltbld', 15:49:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:12 INFO - 'LOGNAME': 'cltbld', 15:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:12 INFO - 'MOZ_NO_REMOTE': '1', 15:49:12 INFO - 'NO_EM_RESTART': '1', 15:49:12 INFO - 'PAGER': '/bin/cat', 15:49:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:12 INFO - 'PWD': '/builds/slave/test', 15:49:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:12 INFO - 'SHELL': '/bin/bash', 15:49:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1RDGKFltB/Listeners', 15:49:12 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 15:49:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:12 INFO - 'USER': 'cltbld', 15:49:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:12 INFO - 'XPC_FLAGS': '0x0', 15:49:12 INFO - 'XPC_SERVICE_NAME': '0', 15:49:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:12 INFO - Downloading/unpacking jsonschema==2.5.1 15:49: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:49: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:49: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:49: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:49: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:49: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:49:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:49:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:15 INFO - Downloading functools32-3.2.3-2.tar.gz 15:49:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:49:15 INFO - warning: no files found matching '*.txt' 15:49:15 INFO - no previously-included directories found matching 'build' 15:49:15 INFO - no previously-included directories found matching 'dist' 15:49:15 INFO - no previously-included directories found matching '.git*' 15:49:15 INFO - Installing collected packages: jsonschema, functools32 15:49:15 INFO - Running setup.py install for functools32 15:49:15 INFO - warning: no files found matching '*.txt' 15:49:15 INFO - no previously-included directories found matching 'build' 15:49:15 INFO - no previously-included directories found matching 'dist' 15:49:15 INFO - no previously-included directories found matching '.git*' 15:49:15 INFO - Successfully installed jsonschema functools32 15:49:15 INFO - Cleaning up... 15:49:16 INFO - Return code: 0 15:49:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:49: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:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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', '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 0x108c59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1805674f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a37d30>, '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 0x10895edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a2cbe8>, '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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:49: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:49: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 functools32==3.2.3-2 15:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4a3rRXu4Ur/Render', 15:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:16 INFO - 'HOME': '/Users/cltbld', 15:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:16 INFO - 'LOGNAME': 'cltbld', 15:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:16 INFO - 'MOZ_NO_REMOTE': '1', 15:49:16 INFO - 'NO_EM_RESTART': '1', 15:49:16 INFO - 'PAGER': '/bin/cat', 15:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:16 INFO - 'PWD': '/builds/slave/test', 15:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:16 INFO - 'SHELL': '/bin/bash', 15:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1RDGKFltB/Listeners', 15:49:16 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 15:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:16 INFO - 'USER': 'cltbld', 15:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:16 INFO - 'XPC_FLAGS': '0x0', 15:49:16 INFO - 'XPC_SERVICE_NAME': '0', 15:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:49:16 INFO - Cleaning up... 15:49:16 INFO - Return code: 0 15:49:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:49: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:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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', '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 0x108c59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1805674f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a37d30>, '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 0x10895edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a2cbe8>, '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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:49: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:49: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 blobuploader==1.2.4 15:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4a3rRXu4Ur/Render', 15:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:16 INFO - 'HOME': '/Users/cltbld', 15:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:16 INFO - 'LOGNAME': 'cltbld', 15:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:16 INFO - 'MOZ_NO_REMOTE': '1', 15:49:16 INFO - 'NO_EM_RESTART': '1', 15:49:16 INFO - 'PAGER': '/bin/cat', 15:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:16 INFO - 'PWD': '/builds/slave/test', 15:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:16 INFO - 'SHELL': '/bin/bash', 15:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1RDGKFltB/Listeners', 15:49:16 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 15:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:16 INFO - 'USER': 'cltbld', 15:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:16 INFO - 'XPC_FLAGS': '0x0', 15:49:16 INFO - 'XPC_SERVICE_NAME': '0', 15:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:16 INFO - Downloading/unpacking blobuploader==1.2.4 15:49: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:49: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:49: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:49: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:49: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:49: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:49:19 INFO - Downloading blobuploader-1.2.4.tar.gz 15:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:49:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:49: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:49: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:49: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:49: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:49: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:49: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:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:49:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:20 INFO - Downloading docopt-0.6.1.tar.gz 15:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:49:20 INFO - Installing collected packages: blobuploader, requests, docopt 15:49:20 INFO - Running setup.py install for blobuploader 15:49:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:49:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:49:20 INFO - Running setup.py install for requests 15:49:21 INFO - Running setup.py install for docopt 15:49:21 INFO - Successfully installed blobuploader requests docopt 15:49:21 INFO - Cleaning up... 15:49:21 INFO - Return code: 0 15:49:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:21 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:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:21 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:49:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:21 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 0x108c59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1805674f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a37d30>, '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 0x10895edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a2cbe8>, '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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:49:21 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:49:21 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:49:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4a3rRXu4Ur/Render', 15:49:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:21 INFO - 'HOME': '/Users/cltbld', 15:49:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:21 INFO - 'LOGNAME': 'cltbld', 15:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:21 INFO - 'MOZ_NO_REMOTE': '1', 15:49:21 INFO - 'NO_EM_RESTART': '1', 15:49:21 INFO - 'PAGER': '/bin/cat', 15:49:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:21 INFO - 'PWD': '/builds/slave/test', 15:49:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:21 INFO - 'SHELL': '/bin/bash', 15:49:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1RDGKFltB/Listeners', 15:49:21 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 15:49:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:21 INFO - 'USER': 'cltbld', 15:49:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:21 INFO - 'XPC_FLAGS': '0x0', 15:49:21 INFO - 'XPC_SERVICE_NAME': '0', 15:49:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:21 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-1RWL4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:21 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-8BWxbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:21 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-eTEub3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:21 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-tJVWYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:22 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-3Efgnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:22 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-b2MBVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:22 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-yYmtFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:22 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-NYnlSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:22 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-FSjvuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:22 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-HHtFL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:22 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-E65_gQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:22 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Q8bCmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:22 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-vgSbkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:23 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-gy5OHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:23 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-FekSse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:23 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-7CnvaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:23 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-6mUshJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:49:23 INFO - Running setup.py install for manifestparser 15:49:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:49:23 INFO - Running setup.py install for mozcrash 15:49:23 INFO - Running setup.py install for mozdebug 15:49:23 INFO - Running setup.py install for mozdevice 15:49:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:49:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:49:24 INFO - Running setup.py install for mozfile 15:49:24 INFO - Running setup.py install for mozhttpd 15:49:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:49:24 INFO - Running setup.py install for mozinfo 15:49:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:49:24 INFO - Running setup.py install for mozInstall 15:49:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:49:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:49:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:49:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:49:24 INFO - Running setup.py install for mozleak 15:49:24 INFO - Running setup.py install for mozlog 15:49:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:49:24 INFO - Running setup.py install for moznetwork 15:49:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:49:25 INFO - Running setup.py install for mozprocess 15:49:25 INFO - Running setup.py install for mozprofile 15:49:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:49:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:49:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:49:25 INFO - Running setup.py install for mozrunner 15:49:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:49:25 INFO - Running setup.py install for mozscreenshot 15:49:25 INFO - Running setup.py install for moztest 15:49:25 INFO - Running setup.py install for mozversion 15:49:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:49:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:49:26 INFO - Cleaning up... 15:49:26 INFO - Return code: 0 15:49:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:26 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:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:26 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:49:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:26 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 0x108c59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1805674f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a37d30>, '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 0x10895edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a2cbe8>, '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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:49:26 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:49:26 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:49:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4a3rRXu4Ur/Render', 15:49:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:26 INFO - 'HOME': '/Users/cltbld', 15:49:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:26 INFO - 'LOGNAME': 'cltbld', 15:49:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:26 INFO - 'MOZ_NO_REMOTE': '1', 15:49:26 INFO - 'NO_EM_RESTART': '1', 15:49:26 INFO - 'PAGER': '/bin/cat', 15:49:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:26 INFO - 'PWD': '/builds/slave/test', 15:49:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:26 INFO - 'SHELL': '/bin/bash', 15:49:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1RDGKFltB/Listeners', 15:49:26 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 15:49:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:26 INFO - 'USER': 'cltbld', 15:49:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:26 INFO - 'XPC_FLAGS': '0x0', 15:49:26 INFO - 'XPC_SERVICE_NAME': '0', 15:49:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:27 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-hYuNCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:27 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-eQuE2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:27 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-4rr8Hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:27 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-mBkNDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:27 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-KAeLW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:27 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-44ZxYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:27 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-X4bYZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:27 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-pSjV9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:28 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:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:28 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-nLL27f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:28 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:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:28 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-WfzsWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:28 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:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:28 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-PYaHn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:28 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:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:28 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-hNmCZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:28 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-6knSDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:28 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:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:28 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-qKEqb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:28 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:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:28 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-vp2Ugq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:28 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:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:28 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-KlStSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:28 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:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:28 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-cL0YsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:28 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:49:28 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:49:28 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:49:28 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:49:28 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:49:28 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:49:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:49:28 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:49:28 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:49:28 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:49:28 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:49:28 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:49:28 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:49:31 INFO - Downloading blessings-1.6.tar.gz 15:49:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:49:32 INFO - Installing collected packages: blessings 15:49:32 INFO - Running setup.py install for blessings 15:49:32 INFO - Successfully installed blessings 15:49:32 INFO - Cleaning up... 15:49:32 INFO - Return code: 0 15:49:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:49:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:32 INFO - Reading from file tmpfile_stdout 15:49:32 INFO - Current package versions: 15:49:32 INFO - blessings == 1.6 15:49:32 INFO - blobuploader == 1.2.4 15:49:32 INFO - docopt == 0.6.1 15:49:32 INFO - functools32 == 3.2.3-2 15:49:32 INFO - jsonschema == 2.5.1 15:49:32 INFO - manifestparser == 1.1 15:49:32 INFO - mozInstall == 1.12 15:49:32 INFO - mozcrash == 1.0 15:49:32 INFO - mozdebug == 0.1 15:49:32 INFO - mozdevice == 0.49 15:49:32 INFO - mozfile == 1.2 15:49:32 INFO - mozhttpd == 0.7 15:49:32 INFO - mozinfo == 0.9 15:49:32 INFO - mozleak == 0.1 15:49:32 INFO - mozlog == 3.4 15:49:32 INFO - moznetwork == 0.27 15:49:32 INFO - mozprocess == 0.24 15:49:32 INFO - mozprofile == 0.28 15:49:32 INFO - mozrunner == 6.13 15:49:32 INFO - mozscreenshot == 0.1 15:49:32 INFO - mozsystemmonitor == 0.3 15:49:32 INFO - moztest == 0.8 15:49:32 INFO - mozversion == 1.4 15:49:32 INFO - psutil == 3.1.1 15:49:32 INFO - requests == 1.2.3 15:49:32 INFO - wsgiref == 0.1.2 15:49:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:32 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 0x108c59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1805674f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a37d30>, '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 0x10895edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a2cbe8>, '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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:49:32 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:49:32 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:49:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4a3rRXu4Ur/Render', 15:49:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:32 INFO - 'HOME': '/Users/cltbld', 15:49:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:32 INFO - 'LOGNAME': 'cltbld', 15:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:32 INFO - 'MOZ_NO_REMOTE': '1', 15:49:32 INFO - 'NO_EM_RESTART': '1', 15:49:32 INFO - 'PAGER': '/bin/cat', 15:49:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:32 INFO - 'PWD': '/builds/slave/test', 15:49:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:32 INFO - 'SHELL': '/bin/bash', 15:49:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1RDGKFltB/Listeners', 15:49:32 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 15:49:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:32 INFO - 'USER': 'cltbld', 15:49:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:32 INFO - 'XPC_FLAGS': '0x0', 15:49:32 INFO - 'XPC_SERVICE_NAME': '0', 15:49:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:32 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:49:32 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:49:32 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:49:32 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:49:32 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:49:32 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:49:32 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:49:32 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:49:32 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:49:32 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:49:32 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:49:32 INFO - Cleaning up... 15:49:32 INFO - Return code: 0 15:49:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:32 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 0x108c59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1805674f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a37d30>, '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 0x10895edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a2cbe8>, '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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:49:32 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:49:32 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:49:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4a3rRXu4Ur/Render', 15:49:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:32 INFO - 'HOME': '/Users/cltbld', 15:49:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:32 INFO - 'LOGNAME': 'cltbld', 15:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:32 INFO - 'MOZ_NO_REMOTE': '1', 15:49:32 INFO - 'NO_EM_RESTART': '1', 15:49:32 INFO - 'PAGER': '/bin/cat', 15:49:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:32 INFO - 'PWD': '/builds/slave/test', 15:49:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:32 INFO - 'SHELL': '/bin/bash', 15:49:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1RDGKFltB/Listeners', 15:49:32 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 15:49:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:32 INFO - 'USER': 'cltbld', 15:49:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:32 INFO - 'XPC_FLAGS': '0x0', 15:49:32 INFO - 'XPC_SERVICE_NAME': '0', 15:49:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:49:33 INFO - Cleaning up... 15:49:33 INFO - Return code: 0 15:49:33 INFO - Running post-action listener: _resource_record_post_action 15:49:33 INFO - Running post-action listener: _start_resource_monitoring 15:49:33 INFO - Starting resource monitoring. 15:49:33 INFO - [mozharness: 2017-01-24 23:49:33.210448Z] Finished create-virtualenv step (success) 15:49:33 INFO - [mozharness: 2017-01-24 23:49:33.211496Z] Running install step. 15:49:33 INFO - Running pre-action listener: _resource_record_pre_action 15:49:33 INFO - Running main action method: install 15:49:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:33 INFO - Reading from file tmpfile_stdout 15:49:33 INFO - Detecting whether we're running mozinstall >=1.0... 15:49:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:49:33 INFO - Reading from file tmpfile_stdout 15:49:33 INFO - Output received: 15:49:33 INFO - Usage: mozinstall [options] installer 15:49:33 INFO - Options: 15:49:33 INFO - -h, --help show this help message and exit 15:49:33 INFO - -d DEST, --destination=DEST 15:49:33 INFO - Directory to install application into. [default: 15:49:33 INFO - "/builds/slave/test"] 15:49:33 INFO - --app=APP Application being installed. [default: firefox] 15:49:33 INFO - mkdir: /builds/slave/test/build/application 15:49:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:49:55 INFO - Reading from file tmpfile_stdout 15:49:55 INFO - Output received: 15:49:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:49:55 INFO - Running post-action listener: _resource_record_post_action 15:49:55 INFO - [mozharness: 2017-01-24 23:49:55.763243Z] Finished install step (success) 15:49:55 INFO - [mozharness: 2017-01-24 23:49:55.763377Z] Running run-tests step. 15:49:55 INFO - Running pre-action listener: _resource_record_pre_action 15:49:55 INFO - Running main action method: run_tests 15:49:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:49:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:49:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:49:55 INFO - 2017-01-24 15:49:55.777 screenresolution[1627:7420] starting screenresolution argv=screenresolution get 15:49:55 INFO - 2017-01-24 15:49:55.780 screenresolution[1627:7420] Display 0: 1600x1200x32@60 15:49:55 INFO - 2017-01-24 15:49:55.786 screenresolution[1628:7423] starting screenresolution argv=screenresolution list 15:49:55 INFO - Available Modes on Display 0 15:49:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:49:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:49:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:49:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:49:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:49:56 INFO - Intel Iris: 15:49:56 INFO - Chipset Model: Intel Iris 15:49:56 INFO - Type: GPU 15:49:56 INFO - Bus: Built-In 15:49:56 INFO - VRAM (Dynamic, Max): 1536 MB 15:49:56 INFO - Vendor: Intel (0x8086) 15:49:56 INFO - Device ID: 0x0a2e 15:49:56 INFO - Revision ID: 0x0009 15:49:56 INFO - Displays: 15:49:56 INFO - Display: 15:49:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:49:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:49:56 INFO - Display Serial Number: 1600x1200 60 15:49:56 INFO - Main Display: Yes 15:49:56 INFO - Mirror: Off 15:49:56 INFO - Online: Yes 15:49:56 INFO - Rotation: Supported 15:49:56 INFO - Return code: 0 15:49:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:49:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:49:56 INFO - Python 2.7.3 15:49:56 INFO - Return code: 0 15:49:56 INFO - grabbing minidump binary from tooltool 15:49: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:49: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 0x108a37d30>, '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 0x10895edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a2cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:49: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:49: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:49: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:49:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:49:56 INFO - Return code: 0 15:49:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:49:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:49:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:49:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:49:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:49:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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:49:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0286 --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:49:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4a3rRXu4Ur/Render', 15:49:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:56 INFO - 'HOME': '/Users/cltbld', 15:49:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:56 INFO - 'LOGNAME': 'cltbld', 15:49:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:49:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:56 INFO - 'MOZ_NO_REMOTE': '1', 15:49:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:56 INFO - 'NO_EM_RESTART': '1', 15:49:56 INFO - 'PAGER': '/bin/cat', 15:49:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:56 INFO - 'PWD': '/builds/slave/test', 15:49:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:56 INFO - 'SHELL': '/bin/bash', 15:49:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c1RDGKFltB/Listeners', 15:49:56 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 15:49:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:56 INFO - 'USER': 'cltbld', 15:49:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:56 INFO - 'XPC_FLAGS': '0x0', 15:49:56 INFO - 'XPC_SERVICE_NAME': '0', 15:49:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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:49:56 INFO - mozversion application_buildid: 20170124133147 15:49:56 INFO - mozversion application_changeset: 68b72e1b219543ee47e99ba38e356ed7b7c66349 15:49:56 INFO - mozversion application_display_name: Nightly 15:49:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:49:56 INFO - mozversion application_name: Firefox 15:49:56 INFO - mozversion application_remotingname: firefox 15:49:56 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 15:49:56 INFO - mozversion application_vendor: Mozilla 15:49:56 INFO - mozversion application_version: 54.0a1 15:49:56 INFO - mozversion platform_buildid: 20170124133147 15:49:56 INFO - mozversion platform_changeset: 68b72e1b219543ee47e99ba38e356ed7b7c66349 15:49:56 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 15:49:56 INFO - mozversion platform_version: 54.0a1 15:49:56 INFO - using testdate: 1485301796 15:49:56 INFO - actual date: 1485301796 15:49:56 INFO - starting webserver on 'localhost:49249' 15:49:56 INFO - SUITE-START | Running 2 tests 15:49:56 INFO - TEST-START | dromaeo_css 15:49:56 INFO - operating with platform_type : mac_ 15:49:56 INFO - Initialising browser for dromaeo_css test... 15:49:56 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpTJX_uE/profile http://localhost:49249/getInfo.html) 15:49:58 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 15:49:58 INFO - PROCESS | 1638 | colorDepth:24 15:49:58 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 15:49:58 INFO - PROCESS | 1638 | __metrics 15:49:58 INFO - PROCESS | 1638 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 15:49:58 INFO - PROCESS | 1638 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:49:58 INFO - PROCESS | 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:49:59 INFO - TEST-INFO | 1638: exit 0 15:49:59 INFO - Browser initialized. 15:49:59 INFO - Running cycle 1/1 for dromaeo_css test... 15:49:59 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpTJX_uE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:50:11 INFO - PROCESS | 1640 | RSS: Main: 227356672 15:50:11 INFO - PROCESS | 1640 | 15:53:15 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 15:53:17 INFO - PROCESS | 1640 | RSS: Main: 354652160 15:53:17 INFO - PROCESS | 1640 | 15:55:01 INFO - PROCESS | 1640 | ************************* 15:55:01 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 15:55:01 INFO - PROCESS | 1640 | 15:55:01 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:55:01 INFO - PROCESS | 1640 | Full stack: 15:55:01 INFO - PROCESS | 1640 | ************************* 15:56:20 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 15:56:20 INFO - PROCESS | 1640 | RSS: Main: 370688000 15:56:20 INFO - PROCESS | 1640 | 15:59:23 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 15:59:23 INFO - PROCESS | 1640 | RSS: Main: 335519744 15:59:23 INFO - PROCESS | 1640 | 16:02:26 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 16:02:26 INFO - PROCESS | 1640 | RSS: Main: 326639616 16:02:26 INFO - PROCESS | 1640 | 16:05:30 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 16:05:30 INFO - PROCESS | 1640 | RSS: Main: 325947392 16:05:30 INFO - PROCESS | 1640 | 16:08:34 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 16:08:34 INFO - PROCESS | 1640 | RSS: Main: 387084288 16:08:34 INFO - PROCESS | 1640 | 16:08:34 INFO - PROCESS | 1640 | __start_tp_report 16:08:34 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 16:08:34 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 16:08:34 INFO - PROCESS | 1640 | |i|pagename|runs| 16:08:34 INFO - PROCESS | 1640 | |0;dojo.html;3277.8197199154047;3344.2475443025323;3361.025302662227;3367.3097015111903;3408.1479630092476;5574.86062848429;5616.241807356006;5623.971880140599;5644.266245388094;5672.035753921828;4186.958130418695;5465.699386533739;5727.57043221759;5736.598438109334;5801.506871915893;5127.282180494733;5147.9704059188125;5172.250023746555;5180.2229665550285;5212.765425555859;5189.351331083614;5269.367675878881;5270.156774916014;5276.525112739852;5286.3392075990505;5129.025485157808;5239.187925871496;5258.974499972507;5291.973540132294;5292.206169074631;5123.2827404163445;5153.304303918961;5206.97396513015;5212.166053431432;5218.034663587248;5193.584513238932;5211.400688920785;5221.269022336875;5248.921266181011;5249.4488078751565;5691.9146212806845;5712.257406537124;5717.199592057115;5720.2849643794525;5728.083506638918;6102.993006153979;6109.890546867989;6225.159603453521;6228.781992630236;6237.844053898689;4880.780364883553;6238.781642642532;6241.594296370721;6248.31268560458;6249.812505624838;5747.942520574825;5761.769529218785;5761.85595360111;5790.652560846363;5807;6308.779192728324;6320.336364681734;6409.839754006094;6414.583052101599;6418.422341989243;5941.732622032019;6042.879142417215;6061.212042434543;6119.051547010221;6137.631742095488;6128.300381460574;6136.151476879633;6144.2488890665945;6155.692215389213;6207.193064901534;5035.77339019745;6158.353372895871;6159.23009623797;6166.445019948226;6184.288806787187;6286;6286.9056964145575;6294.402031806971;6304.873902521924;6305.306416294204;4012.959870401317;4968.975155124202;4989.052080104769;4989.90020199594;4991.101601711654;4335.978320108379;4379.4087798147475;4406.515283318832;4434.312681534368;4469.843555475542;5637.3235211774845;5670;5727.885442291131;5757.934782065331;5763.250777398911;5093.414257360451;6207.968960155261;6213.782517611862;6238.282597501346;6243.344448832807;5645.068563687028;5655.745491452894;5714.342850572238;5714.657120572779;5726.541876649858;5321.228421878806;5373.7581808818695;5376.704281264568;5407.891842163135;5412.323459567554;6365.777197798055;6372.426481616584;6374.553781235269;6376.968115159488;6390.968045159744;6179.474744646575;6181.629102253879;6261.311255761953;6267.874642507033;6269.62382257066;6335.524835637438;6342.492600592034;6342.936570634234;6347.619142851444;6355.555111142361;6200.348963358782;6224.844378890382;6225.408586184305;6227.532935029801;6230.127782110418;6104.186323682914;6141.447269745566;6192.287886892885;6285.088662144055;6292.2134733158355;6015.639061656315;6071.423214794588;6107.947487154961;6121.173641558333;6143.232095988002;5969.880602388015;6092.116643086816;6092.421219984094;6096.9390306096375;6113;5769.221155144001;5822.73797679097;5905.586608937333;5927.8221653350465;5939.346671866005;5933.169356290036;6061.363556826646;6072;6099.786507472395;6123.9283125451975;5657.462541058593;5871.324797648154;5880.617759845596;5880.617759845596;5883.705814709333 16:08:34 INFO - PROCESS | 1640 | |1;ext.html;498.02635846919276;506.8682142642914;517.5006119095075;521.5775222070126;526.134508733134;30137;39453;40565.59434405655;40645.59354406455;41995.79002104993;41930.79034604823;42366.36450453246;42438.78780606093;42504;42693.573064269425;8400.789980250482;9236.44581325121;9432.292578056673;9447.008064153268;9459.675645409648;8407.075221725605;11083.168762342817;11316.943415282953;11358.375289359083;11519.481623326928;11218.270812397168;11317.434128293593;11336.376499292577;11375.943120284386;11398.772024559505;8448.704295349633;8600.827983440358;11230.719232019224;11315;11348.262362946423;9544.761380965461;11017.614383496539;11317.207795454322;11354.716132096764;11378.487968041458;34047.10599599037;39298.214035719415;39418.21163576741;39766.40350394746;39946.60186893474;4047.6761859051207;4073.1853629274265;4084.305668036411;4113.382992551141;4206.600372964564;25375.873120634278;30343.93796217144;30997;31048.53427198594;31295.06114816559;3106.782525223235;3266.8366581671057;3297.934041319184;3341.532185494033;3345.799252044885;476709.61645191896;476794;477920.6103969458;478457;479772;63572.68213658902;63975.08386200146;63989;64077.35922640714;64112;66986.66506667435;67251.3274867245;67275.66362168158;67320;67368.98946515807;15583.831212659095;15766.448174313844;15778.526644200692;15809.209539522977;15824.892257541862;10442.843357349646;10681.985211404904;10690.305130166515;12445.813312800316;12505.3747312634;17081.658366832595;17470.68057069153;17475.38836140729;17572.560685982953;17791.644167116843;7243.637818109073;7257.310555497219;7281.490295679251;7342.77971660851;7397;735.5954225176132;739.8113481062295;852.7015544559355;866.2853146154448;898.3172788680649;25616.975320986952;28650.570241446396;30103.548446773315;30175.849120754257;30346.802197420995;1035.223582313265;1035.6219979707353;1065.1372388365448;1066.4720963123305;1107.894749998749;15175;15680.294386752623;15752.76370854438;15799.052056876624;16030.198490075449;607070;611016;611627;612310;614081;60501.69749151226;60683.69658151681;60714.696426517585;60859;60864.69567652133;1410.8871290296752;1420.7016526529544;1437.6118448018767;1444.0830072903782;1447.7198412500354;28795.99214027499;29559.965401210855;29641.110766677033;30264.243393915327;30325.848370758005;52203.694907627614;52590.68523286949;52632.73683631558;52793.7360313196;52805;509717;515952;521649;523627.1455928088;524765;40614.59385406108;41278.587214127474;41329.58670413258;41534.93906036042;41677.95805104897;37170.8141459291;46553;47418.05163896634;48105.518944811505;49852.50147498478;39187.82436526909;39244;39368.80315598404;40618.39072413857;40975;32302.030939071865;38082.428763568016;38862.639807606596;39397;40455;35316.64683353133;37954.43068353922;38431.61568384392;38618.61381386263;38658.80670596629;28182.577261340688;31958.52062219022;33843.323133537684;34665.82667086749;34720;22318.776812232318;24832.875835620707;24901.750982489943;24956.126535571893;25479.617805733298 16:08:34 INFO - PROCESS | 1640 | |2;jquery.html;383.0958936908893;391.11022423985446;393.0586245940971;397.4277041060872;415.03090284186425;14032.579022629312;20532.38402847915;21248.93755312236;21809.67285490719;21894.99999999998;16402.83597164028;17732.911335443303;20923.895380523078;21204.57590848186;21540.461488462755;5725.99999999999;5945.4946329562035;6075.362086980869;6081.148639190517;6085.452309292163;5862.32583252925;5906.143609176688;5929.555283353744;5958.314793798703;5990.460858522732;5742.1099729541775;5926.436988486087;5927.2294601701715;5934.851628709295;5940.940590594082;5712.8857422851515;5731.111677689964;5799.159121927297;5836.795712150076;5863.736131874076;4425.8229670813125;4486.977565112186;5075.137226671474;5148.073346797575;5157.381114266312;25028.49943001148;25517.979280828746;25594.36014099644;26015.349616259555;26439.074632387776;2514.748525147489;2702.581099929514;3080.245339891741;3114.065780265911;3122.35991621717;11223.831642525287;15535.844641553553;15588.688226235525;15622.218889055615;16309.021458712397;1628.544007677852;1837.9540511487187;1846.621442604263;1872.8595355348268;1887.726279689451;132221;133599.33200334033;134773.97839032422;135893.3205333977;137468.62531374657;9886.319325714645;10538.736531586772;10841.891581084246;11191.608293709682;11681;13964.301784910715;14322.498712545012;14352.138871667734;14373.712525749635;14388.992770506173;7376.262373762688;7712.228777122243;7732.57470839098;8133.2680058794995;8207.7948051298;8470.745877623682;8527.147285271423;8576.571171441528;8606.87089693655;8729.563521823884;6510.674466276667;6705.396514313735;7018.438524917994;7079.787606371817;7121.537100088478;1882.2565086790642;1905.9064228748587;1923.8837278993276;1928.0937959159173;1935.090507461491;1024.3904876598413;1051.6328772595748;1077.7844431113808;1082.978340433187;1118.6979515530925;21972;22351.888240558692;22383.768892711058;22390.44023899383;22481.77518224797;1078.3260462211117;1081.124289325633;1107.3023994883172;1109.872364678056;1116.5757012335423;11077.50151243196;11653.708657283467;11713.472893719803;11800.645980620595;11809.468573914195;387489;496940;678761;681412;691300;113550;114394.71210575908;133318;133530;140123;901.4861528928448;969.0551712080664;1008.2690049713899;1026.820306446369;1028.6039874648166;19460.221591136196;19510.60978780445;19536.925469099337;19541.413757587296;19589;55480.722596388376;56403.71798141147;56731.71634141803;56810.431895680515;56877.431225687214;78149;80684.19315806766;80777.59611201906;80921.59539202266;81231;7298.306660867209;7422.146453157956;7570.772876813704;8048.275655191061;8143.389245806707;9425.151736343762;12738.872611274139;13035.674108147372;13712.794308085586;13889.305534723426;9485.715428537154;11256.549738010388;11544.19190656646;11582.131340149353;11711.252399232164;9695.563699633674;10021.699349019542;10767.892321077003;10795.082417994558;10953.521274627823;11414.828777568177;11583.710407239887;11956.76086478283;11970.401479925795;12198.268103913439;7525.473216874985;7655.885161722467;7669.693212271447;7714;7886.960565197137;10242.487875606339;11362.659120226406;11366.374849383363;11750;11853.34806585646 16:08:34 INFO - PROCESS | 1640 | |3;mootools.html;1432.5559076686222;1454.1129910754444;1468.5374107156247;1480.8297045839731;1484.873785728213;7937.563434011135;8354.832903341938;8365.581720913946;8372.665093396257;8381.497110173394;7380.852382952337;7664.041994750656;7664.233576642347;7722.65248063837;7724.459287849852;2087.3320537428067;2225.9666105008437;2294.7934685878267;2308.3421224950807;2314.5833749925005;2328.1269523928527;2335.6730057791924;2337.91817286395;2341.4146463384154;2347.835651504395;2245.62947113726;2295.690081838947;2296.804771594408;2297.437127903666;2298.528801595669;2077.3248694174454;2189.79196976287;2218.7892150245702;2279.9202027929027;2298.7701579654804;2226.899789509476;2301.8618882866913;2322.883855807203;2326.3602509310076;2332.7900488956075;8201.753947381589;8288.50268983857;8298.875516867192;8313.251807337308;8313.709020184326;4997.85006449807;5080.085584594772;5172.689638621695;5180.792768289264;5181.792728290901;2937.9412411751646;3281.5569898063695;3479.443289073736;3556.0754203906913;3556.893293201208;1184.5853951117126;1219.8780121987818;1252.017166524274;1263.463028213015;1272.840894888139;10797.784044319149;10803.459827008619;10814.459277036116;10817.675469735841;10821.513031913504;4514.638828893746;4612.630989520831;4616.676832621684;4666.4633567139535;4678.8596342109795;9431.386959847589;9436.716898493056;9445.338826282094;9465.479398633004;9470.668526601536;5128.410232823198;5159.664621799571;5188.714620695822;5196.350456192976;5216.321878155815;4590.54094590545;4704.129735998851;4725;4785.760711964388;4836.613070954315;3362.7725880053604;3468.323676882984;3469.5663042119736;3481.408160612702;3484.1115515543374;1086.4459125845876;1151.067635215478;1155.4165146600913;1165.7493638867684;1166.276908316832;877.9824403511894;890.4078787606298;902.7652810269246;905.4471581238076;960.4669408478222;5276;5323.1749078892835;5613.129964855454;5732.512736417368;5962.940370596325;898.7528429681889;985.9499632890949;1004.8894621591619;1005.1406047829001;1039.6101461951769;3702.6667599916136;3767.5102236709054;3863.188730366598;3877.651011408987;3893.7469064510715;8452.366072544584;9711.3202075854;9861.408315501092;9962.402255864672;10050.497475126214;7108.466864985044;9805.754856128653;9994.900050999542;9996.700098997042;10050.59797608102;7518.736844210426;7820.17888121739;7839.843203135905;7842.686292548348;7852.214778522102;5541.916871246854;5570.693611851225;5634.718264086698;5691.7438715257085;5736.42635736423;7204.53170543913;8635.784105397164;9039.638414463348;9294.395864268801;9351.85972210431;9158.313126515404;9380.108889655472;9479;9497.905020949702;9506.429614223169;7113.217546070032;7133.678984445816;7229.674664640209;7308.269173082649;7330.96334518324;7822.217778222132;7897.447178697436;7909;7912.169222231582;7945.562994035384;6993.685284162123;7063.540869843444;7073.575585464889;7114.822129446598;7214.458915581247;6938.930610693828;6979.895301570379;6985.091938048021;7056.964715176391;7127.964360178166;6228.190335256388;6371.203599550056;6449.935500644933;6451.903221451776;6461.19235095613;4912.287718280758;4961.206207006861;4990.8253211137435;5005.099082165245;5077.111505486599;4940.901181976268;4967.7516124192925;5065.797368105234;5171.01750667387;5250.711210883438 16:08:34 INFO - PROCESS | 1640 | |4;prototype.html;337.9712724418424;349.5176656214424;363.5982239625214;367.5258915998362;370.3907072865139;4503.324501324803;4766.094442056007;4967.379077615298;5151.63938524303;5166.741662916858;4725.952740472594;5026.5727413169925;5151.793928242866;5169.3279873616475;5196.610254230938;3071.5392691096404;3376.544166537523;3959.2675355059405;3981.5023122109737;4076.673866090706;3724.3854763963914;3896.7009464415987;4053.918921621566;4070.3894415837567;4072.5927407259187;3609.2675515752453;4078.3270760324513;4082.7743285699307;4089.2230476209575;4118.546959834416;3854.8265328060306;3994.960050399488;4016.2971479990883;4107.958920410788;4119.835206591748;3602.495650608917;3623.4564815277654;3634.273145370936;3641.9453708194396;3655.7623754455876;4384.649228061748;4413.139437809628;4872.65891387603;4933.975330123362;5029.673071250394;3959.9010024749323;4211.43145675336;4431.903311192154;4686.718796872165;4714.764261786897;4651.418572678415;5387.622866399353;5431.701256430894;5447.809326673587;5461.781528738846;3189.681031896815;3505.614382417932;3541.5750109986707;3778.508793856781;3784.2696629213483;5083.61872859537;5359.598030147755;5576.525995290446;5729.598928075036;5734.512566431858;3208.5668434761474;3473.193939151627;3808.9238215236096;3819.7517161384976;3838.500994870649;3582.3014512169875;3841.3085644583684;3875.767453952772;3883.9611603884164;3896.8636097737012;4338.392625032498;4479.238529450001;4494.797734101973;4533.252013417815;4556.703814252063;5484.451554844483;5489.34127904654;5494.752736126884;5497.845452454949;5499.257600223998;1138.7779383020231;1157.4444266752007;1249.5626530714178;1256.604169686546;1270.6658009090418;786.1156199275815;825.7316372179066;843.5360551696542;846.7671390367575;849.3460035772421;4088.9386659200136;4642.839290177456;4747.430308362949;4777.324698169036;4843.692203105142;5094.668846525038;5183.792648294026;5198.376194856642;5305.495977882095;5319.521243088141;4096.651784598283;4358.738475691469;4632.953670463319;4648.558386953234;4758.191107511662;2459.249928771742;2468.321211666806;2526.3557792762726;2775.9167224983285;2809.4662014217233;4743.976280118577;5386.922615476842;5509.393966663663;5512.724363781875;5546;4344.326629372453;4467.932981005288;4752.52474752522;4948.925766113512;4994.9750251248515;4349.738575813042;5033.640916952476;5078.898422031539;5462;5529.364123125812;4366.628836548929;4424.336349547593;4612.515685852937;4612.86161415158;5046.5179026471615;4587.243104887656;4739.597134243628;4860.878478038077;4874.731889746098;5247.842564723064;4595.517470665532;4756.667033307635;5400.4059553448305;5486.725663716878;5650.29371328584;3370.208001119784;3679.9448008280433;3768.736188466781;3778.508793856781;3786.67813235867;5583.497485226349;5587.44125587438;5599.524040456607;5610.971945140248;5643.065605884999;4626.491085980472;4778.876963913547;5198.217639771344;5392.703401312965;5438.510534051954;5372.516473517402;5378.342726945558;5401.704275682898;5419.295491586068;5426.728663566885;4652.697574657636;4982.106799416199;5296.735163241746;5300.734963251745;5333.200019997032;4573.702709323883;4890.388701412324;5059.316992206005;5454.727263636882;5485.094959331665;5333.893322133613;5340.647372391489;5391.59563032782;5399.70301633414;5405.729713514387 16:08:34 INFO - PROCESS | 1640 | |5;yui.html;713.8358177619144;737.3032484302337;768.6694721269852;811.513092144713;813.2721214513012;17054.23255953482;17692.46922592321;17758.023308718017;17771.82228177718;17824.197911094;16644.75032874505;16793.99236045835;16814.915925420388;16818.07500587467;16847.578810529743;852.86780549015;856.3149480415674;876.8114855306108;879.4107947675074;880.3793325705394;873.8121303919656;881.9294456443469;885.0308911741665;885.8139790643972;887.9067697891725;931.6878845586702;933.4819175357674;934.2712684106374;934.2946075712828;935.4714586258776;929.5027160469117;929.6746138851415;930.3394589841255;931.0968360690119;932.9346945713803;616.4113271825396;692.2281655953626;693.1543516909413;693.5006795107514;694.2328726756962;15576;15629.45296914614;15761.29074191664;15807.920960395239;16150.596235094099;14052.92973535136;14115.838540117089;14201.136566144516;14214.573562793134;14245.928770356082;9754.46350450725;11898.58354957581;12117.87882121185;12245.02039836811;12431.316277604728;3649.5255616769914;3735.34631439497;3741.064733816546;3742.7754334740002;3817.3701339278987;99685;104951.4752426233;106771;107278.31804204879;107785;10783.514741836636;10785.730356740987;10855.728606784736;10883.129349652008;11297.209195356247;22546.77453225447;22562.887185563966;22581.548369032855;22616.886915565316;22643.88678056599;7478.214787447239;7556.924430755622;7583;7643.4267429942975;7728.497647652885;4245.172191422643;4346.043870348518;4391.560843915583;4394.714343567658;4492.618127459203;3546.882731939431;3575.8927232183078;3663.249033948034;3687.7418580699095;3693.5013773140818;580.5326711996883;648.144449326884;654.9738010479527;675.9662016899135;676.4554533600499;516.7158063065299;661.7551505942832;681.2676372899153;705.2806137739477;723.9601821899846;9914.851277230848;10149.695509134737;10224;10487.737806554744;10512.106470950055;664.5016237821634;685.2530741491798;699.402011280348;703.2650879830487;706.8338940349014;4105.34314509683;4706.270528068155;4941.925871111936;4958.652894297436;4964.503549645006;95636;99932;101331.49334253282;101542;102033.48983255036;81921;87767;89871;90783.09216907747;91945;56213.7189314059;70852.64573677098;71534;71599.6420017907;72496;8317.168283171635;9557.187639050759;9610.038996100613;9621.759456013655;9715.514224288898;46856;48161;48224.75887620539;48489.75755121202;49142;57394.13908791288;63541.682291588244;63617.681911592;65653;66585.66707166433;11230.21388502797;11570.19008669385;11665.066794656577;11735.295882246752;11929.522819087138;12639.557615483414;12747.426365813746;12901.419436125585;13103.082784205015;13268.402921868732;11929.463174157358;12076.45655945467;12115.454804533994;12337.198082124632;12608.74782504363;11934.28394296345;11963.581274655346;12227.757297997268;12353.32056736888;12603.93698031504;11727;12077.335746534025;12300.569480068154;12354.938225309175;12426.440810163745;10348.637797677045;10649.148068154684;10691.732706682395;10838.67483975482;10913.618023369145;9212.447253164564;9303.906960930304;9754.658586949423;9828.459434731158;9861.112499875046 16:08:34 INFO - PROCESS | 1640 | __end_tp_report 16:08:34 INFO - PROCESS | 1640 | __start_cc_report 16:08:34 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1527 16:08:34 INFO - PROCESS | 1640 | __end_cc_report 16:08:34 INFO - PROCESS | 1640 | __startTimestamp1485302914414__endTimestamp 16:08:34 INFO - PROCESS | 1640 | 16:08:34 INFO - PROCESS | 1640 | ------- Summary: start ------- 16:08:34 INFO - PROCESS | 1640 | Number of tests: 6 16:08:34 INFO - PROCESS | 1640 | 16:08:34 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5702.67 Median:5880.62 stddev:663.76 (11.3%) stddev-sans-first:637.96 16:08:34 INFO - PROCESS | 1640 | Values: 3277.8 3344.2 3361.0 3367.3 3408.1 5574.9 5616.2 5624.0 5644.3 5672.0 4187.0 5465.7 5727.6 5736.6 5801.5 5127.3 5148.0 5172.3 5180.2 5212.8 5189.4 5269.4 5270.2 5276.5 5286.3 5129.0 5239.2 5259.0 5292.0 5292.2 5123.3 5153.3 5207.0 5212.2 5218.0 5193.6 5211.4 5221.3 5248.9 5249.4 5691.9 5712.3 5717.2 5720.3 5728.1 6103.0 6109.9 6225.2 6228.8 6237.8 4880.8 6238.8 6241.6 6248.3 6249.8 5747.9 5761.8 5761.9 5790.7 5807.0 6308.8 6320.3 6409.8 6414.6 6418.4 5941.7 6042.9 6061.2 6119.1 6137.6 6128.3 6136.2 6144.2 6155.7 6207.2 5035.8 6158.4 6159.2 6166.4 6184.3 6286.0 6286.9 6294.4 6304.9 6305.3 4013.0 4969.0 4989.1 4989.9 4991.1 4336.0 4379.4 4406.5 4434.3 4469.8 5637.3 5670.0 5727.9 5757.9 5763.3 5093.4 6208.0 6213.8 6238.3 6243.3 5645.1 5655.7 5714.3 5714.7 5726.5 5321.2 5373.8 5376.7 5407.9 5412.3 6365.8 6372.4 6374.6 6377.0 6391.0 6179.5 6181.6 6261.3 6267.9 6269.6 6335.5 6342.5 6342.9 6347.6 6355.6 6200.3 6224.8 6225.4 6227.5 6230.1 6104.2 6141.4 6192.3 6285.1 6292.2 6015.6 6071.4 6107.9 6121.2 6143.2 5969.9 6092.1 6092.4 6096.9 6113.0 5769.2 5822.7 5905.6 5927.8 5939.3 5933.2 6061.4 6072.0 6099.8 6123.9 5657.5 5871.3 5880.6 5880.6 5883.7 16:08:34 INFO - PROCESS | 1640 | 16:08:34 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:68102.36 Median:39382.90 stddev:143635.19 (364.7%) stddev-sans-first:143948.44 16:08:34 INFO - PROCESS | 1640 | Values: 498.0 506.9 517.5 521.6 526.1 30137.0 39453.0 40565.6 40645.6 41995.8 41930.8 42366.4 42438.8 42504.0 42693.6 8400.8 9236.4 9432.3 9447.0 9459.7 8407.1 11083.2 11316.9 11358.4 11519.5 11218.3 11317.4 11336.4 11375.9 11398.8 8448.7 8600.8 11230.7 11315.0 11348.3 9544.8 11017.6 11317.2 11354.7 11378.5 34047.1 39298.2 39418.2 39766.4 39946.6 4047.7 4073.2 4084.3 4113.4 4206.6 25375.9 30343.9 30997.0 31048.5 31295.1 3106.8 3266.8 3297.9 3341.5 3345.8 476709.6 476794.0 477920.6 478457.0 479772.0 63572.7 63975.1 63989.0 64077.4 64112.0 66986.7 67251.3 67275.7 67320.0 67369.0 15583.8 15766.4 15778.5 15809.2 15824.9 10442.8 10682.0 10690.3 12445.8 12505.4 17081.7 17470.7 17475.4 17572.6 17791.6 7243.6 7257.3 7281.5 7342.8 7397.0 735.6 739.8 852.7 866.3 898.3 25617.0 28650.6 30103.5 30175.8 30346.8 1035.2 1035.6 1065.1 1066.5 1107.9 15175.0 15680.3 15752.8 15799.1 16030.2 607070.0 611016.0 611627.0 612310.0 614081.0 60501.7 60683.7 60714.7 60859.0 60864.7 1410.9 1420.7 1437.6 1444.1 1447.7 28796.0 29560.0 29641.1 30264.2 30325.8 52203.7 52590.7 52632.7 52793.7 52805.0 509717.0 515952.0 521649.0 523627.1 524765.0 40614.6 41278.6 41329.6 41534.9 41678.0 37170.8 46553.0 47418.1 48105.5 49852.5 39187.8 39244.0 39368.8 40618.4 40975.0 32302.0 38082.4 38862.6 39397.0 40455.0 35316.6 37954.4 38431.6 38618.6 38658.8 28182.6 31958.5 33843.3 34665.8 34720.0 22318.8 24832.9 24901.8 24956.1 25479.6 16:08:34 INFO - PROCESS | 1640 | 16:08:34 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:35526.81 Median:22367.83 stddev:100666.65 (450.1%) stddev-sans-first:100914.46 16:08:34 INFO - PROCESS | 1640 | Values: 383.1 391.1 393.1 397.4 415.0 14032.6 20532.4 21248.9 21809.7 21895.0 16402.8 17732.9 20923.9 21204.6 21540.5 5726.0 5945.5 6075.4 6081.1 6085.5 5862.3 5906.1 5929.6 5958.3 5990.5 5742.1 5926.4 5927.2 5934.9 5940.9 5712.9 5731.1 5799.2 5836.8 5863.7 4425.8 4487.0 5075.1 5148.1 5157.4 25028.5 25518.0 25594.4 26015.3 26439.1 2514.7 2702.6 3080.2 3114.1 3122.4 11223.8 15535.8 15588.7 15622.2 16309.0 1628.5 1838.0 1846.6 1872.9 1887.7 132221.0 133599.3 134774.0 135893.3 137468.6 9886.3 10538.7 10841.9 11191.6 11681.0 13964.3 14322.5 14352.1 14373.7 14389.0 7376.3 7712.2 7732.6 8133.3 8207.8 8470.7 8527.1 8576.6 8606.9 8729.6 6510.7 6705.4 7018.4 7079.8 7121.5 1882.3 1905.9 1923.9 1928.1 1935.1 1024.4 1051.6 1077.8 1083.0 1118.7 21972.0 22351.9 22383.8 22390.4 22481.8 1078.3 1081.1 1107.3 1109.9 1116.6 11077.5 11653.7 11713.5 11800.6 11809.5 387489.0 496940.0 678761.0 681412.0 691300.0 113550.0 114394.7 133318.0 133530.0 140123.0 901.5 969.1 1008.3 1026.8 1028.6 19460.2 19510.6 19536.9 19541.4 19589.0 55480.7 56403.7 56731.7 56810.4 56877.4 78149.0 80684.2 80777.6 80921.6 81231.0 7298.3 7422.1 7570.8 8048.3 8143.4 9425.2 12738.9 13035.7 13712.8 13889.3 9485.7 11256.5 11544.2 11582.1 11711.3 9695.6 10021.7 10767.9 10795.1 10953.5 11414.8 11583.7 11956.8 11970.4 12198.3 7525.5 7655.9 7669.7 7714.0 7887.0 10242.5 11362.7 11366.4 11750.0 11853.3 16:08:34 INFO - PROCESS | 1640 | 16:08:34 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5379.46 Median:5185.25 stddev:2911.29 (56.1%) stddev-sans-first:2904.35 16:08:34 INFO - PROCESS | 1640 | Values: 1432.6 1454.1 1468.5 1480.8 1484.9 7937.6 8354.8 8365.6 8372.7 8381.5 7380.9 7664.0 7664.2 7722.7 7724.5 2087.3 2226.0 2294.8 2308.3 2314.6 2328.1 2335.7 2337.9 2341.4 2347.8 2245.6 2295.7 2296.8 2297.4 2298.5 2077.3 2189.8 2218.8 2279.9 2298.8 2226.9 2301.9 2322.9 2326.4 2332.8 8201.8 8288.5 8298.9 8313.3 8313.7 4997.9 5080.1 5172.7 5180.8 5181.8 2937.9 3281.6 3479.4 3556.1 3556.9 1184.6 1219.9 1252.0 1263.5 1272.8 10797.8 10803.5 10814.5 10817.7 10821.5 4514.6 4612.6 4616.7 4666.5 4678.9 9431.4 9436.7 9445.3 9465.5 9470.7 5128.4 5159.7 5188.7 5196.4 5216.3 4590.5 4704.1 4725.0 4785.8 4836.6 3362.8 3468.3 3469.6 3481.4 3484.1 1086.4 1151.1 1155.4 1165.7 1166.3 878.0 890.4 902.8 905.4 960.5 5276.0 5323.2 5613.1 5732.5 5962.9 898.8 985.9 1004.9 1005.1 1039.6 3702.7 3767.5 3863.2 3877.7 3893.7 8452.4 9711.3 9861.4 9962.4 10050.5 7108.5 9805.8 9994.9 9996.7 10050.6 7518.7 7820.2 7839.8 7842.7 7852.2 5541.9 5570.7 5634.7 5691.7 5736.4 7204.5 8635.8 9039.6 9294.4 9351.9 9158.3 9380.1 9479.0 9497.9 9506.4 7113.2 7133.7 7229.7 7308.3 7331.0 7822.2 7897.4 7909.0 7912.2 7945.6 6993.7 7063.5 7073.6 7114.8 7214.5 6938.9 6979.9 6985.1 7057.0 7128.0 6228.2 6371.2 6449.9 6451.9 6461.2 4912.3 4961.2 4990.8 5005.1 5077.1 4940.9 4967.8 5065.8 5171.0 5250.7 16:08:34 INFO - PROCESS | 1640 | 16:08:34 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4295.31 Median:4700.74 stddev:1289.64 (27.4%) stddev-sans-first:1258.58 16:08:34 INFO - PROCESS | 1640 | Values: 338.0 349.5 363.6 367.5 370.4 4503.3 4766.1 4967.4 5151.6 5166.7 4726.0 5026.6 5151.8 5169.3 5196.6 3071.5 3376.5 3959.3 3981.5 4076.7 3724.4 3896.7 4053.9 4070.4 4072.6 3609.3 4078.3 4082.8 4089.2 4118.5 3854.8 3995.0 4016.3 4108.0 4119.8 3602.5 3623.5 3634.3 3641.9 3655.8 4384.6 4413.1 4872.7 4934.0 5029.7 3959.9 4211.4 4431.9 4686.7 4714.8 4651.4 5387.6 5431.7 5447.8 5461.8 3189.7 3505.6 3541.6 3778.5 3784.3 5083.6 5359.6 5576.5 5729.6 5734.5 3208.6 3473.2 3808.9 3819.8 3838.5 3582.3 3841.3 3875.8 3884.0 3896.9 4338.4 4479.2 4494.8 4533.3 4556.7 5484.5 5489.3 5494.8 5497.8 5499.3 1138.8 1157.4 1249.6 1256.6 1270.7 786.1 825.7 843.5 846.8 849.3 4088.9 4642.8 4747.4 4777.3 4843.7 5094.7 5183.8 5198.4 5305.5 5319.5 4096.7 4358.7 4633.0 4648.6 4758.2 2459.2 2468.3 2526.4 2775.9 2809.5 4744.0 5386.9 5509.4 5512.7 5546.0 4344.3 4467.9 4752.5 4948.9 4995.0 4349.7 5033.6 5078.9 5462.0 5529.4 4366.6 4424.3 4612.5 4612.9 5046.5 4587.2 4739.6 4860.9 4874.7 5247.8 4595.5 4756.7 5400.4 5486.7 5650.3 3370.2 3679.9 3768.7 3778.5 3786.7 5583.5 5587.4 5599.5 5611.0 5643.1 4626.5 4778.9 5198.2 5392.7 5438.5 5372.5 5378.3 5401.7 5419.3 5426.7 4652.7 4982.1 5296.7 5300.7 5333.2 4573.7 4890.4 5059.3 5454.7 5485.1 5333.9 5340.6 5391.6 5399.7 5405.7 16:08:34 INFO - PROCESS | 1640 | 16:08:34 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:19880.03 Median:25674.31 stddev:28413.37 (110.7%) stddev-sans-first:28456.63 16:08:34 INFO - PROCESS | 1640 | Values: 713.8 737.3 768.7 811.5 813.3 17054.2 17692.5 17758.0 17771.8 17824.2 16644.8 16794.0 16814.9 16818.1 16847.6 852.9 856.3 876.8 879.4 880.4 873.8 881.9 885.0 885.8 887.9 931.7 933.5 934.3 934.3 935.5 929.5 929.7 930.3 931.1 932.9 616.4 692.2 693.2 693.5 694.2 15576.0 15629.5 15761.3 15807.9 16150.6 14052.9 14115.8 14201.1 14214.6 14245.9 9754.5 11898.6 12117.9 12245.0 12431.3 3649.5 3735.3 3741.1 3742.8 3817.4 99685.0 104951.5 106771.0 107278.3 107785.0 10783.5 10785.7 10855.7 10883.1 11297.2 22546.8 22562.9 22581.5 22616.9 22643.9 7478.2 7556.9 7583.0 7643.4 7728.5 4245.2 4346.0 4391.6 4394.7 4492.6 3546.9 3575.9 3663.2 3687.7 3693.5 580.5 648.1 655.0 676.0 676.5 516.7 661.8 681.3 705.3 724.0 9914.9 10149.7 10224.0 10487.7 10512.1 664.5 685.3 699.4 703.3 706.8 4105.3 4706.3 4941.9 4958.7 4964.5 95636.0 99932.0 101331.5 101542.0 102033.5 81921.0 87767.0 89871.0 90783.1 91945.0 56213.7 70852.6 71534.0 71599.6 72496.0 8317.2 9557.2 9610.0 9621.8 9715.5 46856.0 48161.0 48224.8 48489.8 49142.0 57394.1 63541.7 63617.7 65653.0 66585.7 11230.2 11570.2 11665.1 11735.3 11929.5 12639.6 12747.4 12901.4 13103.1 13268.4 11929.5 12076.5 12115.5 12337.2 12608.7 11934.3 11963.6 12227.8 12353.3 12603.9 11727.0 12077.3 12300.6 12354.9 12426.4 10348.6 10649.1 10691.7 10838.7 10913.6 9212.4 9303.9 9754.7 9828.5 9861.1 16:08:34 INFO - PROCESS | 1640 | -------- Summary: end -------- 16:08:34 INFO - PROCESS | 1640 | 16:08:35 INFO - TEST-INFO | 1640: exit 0 16:08:35 INFO - TEST-OK | dromaeo_css | took 1118842ms 16:08:35 INFO - TEST-START | kraken 16:08:35 INFO - operating with platform_type : mac_ 16:08:35 INFO - Initialising browser for kraken test... 16:08:35 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpFh_fTt/profile http://localhost:49249/getInfo.html) 16:08:37 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 16:08:37 INFO - PROCESS | 1649 | colorDepth:24 16:08:37 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 16:08:37 INFO - PROCESS | 1649 | __metrics 16:08:37 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 16:08:37 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:08:37 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:08:38 INFO - TEST-INFO | 1649: exit 0 16:08:38 INFO - Browser initialized. 16:08:38 INFO - Running cycle 1/1 for kraken test... 16:08:38 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpFh_fTt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:08:50 INFO - PROCESS | 1650 | RSS: Main: 234938368 16:08:50 INFO - PROCESS | 1650 | 16:08:53 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 16:08:54 INFO - PROCESS | 1650 | RSS: Main: 287825920 16:08:54 INFO - PROCESS | 1650 | 16:08:57 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 16:08:57 INFO - PROCESS | 1650 | RSS: Main: 288423936 16:08:57 INFO - PROCESS | 1650 | 16:09:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 16:09:01 INFO - PROCESS | 1650 | RSS: Main: 381317120 16:09:01 INFO - PROCESS | 1650 | 16:09:03 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 16:09:04 INFO - PROCESS | 1650 | RSS: Main: 282554368 16:09:04 INFO - PROCESS | 1650 | 16:09:06 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:09:06 INFO - PROCESS | 1650 | RSS: Main: 286978048 16:09:06 INFO - PROCESS | 1650 | 16:09:09 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 16:09:10 INFO - PROCESS | 1650 | RSS: Main: 327196672 16:09:10 INFO - PROCESS | 1650 | 16:09:13 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 16:09:13 INFO - PROCESS | 1650 | RSS: Main: 330866688 16:09:13 INFO - PROCESS | 1650 | 16:09:16 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 16:09:17 INFO - PROCESS | 1650 | RSS: Main: 327680000 16:09:17 INFO - PROCESS | 1650 | 16:09:18 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:09:19 INFO - PROCESS | 1650 | RSS: Main: 302575616 16:09:19 INFO - PROCESS | 1650 | 16:09:21 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:09:22 INFO - PROCESS | 1650 | RSS: Main: 306622464 16:09:22 INFO - PROCESS | 1650 | 16:09:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:09:25 INFO - PROCESS | 1650 | RSS: Main: 308658176 16:09:25 INFO - PROCESS | 1650 | 16:09:27 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:09:28 INFO - PROCESS | 1650 | RSS: Main: 324083712 16:09:28 INFO - PROCESS | 1650 | 16:09:31 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:09:32 INFO - PROCESS | 1650 | RSS: Main: 322764800 16:09:32 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 16:09:34 INFO - PROCESS | 1650 | RSS: Main: 366968832 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | __start_tp_report 16:09:34 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 16:09:34 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 16:09:34 INFO - PROCESS | 1650 | |i|pagename|runs| 16:09:34 INFO - PROCESS | 1650 | |0;ai-astar;115;115;114;114;113;115;114;114;113;115 16:09:34 INFO - PROCESS | 1650 | |1;audio-beat-detection;141;110;114;170;140;140;111;110;129;110 16:09:34 INFO - PROCESS | 1650 | |2;audio-dft;185;183;184;186;193;186;258;239;182;183 16:09:34 INFO - PROCESS | 1650 | |3;audio-fft;80;58;59;86;59;61;58;60;64;66 16:09:34 INFO - PROCESS | 1650 | |4;audio-oscillator;87;71;86;70;69;68;82;67;69;82 16:09:34 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;89;86;89;87;90;87;89;87;90;86 16:09:34 INFO - PROCESS | 1650 | |6;imaging-darkroom;94;96;95;95;94;95;94;95;95;95 16:09:34 INFO - PROCESS | 1650 | |7;imaging-desaturate;87;84;86;86;85;87;85;85;84;85 16:09:34 INFO - PROCESS | 1650 | |8;json-parse-financial;58;53;53;53;56;55;55;55;54;54 16:09:34 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;66;71;77;77;73;69;72;74;78;104 16:09:34 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;86;88;87;92;95;106;107;107;87;89 16:09:34 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;137;139;140;142;153;140;143;144;246;186 16:09:34 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;173;175;174;174;171;175;175;171;172;173 16:09:34 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;67;67;67;69;68;67;67;67;67;67 16:09:34 INFO - PROCESS | 1650 | __end_tp_report 16:09:34 INFO - PROCESS | 1650 | __start_cc_report 16:09:34 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2093 16:09:34 INFO - PROCESS | 1650 | __end_cc_report 16:09:34 INFO - PROCESS | 1650 | __startTimestamp1485302974289__endTimestamp 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | ------- Summary: start ------- 16:09:34 INFO - PROCESS | 1650 | Number of tests: 14 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.50 stddev:0.79 (0.7%) stddev-sans-first:0.78 16:09:34 INFO - PROCESS | 1650 | Values: 115.0 115.0 114.0 114.0 113.0 115.0 114.0 114.0 113.0 115.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:134.50 stddev:20.21 (15.0%) stddev-sans-first:20.84 16:09:34 INFO - PROCESS | 1650 | Values: 141.0 110.0 114.0 170.0 140.0 140.0 111.0 110.0 129.0 110.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:197.90 Median:186.00 stddev:27.21 (14.6%) stddev-sans-first:28.46 16:09:34 INFO - PROCESS | 1650 | Values: 185.0 183.0 184.0 186.0 193.0 186.0 258.0 239.0 182.0 183.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:65.10 Median:62.50 stddev:9.88 (15.8%) stddev-sans-first:8.89 16:09:34 INFO - PROCESS | 1650 | Values: 80.0 58.0 59.0 86.0 59.0 61.0 58.0 60.0 64.0 66.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:76.50 stddev:8.09 (10.6%) stddev-sans-first:7.34 16:09:34 INFO - PROCESS | 1650 | Values: 87.0 71.0 86.0 70.0 69.0 68.0 82.0 67.0 69.0 82.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:89.00 stddev:1.56 (1.8%) stddev-sans-first:1.62 16:09:34 INFO - PROCESS | 1650 | Values: 89.0 86.0 89.0 87.0 90.0 87.0 89.0 87.0 90.0 86.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 16:09:34 INFO - PROCESS | 1650 | Values: 94.0 96.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:85.50 stddev:1.07 (1.3%) stddev-sans-first:0.97 16:09:34 INFO - PROCESS | 1650 | Values: 87.0 84.0 86.0 86.0 85.0 87.0 85.0 85.0 84.0 85.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.58 (2.9%) stddev-sans-first:1.09 16:09:34 INFO - PROCESS | 1650 | Values: 58.0 53.0 53.0 53.0 56.0 55.0 55.0 55.0 54.0 54.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:76.10 Median:73.50 stddev:10.50 (14.3%) stddev-sans-first:10.49 16:09:34 INFO - PROCESS | 1650 | Values: 66.0 71.0 77.0 77.0 73.0 69.0 72.0 74.0 78.0 104.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:94.40 Median:87.50 stddev:8.87 (10.1%) stddev-sans-first:8.87 16:09:34 INFO - PROCESS | 1650 | Values: 86.0 88.0 87.0 92.0 95.0 106.0 107.0 107.0 87.0 89.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:157.00 Median:143.50 stddev:34.43 (24.0%) stddev-sans-first:35.75 16:09:34 INFO - PROCESS | 1650 | Values: 137.0 139.0 140.0 142.0 153.0 140.0 143.0 144.0 246.0 186.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:174.00 stddev:1.57 (0.9%) stddev-sans-first:1.66 16:09:34 INFO - PROCESS | 1650 | Values: 173.0 175.0 174.0 174.0 171.0 175.0 175.0 171.0 172.0 173.0 16:09:34 INFO - PROCESS | 1650 | 16:09:34 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 16:09:34 INFO - PROCESS | 1650 | Values: 67.0 67.0 67.0 69.0 68.0 67.0 67.0 67.0 67.0 67.0 16:09:34 INFO - PROCESS | 1650 | -------- Summary: end -------- 16:09:34 INFO - PROCESS | 1650 | 16:09:35 INFO - TEST-INFO | 1650: exit 0 16:09:35 INFO - TEST-OK | kraken | took 60011ms 16:09:35 INFO - SUITE-END | took 1178s 16:09:35 INFO - Completed test suite (00:19:39) 16:09:35 INFO - javascript benchmark 16:09:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3277.8197199154047, 3344.2475443025323, 3361.025302662227, 3367.3097015111903, 3408.1479630092476, 5574.86062848429, 5616.241807356006, 5623.971880140599, 5644.266245388094, 5672.035753921828, 4186.958130418695, 5465.699386533739, 5727.57043221759, 5736.598438109334, 5801.506871915893, 5127.282180494733, 5147.9704059188125, 5172.250023746555, 5180.2229665550285, 5212.765425555859, 5189.351331083614, 5269.367675878881, 5270.156774916014, 5276.525112739852, 5286.3392075990505, 5129.025485157808, 5239.187925871496, 5258.974499972507, 5291.973540132294, 5292.206169074631, 5123.2827404163445, 5153.304303918961, 5206.97396513015, 5212.166053431432, 5218.034663587248, 5193.584513238932, 5211.400688920785, 5221.269022336875, 5248.921266181011, 5249.4488078751565, 5691.9146212806845, 5712.257406537124, 5717.199592057115, 5720.2849643794525, 5728.083506638918, 6102.993006153979, 6109.890546867989, 6225.159603453521, 6228.781992630236, 6237.844053898689, 4880.780364883553, 6238.781642642532, 6241.594296370721, 6248.31268560458, 6249.812505624838, 5747.942520574825, 5761.769529218785, 5761.85595360111, 5790.652560846363, 5807.0, 6308.779192728324, 6320.336364681734, 6409.839754006094, 6414.583052101599, 6418.422341989243, 5941.732622032019, 6042.879142417215, 6061.212042434543, 6119.051547010221, 6137.631742095488, 6128.300381460574, 6136.151476879633, 6144.2488890665945, 6155.692215389213, 6207.193064901534, 5035.77339019745, 6158.353372895871, 6159.23009623797, 6166.445019948226, 6184.288806787187, 6286.0, 6286.9056964145575, 6294.402031806971, 6304.873902521924, 6305.306416294204, 4012.959870401317, 4968.975155124202, 4989.052080104769, 4989.90020199594, 4991.101601711654, 4335.978320108379, 4379.4087798147475, 4406.515283318832, 4434.312681534368, 4469.843555475542, 5637.3235211774845, 5670.0, 5727.885442291131, 5757.934782065331, 5763.250777398911, 5093.414257360451, 6207.968960155261, 6213.782517611862, 6238.282597501346, 6243.344448832807, 5645.068563687028, 5655.745491452894, 5714.342850572238, 5714.657120572779, 5726.541876649858, 5321.228421878806, 5373.7581808818695, 5376.704281264568, 5407.891842163135, 5412.323459567554, 6365.777197798055, 6372.426481616584, 6374.553781235269, 6376.968115159488, 6390.968045159744, 6179.474744646575, 6181.629102253879, 6261.311255761953, 6267.874642507033, 6269.62382257066, 6335.524835637438, 6342.492600592034, 6342.936570634234, 6347.619142851444, 6355.555111142361, 6200.348963358782, 6224.844378890382, 6225.408586184305, 6227.532935029801, 6230.127782110418, 6104.186323682914, 6141.447269745566, 6192.287886892885, 6285.088662144055, 6292.2134733158355, 6015.639061656315, 6071.423214794588, 6107.947487154961, 6121.173641558333, 6143.232095988002, 5969.880602388015, 6092.116643086816, 6092.421219984094, 6096.9390306096375, 6113.0, 5769.221155144001, 5822.73797679097, 5905.586608937333, 5927.8221653350465, 5939.346671866005, 5933.169356290036, 6061.363556826646, 6072.0, 6099.786507472395, 6123.9283125451975, 5657.462541058593, 5871.324797648154, 5880.617759845596, 5880.617759845596, 5883.705814709333], "value": 5661.209583733869, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.02635846919276, 506.8682142642914, 517.5006119095075, 521.5775222070126, 526.134508733134, 30137.0, 39453.0, 40565.59434405655, 40645.59354406455, 41995.79002104993, 41930.79034604823, 42366.36450453246, 42438.78780606093, 42504.0, 42693.573064269425, 8400.789980250482, 9236.44581325121, 9432.292578056673, 9447.008064153268, 9459.675645409648, 8407.075221725605, 11083.168762342817, 11316.943415282953, 11358.375289359083, 11519.481623326928, 11218.270812397168, 11317.434128293593, 11336.376499292577, 11375.943120284386, 11398.772024559505, 8448.704295349633, 8600.827983440358, 11230.719232019224, 11315.0, 11348.262362946423, 9544.761380965461, 11017.614383496539, 11317.207795454322, 11354.716132096764, 11378.487968041458, 34047.10599599037, 39298.214035719415, 39418.21163576741, 39766.40350394746, 39946.60186893474, 4047.6761859051207, 4073.1853629274265, 4084.305668036411, 4113.382992551141, 4206.600372964564, 25375.873120634278, 30343.93796217144, 30997.0, 31048.53427198594, 31295.06114816559, 3106.782525223235, 3266.8366581671057, 3297.934041319184, 3341.532185494033, 3345.799252044885, 476709.61645191896, 476794.0, 477920.6103969458, 478457.0, 479772.0, 63572.68213658902, 63975.08386200146, 63989.0, 64077.35922640714, 64112.0, 66986.66506667435, 67251.3274867245, 67275.66362168158, 67320.0, 67368.98946515807, 15583.831212659095, 15766.448174313844, 15778.526644200692, 15809.209539522977, 15824.892257541862, 10442.843357349646, 10681.985211404904, 10690.305130166515, 12445.813312800316, 12505.3747312634, 17081.658366832595, 17470.68057069153, 17475.38836140729, 17572.560685982953, 17791.644167116843, 7243.637818109073, 7257.310555497219, 7281.490295679251, 7342.77971660851, 7397.0, 735.5954225176132, 739.8113481062295, 852.7015544559355, 866.2853146154448, 898.3172788680649, 25616.975320986952, 28650.570241446396, 30103.548446773315, 30175.849120754257, 30346.802197420995, 1035.223582313265, 1035.6219979707353, 1065.1372388365448, 1066.4720963123305, 1107.894749998749, 15175.0, 15680.294386752623, 15752.76370854438, 15799.052056876624, 16030.198490075449, 607070.0, 611016.0, 611627.0, 612310.0, 614081.0, 60501.69749151226, 60683.69658151681, 60714.696426517585, 60859.0, 60864.69567652133, 1410.8871290296752, 1420.7016526529544, 1437.6118448018767, 1444.0830072903782, 1447.7198412500354, 28795.99214027499, 29559.965401210855, 29641.110766677033, 30264.243393915327, 30325.848370758005, 52203.694907627614, 52590.68523286949, 52632.73683631558, 52793.7360313196, 52805.0, 509717.0, 515952.0, 521649.0, 523627.1455928088, 524765.0, 40614.59385406108, 41278.587214127474, 41329.58670413258, 41534.93906036042, 41677.95805104897, 37170.8141459291, 46553.0, 47418.05163896634, 48105.518944811505, 49852.50147498478, 39187.82436526909, 39244.0, 39368.80315598404, 40618.39072413857, 40975.0, 32302.030939071865, 38082.428763568016, 38862.639807606596, 39397.0, 40455.0, 35316.64683353133, 37954.43068353922, 38431.61568384392, 38618.61381386263, 38658.80670596629, 28182.577261340688, 31958.52062219022, 33843.323133537684, 34665.82667086749, 34720.0, 22318.776812232318, 24832.875835620707, 24901.750982489943, 24956.126535571893, 25479.617805733298], "value": 20272.498581146087, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.0958936908893, 391.11022423985446, 393.0586245940971, 397.4277041060872, 415.03090284186425, 14032.579022629312, 20532.38402847915, 21248.93755312236, 21809.67285490719, 21894.99999999998, 16402.83597164028, 17732.911335443303, 20923.895380523078, 21204.57590848186, 21540.461488462755, 5725.99999999999, 5945.4946329562035, 6075.362086980869, 6081.148639190517, 6085.452309292163, 5862.32583252925, 5906.143609176688, 5929.555283353744, 5958.314793798703, 5990.460858522732, 5742.1099729541775, 5926.436988486087, 5927.2294601701715, 5934.851628709295, 5940.940590594082, 5712.8857422851515, 5731.111677689964, 5799.159121927297, 5836.795712150076, 5863.736131874076, 4425.8229670813125, 4486.977565112186, 5075.137226671474, 5148.073346797575, 5157.381114266312, 25028.49943001148, 25517.979280828746, 25594.36014099644, 26015.349616259555, 26439.074632387776, 2514.748525147489, 2702.581099929514, 3080.245339891741, 3114.065780265911, 3122.35991621717, 11223.831642525287, 15535.844641553553, 15588.688226235525, 15622.218889055615, 16309.021458712397, 1628.544007677852, 1837.9540511487187, 1846.621442604263, 1872.8595355348268, 1887.726279689451, 132221.0, 133599.33200334033, 134773.97839032422, 135893.3205333977, 137468.62531374657, 9886.319325714645, 10538.736531586772, 10841.891581084246, 11191.608293709682, 11681.0, 13964.301784910715, 14322.498712545012, 14352.138871667734, 14373.712525749635, 14388.992770506173, 7376.262373762688, 7712.228777122243, 7732.57470839098, 8133.2680058794995, 8207.7948051298, 8470.745877623682, 8527.147285271423, 8576.571171441528, 8606.87089693655, 8729.563521823884, 6510.674466276667, 6705.396514313735, 7018.438524917994, 7079.787606371817, 7121.537100088478, 1882.2565086790642, 1905.9064228748587, 1923.8837278993276, 1928.0937959159173, 1935.090507461491, 1024.3904876598413, 1051.6328772595748, 1077.7844431113808, 1082.978340433187, 1118.6979515530925, 21972.0, 22351.888240558692, 22383.768892711058, 22390.44023899383, 22481.77518224797, 1078.3260462211117, 1081.124289325633, 1107.3023994883172, 1109.872364678056, 1116.5757012335423, 11077.50151243196, 11653.708657283467, 11713.472893719803, 11800.645980620595, 11809.468573914195, 387489.0, 496940.0, 678761.0, 681412.0, 691300.0, 113550.0, 114394.71210575908, 133318.0, 133530.0, 140123.0, 901.4861528928448, 969.0551712080664, 1008.2690049713899, 1026.820306446369, 1028.6039874648166, 19460.221591136196, 19510.60978780445, 19536.925469099337, 19541.413757587296, 19589.0, 55480.722596388376, 56403.71798141147, 56731.71634141803, 56810.431895680515, 56877.431225687214, 78149.0, 80684.19315806766, 80777.59611201906, 80921.59539202266, 81231.0, 7298.306660867209, 7422.146453157956, 7570.772876813704, 8048.275655191061, 8143.389245806707, 9425.151736343762, 12738.872611274139, 13035.674108147372, 13712.794308085586, 13889.305534723426, 9485.715428537154, 11256.549738010388, 11544.19190656646, 11582.131340149353, 11711.252399232164, 9695.563699633674, 10021.699349019542, 10767.892321077003, 10795.082417994558, 10953.521274627823, 11414.828777568177, 11583.710407239887, 11956.76086478283, 11970.401479925795, 12198.268103913439, 7525.473216874985, 7655.885161722467, 7669.693212271447, 7714.0, 7886.960565197137, 10242.487875606339, 11362.659120226406, 11366.374849383363, 11750.0, 11853.34806585646], "value": 9931.0913553773, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.5559076686222, 1454.1129910754444, 1468.5374107156247, 1480.8297045839731, 1484.873785728213, 7937.563434011135, 8354.832903341938, 8365.581720913946, 8372.665093396257, 8381.497110173394, 7380.852382952337, 7664.041994750656, 7664.233576642347, 7722.65248063837, 7724.459287849852, 2087.3320537428067, 2225.9666105008437, 2294.7934685878267, 2308.3421224950807, 2314.5833749925005, 2328.1269523928527, 2335.6730057791924, 2337.91817286395, 2341.4146463384154, 2347.835651504395, 2245.62947113726, 2295.690081838947, 2296.804771594408, 2297.437127903666, 2298.528801595669, 2077.3248694174454, 2189.79196976287, 2218.7892150245702, 2279.9202027929027, 2298.7701579654804, 2226.899789509476, 2301.8618882866913, 2322.883855807203, 2326.3602509310076, 2332.7900488956075, 8201.753947381589, 8288.50268983857, 8298.875516867192, 8313.251807337308, 8313.709020184326, 4997.85006449807, 5080.085584594772, 5172.689638621695, 5180.792768289264, 5181.792728290901, 2937.9412411751646, 3281.5569898063695, 3479.443289073736, 3556.0754203906913, 3556.893293201208, 1184.5853951117126, 1219.8780121987818, 1252.017166524274, 1263.463028213015, 1272.840894888139, 10797.784044319149, 10803.459827008619, 10814.459277036116, 10817.675469735841, 10821.513031913504, 4514.638828893746, 4612.630989520831, 4616.676832621684, 4666.4633567139535, 4678.8596342109795, 9431.386959847589, 9436.716898493056, 9445.338826282094, 9465.479398633004, 9470.668526601536, 5128.410232823198, 5159.664621799571, 5188.714620695822, 5196.350456192976, 5216.321878155815, 4590.54094590545, 4704.129735998851, 4725.0, 4785.760711964388, 4836.613070954315, 3362.7725880053604, 3468.323676882984, 3469.5663042119736, 3481.408160612702, 3484.1115515543374, 1086.4459125845876, 1151.067635215478, 1155.4165146600913, 1165.7493638867684, 1166.276908316832, 877.9824403511894, 890.4078787606298, 902.7652810269246, 905.4471581238076, 960.4669408478222, 5276.0, 5323.1749078892835, 5613.129964855454, 5732.512736417368, 5962.940370596325, 898.7528429681889, 985.9499632890949, 1004.8894621591619, 1005.1406047829001, 1039.6101461951769, 3702.6667599916136, 3767.5102236709054, 3863.188730366598, 3877.651011408987, 3893.7469064510715, 8452.366072544584, 9711.3202075854, 9861.408315501092, 9962.402255864672, 10050.497475126214, 7108.466864985044, 9805.754856128653, 9994.900050999542, 9996.700098997042, 10050.59797608102, 7518.736844210426, 7820.17888121739, 7839.843203135905, 7842.686292548348, 7852.214778522102, 5541.916871246854, 5570.693611851225, 5634.718264086698, 5691.7438715257085, 5736.42635736423, 7204.53170543913, 8635.784105397164, 9039.638414463348, 9294.395864268801, 9351.85972210431, 9158.313126515404, 9380.108889655472, 9479.0, 9497.905020949702, 9506.429614223169, 7113.217546070032, 7133.678984445816, 7229.674664640209, 7308.269173082649, 7330.96334518324, 7822.217778222132, 7897.447178697436, 7909.0, 7912.169222231582, 7945.562994035384, 6993.685284162123, 7063.540869843444, 7073.575585464889, 7114.822129446598, 7214.458915581247, 6938.930610693828, 6979.895301570379, 6985.091938048021, 7056.964715176391, 7127.964360178166, 6228.190335256388, 6371.203599550056, 6449.935500644933, 6451.903221451776, 6461.19235095613, 4912.287718280758, 4961.206207006861, 4990.8253211137435, 5005.099082165245, 5077.111505486599, 4940.901181976268, 4967.7516124192925, 5065.797368105234, 5171.01750667387, 5250.711210883438], "value": 4386.089002445915, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.9712724418424, 349.5176656214424, 363.5982239625214, 367.5258915998362, 370.3907072865139, 4503.324501324803, 4766.094442056007, 4967.379077615298, 5151.63938524303, 5166.741662916858, 4725.952740472594, 5026.5727413169925, 5151.793928242866, 5169.3279873616475, 5196.610254230938, 3071.5392691096404, 3376.544166537523, 3959.2675355059405, 3981.5023122109737, 4076.673866090706, 3724.3854763963914, 3896.7009464415987, 4053.918921621566, 4070.3894415837567, 4072.5927407259187, 3609.2675515752453, 4078.3270760324513, 4082.7743285699307, 4089.2230476209575, 4118.546959834416, 3854.8265328060306, 3994.960050399488, 4016.2971479990883, 4107.958920410788, 4119.835206591748, 3602.495650608917, 3623.4564815277654, 3634.273145370936, 3641.9453708194396, 3655.7623754455876, 4384.649228061748, 4413.139437809628, 4872.65891387603, 4933.975330123362, 5029.673071250394, 3959.9010024749323, 4211.43145675336, 4431.903311192154, 4686.718796872165, 4714.764261786897, 4651.418572678415, 5387.622866399353, 5431.701256430894, 5447.809326673587, 5461.781528738846, 3189.681031896815, 3505.614382417932, 3541.5750109986707, 3778.508793856781, 3784.2696629213483, 5083.61872859537, 5359.598030147755, 5576.525995290446, 5729.598928075036, 5734.512566431858, 3208.5668434761474, 3473.193939151627, 3808.9238215236096, 3819.7517161384976, 3838.500994870649, 3582.3014512169875, 3841.3085644583684, 3875.767453952772, 3883.9611603884164, 3896.8636097737012, 4338.392625032498, 4479.238529450001, 4494.797734101973, 4533.252013417815, 4556.703814252063, 5484.451554844483, 5489.34127904654, 5494.752736126884, 5497.845452454949, 5499.257600223998, 1138.7779383020231, 1157.4444266752007, 1249.5626530714178, 1256.604169686546, 1270.6658009090418, 786.1156199275815, 825.7316372179066, 843.5360551696542, 846.7671390367575, 849.3460035772421, 4088.9386659200136, 4642.839290177456, 4747.430308362949, 4777.324698169036, 4843.692203105142, 5094.668846525038, 5183.792648294026, 5198.376194856642, 5305.495977882095, 5319.521243088141, 4096.651784598283, 4358.738475691469, 4632.953670463319, 4648.558386953234, 4758.191107511662, 2459.249928771742, 2468.321211666806, 2526.3557792762726, 2775.9167224983285, 2809.4662014217233, 4743.976280118577, 5386.922615476842, 5509.393966663663, 5512.724363781875, 5546.0, 4344.326629372453, 4467.932981005288, 4752.52474752522, 4948.925766113512, 4994.9750251248515, 4349.738575813042, 5033.640916952476, 5078.898422031539, 5462.0, 5529.364123125812, 4366.628836548929, 4424.336349547593, 4612.515685852937, 4612.86161415158, 5046.5179026471615, 4587.243104887656, 4739.597134243628, 4860.878478038077, 4874.731889746098, 5247.842564723064, 4595.517470665532, 4756.667033307635, 5400.4059553448305, 5486.725663716878, 5650.29371328584, 3370.208001119784, 3679.9448008280433, 3768.736188466781, 3778.508793856781, 3786.67813235867, 5583.497485226349, 5587.44125587438, 5599.524040456607, 5610.971945140248, 5643.065605884999, 4626.491085980472, 4778.876963913547, 5198.217639771344, 5392.703401312965, 5438.510534051954, 5372.516473517402, 5378.342726945558, 5401.704275682898, 5419.295491586068, 5426.728663566885, 4652.697574657636, 4982.106799416199, 5296.735163241746, 5300.734963251745, 5333.200019997032, 4573.702709323883, 4890.388701412324, 5059.316992206005, 5454.727263636882, 5485.094959331665, 5333.893322133613, 5340.647372391489, 5391.59563032782, 5399.70301633414, 5405.729713514387], "value": 3898.2549245482874, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.8358177619144, 737.3032484302337, 768.6694721269852, 811.513092144713, 813.2721214513012, 17054.23255953482, 17692.46922592321, 17758.023308718017, 17771.82228177718, 17824.197911094, 16644.75032874505, 16793.99236045835, 16814.915925420388, 16818.07500587467, 16847.578810529743, 852.86780549015, 856.3149480415674, 876.8114855306108, 879.4107947675074, 880.3793325705394, 873.8121303919656, 881.9294456443469, 885.0308911741665, 885.8139790643972, 887.9067697891725, 931.6878845586702, 933.4819175357674, 934.2712684106374, 934.2946075712828, 935.4714586258776, 929.5027160469117, 929.6746138851415, 930.3394589841255, 931.0968360690119, 932.9346945713803, 616.4113271825396, 692.2281655953626, 693.1543516909413, 693.5006795107514, 694.2328726756962, 15576.0, 15629.45296914614, 15761.29074191664, 15807.920960395239, 16150.596235094099, 14052.92973535136, 14115.838540117089, 14201.136566144516, 14214.573562793134, 14245.928770356082, 9754.46350450725, 11898.58354957581, 12117.87882121185, 12245.02039836811, 12431.316277604728, 3649.5255616769914, 3735.34631439497, 3741.064733816546, 3742.7754334740002, 3817.3701339278987, 99685.0, 104951.4752426233, 106771.0, 107278.31804204879, 107785.0, 10783.514741836636, 10785.730356740987, 10855.728606784736, 10883.129349652008, 11297.209195356247, 22546.77453225447, 22562.887185563966, 22581.548369032855, 22616.886915565316, 22643.88678056599, 7478.214787447239, 7556.924430755622, 7583.0, 7643.4267429942975, 7728.497647652885, 4245.172191422643, 4346.043870348518, 4391.560843915583, 4394.714343567658, 4492.618127459203, 3546.882731939431, 3575.8927232183078, 3663.249033948034, 3687.7418580699095, 3693.5013773140818, 580.5326711996883, 648.144449326884, 654.9738010479527, 675.9662016899135, 676.4554533600499, 516.7158063065299, 661.7551505942832, 681.2676372899153, 705.2806137739477, 723.9601821899846, 9914.851277230848, 10149.695509134737, 10224.0, 10487.737806554744, 10512.106470950055, 664.5016237821634, 685.2530741491798, 699.402011280348, 703.2650879830487, 706.8338940349014, 4105.34314509683, 4706.270528068155, 4941.925871111936, 4958.652894297436, 4964.503549645006, 95636.0, 99932.0, 101331.49334253282, 101542.0, 102033.48983255036, 81921.0, 87767.0, 89871.0, 90783.09216907747, 91945.0, 56213.7189314059, 70852.64573677098, 71534.0, 71599.6420017907, 72496.0, 8317.168283171635, 9557.187639050759, 9610.038996100613, 9621.759456013655, 9715.514224288898, 46856.0, 48161.0, 48224.75887620539, 48489.75755121202, 49142.0, 57394.13908791288, 63541.682291588244, 63617.681911592, 65653.0, 66585.66707166433, 11230.21388502797, 11570.19008669385, 11665.066794656577, 11735.295882246752, 11929.522819087138, 12639.557615483414, 12747.426365813746, 12901.419436125585, 13103.082784205015, 13268.402921868732, 11929.463174157358, 12076.45655945467, 12115.454804533994, 12337.198082124632, 12608.74782504363, 11934.28394296345, 11963.581274655346, 12227.757297997268, 12353.32056736888, 12603.93698031504, 11727.0, 12077.335746534025, 12300.569480068154, 12354.938225309175, 12426.440810163745, 10348.637797677045, 10649.148068154684, 10691.732706682395, 10838.67483975482, 10913.618023369145, 9212.447253164564, 9303.906960930304, 9754.658586949423, 9828.459434731158, 9861.112499875046], "value": 7424.696622681763, "unit": "score"}], "name": "dromaeo_css", "value": 7245.724827562541, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 114.0, 113.0, 115.0, 114.0, 114.0, 113.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 114.0, 170.0, 140.0, 140.0, 111.0, 110.0, 129.0, 110.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 184.0, 186.0, 193.0, 186.0, 258.0, 239.0, 182.0, 183.0], "value": 197.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 59.0, 86.0, 59.0, 61.0, 58.0, 60.0, 64.0, 66.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 86.0, 70.0, 69.0, 68.0, 82.0, 67.0, 69.0, 82.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 87.0, 90.0, 87.0, 89.0, 87.0, 90.0, 86.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 86.0, 85.0, 87.0, 85.0, 85.0, 84.0, 85.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 53.0, 53.0, 53.0, 56.0, 55.0, 55.0, 55.0, 54.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 77.0, 77.0, 73.0, 69.0, 72.0, 74.0, 78.0, 104.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 87.0, 92.0, 95.0, 106.0, 107.0, 107.0, 87.0, 89.0], "value": 94.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 140.0, 142.0, 153.0, 140.0, 143.0, 144.0, 246.0, 186.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 175.0, 174.0, 174.0, 171.0, 175.0, 175.0, 171.0, 172.0, 173.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 69.0, 68.0, 67.0, 67.0, 67.0, 67.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1470.7, "alertThreshold": 2.0}]} 16:09:35 INFO - Return code: 0 16:09:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:09:35 ERROR - # TBPL SUCCESS # 16:09:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:09:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:09:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:09:35 INFO - 2017-01-24 16:09:35.584 screenresolution[1654:9753] starting screenresolution argv=screenresolution get 16:09:35 INFO - 2017-01-24 16:09:35.588 screenresolution[1654:9753] Display 0: 1600x1200x32@60 16:09:35 INFO - 2017-01-24 16:09:35.595 screenresolution[1655:9756] starting screenresolution argv=screenresolution list 16:09:35 INFO - Available Modes on Display 0 16:09:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:09:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:09:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:09:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:09:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:09:35 INFO - Intel Iris: 16:09:35 INFO - Chipset Model: Intel Iris 16:09:35 INFO - Type: GPU 16:09:35 INFO - Bus: Built-In 16:09:35 INFO - VRAM (Dynamic, Max): 1536 MB 16:09:35 INFO - Vendor: Intel (0x8086) 16:09:35 INFO - Device ID: 0x0a2e 16:09:35 INFO - Revision ID: 0x0009 16:09:35 INFO - Displays: 16:09:35 INFO - Display: 16:09:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:09:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:09:35 INFO - Display Serial Number: 1600x1200 60 16:09:35 INFO - Main Display: Yes 16:09:35 INFO - Mirror: Off 16:09:35 INFO - Online: Yes 16:09:35 INFO - Rotation: Supported 16:09:35 INFO - Return code: 0 16:09:35 INFO - Running post-action listener: _resource_record_post_action 16:09:35 INFO - [mozharness: 2017-01-25 00:09:35.835054Z] Finished run-tests step (success) 16:09:35 INFO - Running post-run listener: _resource_record_post_run 16:09:35 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 37782528; Write bytes: 553111040; Read time: 239; Write time: 1675 16:09:35 INFO - TinderboxPrint: CPU usage
53.7% 16:09:35 INFO - TinderboxPrint: I/O read bytes / time
37,782,528 / 239 16:09:35 INFO - TinderboxPrint: I/O write bytes / time
553,111,040 / 1,675 16:09:35 INFO - TinderboxPrint: CPU idle
2,225.7 (46.3%) 16:09:35 INFO - TinderboxPrint: CPU system
960.3 (20.0%) 16:09:35 INFO - TinderboxPrint: CPU user
1,623.7 (33.8%) 16:09:35 INFO - TinderboxPrint: Swap in / out
288,923,648 / 0 16:09:35 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 241484288; Write bytes: 233799680; Read time: 17527; Write time: 498 16:09:35 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35881984; Write bytes: 299752960; Read time: 223; Write time: 1125 16:09:36 INFO - Running post-run listener: _upload_blobber_files 16:09:36 INFO - Blob upload gear active. 16:09:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:09:36 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 16:09:36 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:09:36 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:09:36 INFO - (blobuploader) - INFO - Open directory for files ... 16:09:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:09:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:09:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:37 INFO - (blobuploader) - INFO - Done attempting. 16:09:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:09:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:09:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:38 INFO - (blobuploader) - INFO - Done attempting. 16:09:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:09:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:09:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:39 INFO - (blobuploader) - INFO - Done attempting. 16:09:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:09:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:09:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:42 INFO - (blobuploader) - INFO - Done attempting. 16:09:42 INFO - (blobuploader) - INFO - Iteration through files over. 16:09:42 INFO - Return code: 0 16:09:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:09:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:09:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87f44cb6c82f838897861d94259afd5f7412e2e25b1b594fce62fb9bf7fa68b48e356f6afa9f1ce0a77476a385d866bfbe7b2f04b1a0b9633e503c5e249847f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e3b1d483b4557a548a6bedc79ed0617b023bc7c46ce9c0740d5589ac3546ffcfea02e320075cab79176a95f39b416e80492a2abc8ce4cdb4af5a46d29425bab0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b40464b10e1b33a9605cfe28e62ed1bb0928a12284fd87e0d12689c92d1b83a4d5266ba77a54ff4b4279f0e30e54599936f3039081959e110affb7418408f8ad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/95e35ce444b1d08239f9e66761d81eaf730545bafe02a0d4c774be09d77b4e62d4a6d55b01d5f4c345dc36ca8bb0b48a2d02a9e5a6972816818b4542619e9229"} 16:09:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:09:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:09:42 INFO - Contents: 16:09:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87f44cb6c82f838897861d94259afd5f7412e2e25b1b594fce62fb9bf7fa68b48e356f6afa9f1ce0a77476a385d866bfbe7b2f04b1a0b9633e503c5e249847f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e3b1d483b4557a548a6bedc79ed0617b023bc7c46ce9c0740d5589ac3546ffcfea02e320075cab79176a95f39b416e80492a2abc8ce4cdb4af5a46d29425bab0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b40464b10e1b33a9605cfe28e62ed1bb0928a12284fd87e0d12689c92d1b83a4d5266ba77a54ff4b4279f0e30e54599936f3039081959e110affb7418408f8ad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/95e35ce444b1d08239f9e66761d81eaf730545bafe02a0d4c774be09d77b4e62d4a6d55b01d5f4c345dc36ca8bb0b48a2d02a9e5a6972816818b4542619e9229"} 16:09:42 INFO - Running post-run listener: copy_logs_to_upload_dir 16:09:42 INFO - Copying logs to upload dir... 16:09:42 INFO - mkdir: /builds/slave/test/build/upload/logs 16:09:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.008046 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-24 16:09:42.358127) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-24 16:09:42.361776) ========= 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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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/Graphics/sha512/87f44cb6c82f838897861d94259afd5f7412e2e25b1b594fce62fb9bf7fa68b48e356f6afa9f1ce0a77476a385d866bfbe7b2f04b1a0b9633e503c5e249847f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e3b1d483b4557a548a6bedc79ed0617b023bc7c46ce9c0740d5589ac3546ffcfea02e320075cab79176a95f39b416e80492a2abc8ce4cdb4af5a46d29425bab0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b40464b10e1b33a9605cfe28e62ed1bb0928a12284fd87e0d12689c92d1b83a4d5266ba77a54ff4b4279f0e30e54599936f3039081959e110affb7418408f8ad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/95e35ce444b1d08239f9e66761d81eaf730545bafe02a0d4c774be09d77b4e62d4a6d55b01d5f4c345dc36ca8bb0b48a2d02a9e5a6972816818b4542619e9229"} build_url:https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011473 build_url: 'https://queue.taskcluster.net/v1/task/QRXMj1KMTOS6D3IwdPWRiQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87f44cb6c82f838897861d94259afd5f7412e2e25b1b594fce62fb9bf7fa68b48e356f6afa9f1ce0a77476a385d866bfbe7b2f04b1a0b9633e503c5e249847f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e3b1d483b4557a548a6bedc79ed0617b023bc7c46ce9c0740d5589ac3546ffcfea02e320075cab79176a95f39b416e80492a2abc8ce4cdb4af5a46d29425bab0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b40464b10e1b33a9605cfe28e62ed1bb0928a12284fd87e0d12689c92d1b83a4d5266ba77a54ff4b4279f0e30e54599936f3039081959e110affb7418408f8ad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/95e35ce444b1d08239f9e66761d81eaf730545bafe02a0d4c774be09d77b4e62d4a6d55b01d5f4c345dc36ca8bb0b48a2d02a9e5a6972816818b4542619e9229"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-24 16:09:42.400046) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 16:09:42.400754) ========= 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.4a3rRXu4Ur/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.c1RDGKFltB/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.005048 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 16:09:42.451746) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-24 16:09:42.452065) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-24 16:09:42.452424) ========= ========= Total master_lag: 0.29 =========