builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0207 starttime: 1478699453.15 results: success (0) buildid: 20161109044522 builduid: cd6c6be1202b418fa97516b9c30bde20 revision: 3466e6bc9874e5d7e53649d594ab70af9af2e358 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.151091) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.156883) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.157225) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.203553) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.203851) ========= 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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 --2016-11-09 05:50:53-- 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% 159M=0s 2016-11-09 05:50:53 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147290 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.373418) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.373738) ========= 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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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.019903 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:53.421837) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-09 05:50:53.422205) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 3466e6bc9874e5d7e53649d594ab70af9af2e358 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 3466e6bc9874e5d7e53649d594ab70af9af2e358 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UpzPBFzIsL/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.shnW5AXBhJ/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 2016-11-09 05:50:53,505 truncating revision to first 12 chars 2016-11-09 05:50:53,505 Setting DEBUG logging. 2016-11-09 05:50:53,506 attempt 1/10 2016-11-09 05:50:53,506 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/3466e6bc9874?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-09 05:50:54,505 unpacking tar archive at: larch-3466e6bc9874/testing/mozharness/ program finished with exit code 0 elapsedTime=1.275754 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-09 05:50:54.719756) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:54.720080) ========= script_repo_revision: 3466e6bc9874e5d7e53649d594ab70af9af2e358 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:54.720458) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:54.720724) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-09 05:50:54.736409) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-09 05:50:54.736807) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UpzPBFzIsL/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.shnW5AXBhJ/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:50:54 INFO - MultiFileLogger online at 20161109 05:50:54 in /builds/slave/test 05:50:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 05:50:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:50:54 INFO - {'append_to_log': False, 05:50:54 INFO - 'base_work_dir': '/builds/slave/test', 05:50:54 INFO - 'blob_upload_branch': 'Larch', 05:50:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:50:54 INFO - 'branch': 'Larch', 05:50:54 INFO - 'buildbot_json_path': 'buildprops.json', 05:50:54 INFO - 'config_files': ('talos/mac_config.py',), 05:50:54 INFO - 'default_actions': ('clobber', 05:50:54 INFO - 'read-buildbot-config', 05:50:54 INFO - 'download-and-extract', 05:50:54 INFO - 'populate-webroot', 05:50:54 INFO - 'create-virtualenv', 05:50:54 INFO - 'install', 05:50:54 INFO - 'run-tests'), 05:50:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:50:54 INFO - 'download_minidump_stackwalk': True, 05:50:54 INFO - 'download_symbols': 'ondemand', 05:50:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:50:54 INFO - 'tooltool.py': '/tools/tooltool.py', 05:50:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:50:54 INFO - '/tools/misc-python/virtualenv.py')}, 05:50:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:50:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:50:54 INFO - 'installer_path': 'installer.exe', 05:50:54 INFO - 'log_level': 'info', 05:50:54 INFO - 'log_name': 'talos', 05:50:54 INFO - 'log_to_console': True, 05:50:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:50:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:50:54 INFO - 'opt_config_files': (), 05:50:54 INFO - 'pip_index': False, 05:50:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:50:54 INFO - 'cmd': ('bash', 05:50:54 INFO - '-c', 05:50:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:50:54 INFO - 'enabled': True, 05:50:54 INFO - 'halt_on_failure': False, 05:50:54 INFO - 'name': 'check_screen_resolution'},), 05:50:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:50:54 INFO - 'cmd': ('bash', 05:50:54 INFO - '-c', 05:50:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:50:54 INFO - 'enabled': True, 05:50:54 INFO - 'halt_on_failure': False, 05:50:54 INFO - 'name': 'check_screen_resolution'},), 05:50:54 INFO - 'run_cmd_checks_enabled': True, 05:50:54 INFO - 'sps_profile': False, 05:50:54 INFO - 'sps_profile_interval': 0, 05:50:54 INFO - 'suite': 'dromaeojs', 05:50:54 INFO - 'system_bits': '32', 05:50:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:50:54 INFO - 'title': 't-yosemite-r7-0207', 05:50:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:50:54 INFO - 'use_talos_json': True, 05:50:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:50:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:50:54 INFO - 'work_dir': 'build'} 05:50:54 INFO - [mozharness: 2016-11-09 13:50:54.935835Z] Running clobber step. 05:50:54 INFO - Running pre-action listener: _resource_record_pre_action 05:50:54 INFO - Running main action method: clobber 05:50:54 INFO - rmtree: /builds/slave/test/build 05:50:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:50:56 INFO - Running post-action listener: _resource_record_post_action 05:50:56 INFO - [mozharness: 2016-11-09 13:50:56.513046Z] Finished clobber step (success) 05:50:56 INFO - [mozharness: 2016-11-09 13:50:56.513263Z] Running read-buildbot-config step. 05:50:56 INFO - Running pre-action listener: _resource_record_pre_action 05:50:56 INFO - Running main action method: read_buildbot_config 05:50:56 INFO - Using buildbot properties: 05:50:56 INFO - { 05:50:56 INFO - "project": "", 05:50:56 INFO - "product": "firefox", 05:50:56 INFO - "script_repo_revision": "production", 05:50:56 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 05:50:56 INFO - "repository": "", 05:50:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 05:50:56 INFO - "buildid": "20161109044522", 05:50:56 INFO - "pgo_build": "False", 05:50:56 INFO - "basedir": "/builds/slave/test", 05:50:56 INFO - "buildnumber": 0, 05:50:56 INFO - "slavename": "t-yosemite-r7-0207", 05:50:56 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:50:56 INFO - "platform": "yosemite_r7", 05:50:56 INFO - "scheduler": "tests-larch-macosx64-talos", 05:50:56 INFO - "branch": "larch", 05:50:56 INFO - "repo_path": "projects/larch", 05:50:56 INFO - "revision": "3466e6bc9874e5d7e53649d594ab70af9af2e358", 05:50:56 INFO - "stage_platform": "macosx64", 05:50:56 INFO - "builduid": "cd6c6be1202b418fa97516b9c30bde20", 05:50:56 INFO - "slavebuilddir": "test" 05:50:56 INFO - } 05:50:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:50:56 INFO - Running post-action listener: _resource_record_post_action 05:50:56 INFO - [mozharness: 2016-11-09 13:50:56.517373Z] Finished read-buildbot-config step (success) 05:50:56 INFO - [mozharness: 2016-11-09 13:50:56.517496Z] Running download-and-extract step. 05:50:56 INFO - Running pre-action listener: _resource_record_pre_action 05:50:56 INFO - Running main action method: download_and_extract 05:50:56 INFO - mkdir: /builds/slave/test/build/tests 05:50: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')]} 05:50:56 INFO - trying https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:50:56 INFO - Downloading https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:50:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:50:58 INFO - Downloaded 1199 bytes. 05:50:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:50:58 INFO - Using the following test package requirements: 05:50:58 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:50:58 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:50:58 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:50:58 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'jsshell-mac.zip'], 05:50:58 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:50:58 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:50:58 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:50:58 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:50:58 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:50:58 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:50:58 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:50:58 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:50:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:50:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:50:58 INFO - Fetch https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:50:58 INFO - Http code: 200 05:50:58 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 05:50:58 INFO - x-amz-cf-id: 0CKLfBAeJPPF7YvsEO-WTm6ZnKcSIjD_2bLimbvtLxdG0GAamL3TkA== 05:50:58 INFO - x-amz-version-id: hoO5Uzybr.3iMQqmdTD1G3qyxhDyUMVB 05:50:59 INFO - Expected file size: 16308014 05:50:59 INFO - Obtained file size: 16308014 05:51:06 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:51:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:51:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:51:06 INFO - Fetch https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:51:06 INFO - Http code: 200 05:51:06 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 05:51:06 INFO - x-amz-cf-id: 9_mjShj30V882xd0NNPtaUwwF6oWtB1v_j2R3Wo2opva6YIrDT4KMA== 05:51:06 INFO - x-amz-version-id: hoO5Uzybr.3iMQqmdTD1G3qyxhDyUMVB 05:51:07 INFO - Expected file size: 16308014 05:51:07 INFO - Obtained file size: 16308014 05:51:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:51:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:51:13 INFO - Fetch https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:51:14 INFO - Http code: 200 05:51:14 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 05:51:14 INFO - x-amz-cf-id: GZGOABVSWBZklO9_VI1PKDd0wFpp-nHIxeqEfuHDruh3y0H6cRLmHQ== 05:51:14 INFO - x-amz-version-id: AMfURl66fRsLFOojMNgc69GtLS5ml7Xn 05:51:15 INFO - Expected file size: 14409038 05:51:15 INFO - Obtained file size: 14409038 05:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:15 INFO - trying https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:51:15 INFO - Downloading https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:51:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:51:18 INFO - Downloaded 111576760 bytes. 05:51:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:51:18 INFO - mkdir: /builds/slave/test/properties 05:51:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:51:18 INFO - Writing to file /builds/slave/test/properties/build_url 05:51:18 INFO - Contents: 05:51:18 INFO - build_url:https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:51:19 INFO - Running post-action listener: _resource_record_post_action 05:51:19 INFO - Running post-action listener: set_extra_try_arguments 05:51:19 INFO - [mozharness: 2016-11-09 13:51:19.709367Z] Finished download-and-extract step (success) 05:51:19 INFO - [mozharness: 2016-11-09 13:51:19.709495Z] Running populate-webroot step. 05:51:19 INFO - Running pre-action listener: _resource_record_pre_action 05:51:19 INFO - Running main action method: populate_webroot 05:51:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:51:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:51:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:51:19 INFO - '--tppagecycles', 05:51:19 INFO - '7'], 05:51:19 INFO - 'tests': ['tsvgm']}}, 05:51:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:51:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:51:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:51:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:51:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:51:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:51:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['damp', 'tps']}, 05:51:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'tests': ['damp', 'tps']}, 05:51:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['dromaeo_dom']}, 05:51:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:51:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['basic_compositor_video']}, 05:51:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:51:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'other-e10s': {'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['a11yr', 05:51:19 INFO - 'ts_paint', 05:51:19 INFO - 'tpaint', 05:51:19 INFO - 'sessionrestore', 05:51:19 INFO - 'sessionrestore_no_auto_restore', 05:51:19 INFO - 'tabpaint']}, 05:51:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['tsvgx', 05:51:19 INFO - 'tsvgr_opacity', 05:51:19 INFO - 'tart', 05:51:19 INFO - 'tscrollx', 05:51:19 INFO - 'cart']}, 05:51:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:51:19 INFO - 'tsvgr_opacity', 05:51:19 INFO - 'tart', 05:51:19 INFO - 'tscrollx', 05:51:19 INFO - 'cart']}, 05:51:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'talos_options': ['--disable-e10s'], 05:51:19 INFO - 'tests': ['tp5o']}, 05:51:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'tests': ['tp5o']}, 05:51:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'talos_options': ['--disable-e10s', 05:51:19 INFO - '--xperf_path', 05:51:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:51:19 INFO - 'tests': ['tp5n']}, 05:51:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:51:19 INFO - 'talos_options': ['--xperf_path', 05:51:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:51:19 INFO - 'tests': ['tp5n']}}, 05:51:19 INFO - 'talos.zip': {'path': '', 05:51:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:51:19 INFO - Running post-action listener: _resource_record_post_action 05:51:19 INFO - [mozharness: 2016-11-09 13:51:19.728577Z] Finished populate-webroot step (success) 05:51:19 INFO - [mozharness: 2016-11-09 13:51:19.728713Z] Running create-virtualenv step. 05:51:19 INFO - Running pre-action listener: _resource_record_pre_action 05:51:19 INFO - Running main action method: create_virtualenv 05:51:19 INFO - Creating virtualenv /builds/slave/test/build/venv 05:51:19 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:51:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:51:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:51:19 INFO - Using real prefix '/tools/python27' 05:51:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:51:20 INFO - Installing distribute.............................................................................................................................................................................................done. 05:51:23 INFO - Installing pip.................done. 05:51:23 INFO - Return code: 0 05:51:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:51:23 INFO - Reading from file tmpfile_stdout 05:51:23 INFO - Output received: 05:51:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:51:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:51:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5594b92a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10755ad30>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107552b20>, '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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:51:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:51:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UpzPBFzIsL/Render', 05:51:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:23 INFO - 'HOME': '/Users/cltbld', 05:51:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:23 INFO - 'LOGNAME': 'cltbld', 05:51:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:23 INFO - 'MOZ_NO_REMOTE': '1', 05:51:23 INFO - 'NO_EM_RESTART': '1', 05:51:23 INFO - 'PAGER': '/bin/cat', 05:51:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:23 INFO - 'PWD': '/builds/slave/test', 05:51:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:23 INFO - 'SHELL': '/bin/bash', 05:51:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.shnW5AXBhJ/Listeners', 05:51:23 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:51:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:23 INFO - 'USER': 'cltbld', 05:51:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:23 INFO - 'XPC_FLAGS': '0x0', 05:51:23 INFO - 'XPC_SERVICE_NAME': '0', 05:51:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:51:24 INFO - Cleaning up... 05:51:24 INFO - Return code: 0 05:51:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:24 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 0x107746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5594b92a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10755ad30>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107552b20>, '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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:51:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:51:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:51:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UpzPBFzIsL/Render', 05:51:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:24 INFO - 'HOME': '/Users/cltbld', 05:51:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:24 INFO - 'LOGNAME': 'cltbld', 05:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:24 INFO - 'MOZ_NO_REMOTE': '1', 05:51:24 INFO - 'NO_EM_RESTART': '1', 05:51:24 INFO - 'PAGER': '/bin/cat', 05:51:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:24 INFO - 'PWD': '/builds/slave/test', 05:51:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:24 INFO - 'SHELL': '/bin/bash', 05:51:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.shnW5AXBhJ/Listeners', 05:51:24 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:51:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:24 INFO - 'USER': 'cltbld', 05:51:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:24 INFO - 'XPC_FLAGS': '0x0', 05:51:24 INFO - 'XPC_SERVICE_NAME': '0', 05:51:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:24 INFO - Downloading/unpacking psutil>=3.1.1 05:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:51:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:51:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:51:27 INFO - Installing collected packages: psutil 05:51:27 INFO - Running setup.py install for psutil 05:51:27 INFO - building 'psutil._psutil_osx' extension 05:51:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:51:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:51:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:51:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:51:28 INFO - building 'psutil._psutil_posix' extension 05:51:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:51:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:51:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:51:28 INFO - ^ 05:51:28 INFO - 1 warning generated. 05:51:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:51:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:51:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:51:28 INFO - Successfully installed psutil 05:51:28 INFO - Cleaning up... 05:51:28 INFO - Return code: 0 05:51:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:28 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 0x107746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5594b92a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10755ad30>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107552b20>, '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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:51:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:51:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:51:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UpzPBFzIsL/Render', 05:51:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:28 INFO - 'HOME': '/Users/cltbld', 05:51:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:28 INFO - 'LOGNAME': 'cltbld', 05:51:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:28 INFO - 'MOZ_NO_REMOTE': '1', 05:51:28 INFO - 'NO_EM_RESTART': '1', 05:51:28 INFO - 'PAGER': '/bin/cat', 05:51:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:28 INFO - 'PWD': '/builds/slave/test', 05:51:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:28 INFO - 'SHELL': '/bin/bash', 05:51:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.shnW5AXBhJ/Listeners', 05:51:28 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:51:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:28 INFO - 'USER': 'cltbld', 05:51:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:28 INFO - 'XPC_FLAGS': '0x0', 05:51:28 INFO - 'XPC_SERVICE_NAME': '0', 05:51:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:51: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 05:51: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 05:51: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 05:51: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 05:51: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 05:51: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 05:51:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:51:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:51:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:51:31 INFO - Installing collected packages: mozsystemmonitor 05:51:31 INFO - Running setup.py install for mozsystemmonitor 05:51:31 INFO - Successfully installed mozsystemmonitor 05:51:31 INFO - Cleaning up... 05:51:31 INFO - Return code: 0 05:51:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:31 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 0x107746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5594b92a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10755ad30>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107552b20>, '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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:51:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:51:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:51:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UpzPBFzIsL/Render', 05:51:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:31 INFO - 'HOME': '/Users/cltbld', 05:51:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:31 INFO - 'LOGNAME': 'cltbld', 05:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:31 INFO - 'MOZ_NO_REMOTE': '1', 05:51:31 INFO - 'NO_EM_RESTART': '1', 05:51:31 INFO - 'PAGER': '/bin/cat', 05:51:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:31 INFO - 'PWD': '/builds/slave/test', 05:51:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:31 INFO - 'SHELL': '/bin/bash', 05:51:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.shnW5AXBhJ/Listeners', 05:51:31 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:51:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:31 INFO - 'USER': 'cltbld', 05:51:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:31 INFO - 'XPC_FLAGS': '0x0', 05:51:31 INFO - 'XPC_SERVICE_NAME': '0', 05:51:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:32 INFO - Downloading/unpacking jsonschema==2.5.1 05:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:51:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:34 INFO - Downloading functools32-3.2.3-2.tar.gz 05:51:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:51:34 INFO - warning: no files found matching '*.txt' 05:51:34 INFO - no previously-included directories found matching 'build' 05:51:34 INFO - no previously-included directories found matching 'dist' 05:51:34 INFO - no previously-included directories found matching '.git*' 05:51:34 INFO - Installing collected packages: jsonschema, functools32 05:51:35 INFO - Running setup.py install for functools32 05:51:35 INFO - warning: no files found matching '*.txt' 05:51:35 INFO - no previously-included directories found matching 'build' 05:51:35 INFO - no previously-included directories found matching 'dist' 05:51:35 INFO - no previously-included directories found matching '.git*' 05:51:35 INFO - Successfully installed jsonschema functools32 05:51:35 INFO - Cleaning up... 05:51:35 INFO - Return code: 0 05:51:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:35 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 0x107746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5594b92a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10755ad30>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107552b20>, '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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:51:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:51:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:51:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UpzPBFzIsL/Render', 05:51:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:35 INFO - 'HOME': '/Users/cltbld', 05:51:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:35 INFO - 'LOGNAME': 'cltbld', 05:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:35 INFO - 'MOZ_NO_REMOTE': '1', 05:51:35 INFO - 'NO_EM_RESTART': '1', 05:51:35 INFO - 'PAGER': '/bin/cat', 05:51:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:35 INFO - 'PWD': '/builds/slave/test', 05:51:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:35 INFO - 'SHELL': '/bin/bash', 05:51:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.shnW5AXBhJ/Listeners', 05:51:35 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:51:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:35 INFO - 'USER': 'cltbld', 05:51:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:35 INFO - 'XPC_FLAGS': '0x0', 05:51:35 INFO - 'XPC_SERVICE_NAME': '0', 05:51:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:51:35 INFO - Cleaning up... 05:51:35 INFO - Return code: 0 05:51:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:35 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 0x107746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5594b92a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10755ad30>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107552b20>, '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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:51:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:51:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:51:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UpzPBFzIsL/Render', 05:51:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:35 INFO - 'HOME': '/Users/cltbld', 05:51:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:35 INFO - 'LOGNAME': 'cltbld', 05:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:35 INFO - 'MOZ_NO_REMOTE': '1', 05:51:35 INFO - 'NO_EM_RESTART': '1', 05:51:35 INFO - 'PAGER': '/bin/cat', 05:51:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:35 INFO - 'PWD': '/builds/slave/test', 05:51:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:35 INFO - 'SHELL': '/bin/bash', 05:51:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.shnW5AXBhJ/Listeners', 05:51:35 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:51:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:35 INFO - 'USER': 'cltbld', 05:51:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:35 INFO - 'XPC_FLAGS': '0x0', 05:51:35 INFO - 'XPC_SERVICE_NAME': '0', 05:51:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:35 INFO - Downloading/unpacking blobuploader==1.2.4 05:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:38 INFO - Downloading blobuploader-1.2.4.tar.gz 05:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:51:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:51:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:39 INFO - Downloading docopt-0.6.1.tar.gz 05:51:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:51:39 INFO - Installing collected packages: blobuploader, requests, docopt 05:51:39 INFO - Running setup.py install for blobuploader 05:51:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:51:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:51:39 INFO - Running setup.py install for requests 05:51:40 INFO - Running setup.py install for docopt 05:51:40 INFO - Successfully installed blobuploader requests docopt 05:51:40 INFO - Cleaning up... 05:51:40 INFO - Return code: 0 05:51:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:40 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 0x107746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5594b92a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10755ad30>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107552b20>, '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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:51:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:51:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UpzPBFzIsL/Render', 05:51:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:40 INFO - 'HOME': '/Users/cltbld', 05:51:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:40 INFO - 'LOGNAME': 'cltbld', 05:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:40 INFO - 'MOZ_NO_REMOTE': '1', 05:51:40 INFO - 'NO_EM_RESTART': '1', 05:51:40 INFO - 'PAGER': '/bin/cat', 05:51:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:40 INFO - 'PWD': '/builds/slave/test', 05:51:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:40 INFO - 'SHELL': '/bin/bash', 05:51:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.shnW5AXBhJ/Listeners', 05:51:40 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:51:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:40 INFO - 'USER': 'cltbld', 05:51:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:40 INFO - 'XPC_FLAGS': '0x0', 05:51:40 INFO - 'XPC_SERVICE_NAME': '0', 05:51:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:51:40 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-zEuhp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:51:40 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-XS95A_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:51:40 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-HBMq7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:51:41 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-OCRAMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:51:41 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-3g48pT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:51:41 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-IB4n2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:51:41 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-_wFWwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:51:41 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-2V5QpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:51:41 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-kxVGyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:51:41 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Ie0O8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:51:41 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-V0riAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:51:41 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-mHlnme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:51:42 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-q13dx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:51:42 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-p3HdPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:42 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-58XP7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:51:42 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-gBbb0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:51:42 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-MYAxZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:51:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:51:42 INFO - Running setup.py install for manifestparser 05:51:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:51:42 INFO - Running setup.py install for mozcrash 05:51:42 INFO - Running setup.py install for mozdebug 05:51:42 INFO - Running setup.py install for mozdevice 05:51:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Running setup.py install for mozfile 05:51:43 INFO - Running setup.py install for mozhttpd 05:51:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Running setup.py install for mozinfo 05:51:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Running setup.py install for mozInstall 05:51:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:51:43 INFO - Running setup.py install for mozleak 05:51:43 INFO - Running setup.py install for mozlog 05:51:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Running setup.py install for moznetwork 05:51:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Running setup.py install for mozprocess 05:51:44 INFO - Running setup.py install for mozprofile 05:51:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Running setup.py install for mozrunner 05:51:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:51:44 INFO - Running setup.py install for mozscreenshot 05:51:44 INFO - Running setup.py install for moztest 05:51:44 INFO - Running setup.py install for mozversion 05:51:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:51:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:51:45 INFO - Cleaning up... 05:51:45 INFO - Return code: 0 05:51:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:51:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:45 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 0x107746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5594b92a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10755ad30>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107552b20>, '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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:51:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:51:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:51:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UpzPBFzIsL/Render', 05:51:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:45 INFO - 'HOME': '/Users/cltbld', 05:51:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:45 INFO - 'LOGNAME': 'cltbld', 05:51:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:45 INFO - 'MOZ_NO_REMOTE': '1', 05:51:45 INFO - 'NO_EM_RESTART': '1', 05:51:45 INFO - 'PAGER': '/bin/cat', 05:51:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:45 INFO - 'PWD': '/builds/slave/test', 05:51:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:45 INFO - 'SHELL': '/bin/bash', 05:51:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.shnW5AXBhJ/Listeners', 05:51:45 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:51:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:45 INFO - 'USER': 'cltbld', 05:51:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:45 INFO - 'XPC_FLAGS': '0x0', 05:51:45 INFO - 'XPC_SERVICE_NAME': '0', 05:51:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:51:45 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-r7b80_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:51:45 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-A6_YmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:51:45 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-GsoX0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:51:45 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-gDusOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:51:45 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-NstWPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:51:45 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-6MEB31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:51:46 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-yXrVnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:51:46 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-EEljYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:51:46 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-piMmAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:51:46 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-v2ylnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:51:46 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-zi1yzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:51:46 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-JVM7zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:51:46 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-pFYSFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:51:46 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-6t2z3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:46 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-WTu23p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:51:47 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-kNCWJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:51:47 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-_WK4UM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:51:47 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:51:47 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:51:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:49 INFO - Downloading blessings-1.6.tar.gz 05:51:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:51:49 INFO - Installing collected packages: blessings 05:51:49 INFO - Running setup.py install for blessings 05:51:49 INFO - Successfully installed blessings 05:51:49 INFO - Cleaning up... 05:51:50 INFO - Return code: 0 05:51:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:51:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:51:50 INFO - Reading from file tmpfile_stdout 05:51:50 INFO - Current package versions: 05:51:50 INFO - blessings == 1.6 05:51:50 INFO - blobuploader == 1.2.4 05:51:50 INFO - docopt == 0.6.1 05:51:50 INFO - functools32 == 3.2.3-2 05:51:50 INFO - jsonschema == 2.5.1 05:51:50 INFO - manifestparser == 1.1 05:51:50 INFO - mozInstall == 1.12 05:51:50 INFO - mozcrash == 0.17 05:51:50 INFO - mozdebug == 0.1 05:51:50 INFO - mozdevice == 0.48 05:51:50 INFO - mozfile == 1.2 05:51:50 INFO - mozhttpd == 0.7 05:51:50 INFO - mozinfo == 0.9 05:51:50 INFO - mozleak == 0.1 05:51:50 INFO - mozlog == 3.4 05:51:50 INFO - moznetwork == 0.27 05:51:50 INFO - mozprocess == 0.23 05:51:50 INFO - mozprofile == 0.28 05:51:50 INFO - mozrunner == 6.12 05:51:50 INFO - mozscreenshot == 0.1 05:51:50 INFO - mozsystemmonitor == 0.3 05:51:50 INFO - moztest == 0.7 05:51:50 INFO - mozversion == 1.4 05:51:50 INFO - psutil == 3.1.1 05:51:50 INFO - requests == 1.2.3 05:51:50 INFO - wsgiref == 0.1.2 05:51:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:51 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 0x107746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5594b92a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10755ad30>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107552b20>, '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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:51:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UpzPBFzIsL/Render', 05:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:51 INFO - 'HOME': '/Users/cltbld', 05:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:51 INFO - 'LOGNAME': 'cltbld', 05:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:51 INFO - 'MOZ_NO_REMOTE': '1', 05:51:51 INFO - 'NO_EM_RESTART': '1', 05:51:51 INFO - 'PAGER': '/bin/cat', 05:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:51 INFO - 'PWD': '/builds/slave/test', 05:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:51 INFO - 'SHELL': '/bin/bash', 05:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.shnW5AXBhJ/Listeners', 05:51:51 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:51 INFO - 'USER': 'cltbld', 05:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:51 INFO - 'XPC_FLAGS': '0x0', 05:51:51 INFO - 'XPC_SERVICE_NAME': '0', 05:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:51:51 INFO - Cleaning up... 05:51:51 INFO - Return code: 0 05:51:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:51:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5594b92a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10755ad30>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107552b20>, '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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:51:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UpzPBFzIsL/Render', 05:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:51:51 INFO - 'HOME': '/Users/cltbld', 05:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:51:51 INFO - 'LOGNAME': 'cltbld', 05:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:51 INFO - 'MOZ_NO_REMOTE': '1', 05:51:51 INFO - 'NO_EM_RESTART': '1', 05:51:51 INFO - 'PAGER': '/bin/cat', 05:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:51 INFO - 'PWD': '/builds/slave/test', 05:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:51:51 INFO - 'SHELL': '/bin/bash', 05:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.shnW5AXBhJ/Listeners', 05:51:51 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:51:51 INFO - 'USER': 'cltbld', 05:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:51 INFO - 'XPC_FLAGS': '0x0', 05:51:51 INFO - 'XPC_SERVICE_NAME': '0', 05:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:51:52 INFO - Cleaning up... 05:51:52 INFO - Return code: 0 05:51:52 INFO - Running post-action listener: _resource_record_post_action 05:51:52 INFO - Running post-action listener: _start_resource_monitoring 05:51:52 INFO - Starting resource monitoring. 05:51:52 INFO - [mozharness: 2016-11-09 13:51:52.163096Z] Finished create-virtualenv step (success) 05:51:52 INFO - [mozharness: 2016-11-09 13:51:52.164115Z] Running install step. 05:51:52 INFO - Running pre-action listener: _resource_record_pre_action 05:51:52 INFO - Running main action method: install 05:51:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:51:52 INFO - Reading from file tmpfile_stdout 05:51:52 INFO - Detecting whether we're running mozinstall >=1.0... 05:51:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:51:52 INFO - Reading from file tmpfile_stdout 05:51:52 INFO - Output received: 05:51:52 INFO - Usage: mozinstall [options] installer 05:51:52 INFO - Options: 05:51:52 INFO - -h, --help show this help message and exit 05:51:52 INFO - -d DEST, --destination=DEST 05:51:52 INFO - Directory to install application into. [default: 05:51:52 INFO - "/builds/slave/test"] 05:51:52 INFO - --app=APP Application being installed. [default: firefox] 05:51:52 INFO - mkdir: /builds/slave/test/build/application 05:51:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:52:26 INFO - Reading from file tmpfile_stdout 05:52:26 INFO - Output received: 05:52:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:52:26 INFO - Running post-action listener: _resource_record_post_action 05:52:26 INFO - [mozharness: 2016-11-09 13:52:26.554476Z] Finished install step (success) 05:52:26 INFO - [mozharness: 2016-11-09 13:52:26.554608Z] Running run-tests step. 05:52:26 INFO - Running pre-action listener: _resource_record_pre_action 05:52:26 INFO - Running main action method: run_tests 05:52:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:52:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:52:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:52:26 INFO - 2016-11-09 05:52:26.569 screenresolution[2280:25318] starting screenresolution argv=screenresolution get 05:52:26 INFO - 2016-11-09 05:52:26.571 screenresolution[2280:25318] Display 0: 1600x1200x32@60 05:52:26 INFO - 2016-11-09 05:52:26.577 screenresolution[2281:25321] starting screenresolution argv=screenresolution list 05:52:26 INFO - Available Modes on Display 0 05:52:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:52:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:52:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:52:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:52:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:52:26 INFO - Intel Iris: 05:52:26 INFO - Chipset Model: Intel Iris 05:52:26 INFO - Type: GPU 05:52:26 INFO - Bus: Built-In 05:52:26 INFO - VRAM (Dynamic, Max): 1536 MB 05:52:26 INFO - Vendor: Intel (0x8086) 05:52:26 INFO - Device ID: 0x0a2e 05:52:26 INFO - Revision ID: 0x0009 05:52:26 INFO - Displays: 05:52:26 INFO - Display: 05:52:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:52:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:52:26 INFO - Display Serial Number: 1600x1200 60 05:52:26 INFO - Main Display: Yes 05:52:26 INFO - Mirror: Off 05:52:26 INFO - Online: Yes 05:52:26 INFO - Rotation: Supported 05:52:26 INFO - Return code: 0 05:52:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:52:26 INFO - Python 2.7.3 05:52:26 INFO - Return code: 0 05:52:26 INFO - grabbing minidump binary from tooltool 05:52: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')]} 05:52:26 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 0x10755ad30>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107552b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:52:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:52:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:52:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:52:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:52:26 INFO - Return code: 0 05:52:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:52:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:52:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:52:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:52:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:52:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0207 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:52:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UpzPBFzIsL/Render', 05:52:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:26 INFO - 'HOME': '/Users/cltbld', 05:52:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:26 INFO - 'LOGNAME': 'cltbld', 05:52:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:52:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:26 INFO - 'MOZ_NO_REMOTE': '1', 05:52:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:52:26 INFO - 'NO_EM_RESTART': '1', 05:52:26 INFO - 'PAGER': '/bin/cat', 05:52:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:26 INFO - 'PWD': '/builds/slave/test', 05:52:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:52:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:26 INFO - 'SHELL': '/bin/bash', 05:52:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.shnW5AXBhJ/Listeners', 05:52:26 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 05:52:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:26 INFO - 'USER': 'cltbld', 05:52:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:26 INFO - 'XPC_FLAGS': '0x0', 05:52:26 INFO - 'XPC_SERVICE_NAME': '0', 05:52:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:52:27 INFO - mozversion application_buildid: 20161109044522 05:52:27 INFO - mozversion application_changeset: 3466e6bc9874e5d7e53649d594ab70af9af2e358 05:52:27 INFO - mozversion application_display_name: Nightly 05:52:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:52:27 INFO - mozversion application_name: Firefox 05:52:27 INFO - mozversion application_remotingname: firefox 05:52:27 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 05:52:27 INFO - mozversion application_vendor: Mozilla 05:52:27 INFO - mozversion application_version: 52.0a1 05:52:27 INFO - mozversion platform_buildid: 20161109044522 05:52:27 INFO - mozversion platform_changeset: 3466e6bc9874e5d7e53649d594ab70af9af2e358 05:52:27 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 05:52:27 INFO - mozversion platform_version: 52.0a1 05:52:27 INFO - using testdate: 1478699547 05:52:27 INFO - actual date: 1478699547 05:52:27 INFO - starting webserver on 'localhost:56565' 05:52:27 INFO - SUITE-START | Running 2 tests 05:52:27 INFO - TEST-START | dromaeo_css 05:52:27 INFO - operating with platform_type : mac_ 05:52:27 INFO - Initialising browser for dromaeo_css test... 05:52:27 INFO - TEST-INFO | started process 2291 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmp7tpswD/profile http://localhost:56565/getInfo.html) 05:52:29 INFO - PROCESS | 2291 | __metrics Screen width/height:1600/1200 05:52:29 INFO - PROCESS | 2291 | colorDepth:24 05:52:29 INFO - PROCESS | 2291 | Browser inner width/height: 1024/689 05:52:29 INFO - PROCESS | 2291 | __metrics 05:52:29 INFO - PROCESS | 2291 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:52:29 INFO - PROCESS | 2291 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:52:30 INFO - TEST-INFO | 2291: exit 0 05:52:30 INFO - Browser initialized. 05:52:30 INFO - Running cycle 1/1 for dromaeo_css test... 05:52:30 INFO - TEST-INFO | started process 2293 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmp7tpswD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:42 INFO - PROCESS | 2293 | RSS: Main: 232955904 05:52:42 INFO - PROCESS | 2293 | 05:53:34 INFO - PROCESS | 2293 | 1478699614308 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:55:46 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:56565/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56565/tests/dromaeo/cssquery-ext.html) 05:55:48 INFO - PROCESS | 2293 | RSS: Main: 355717120 05:55:48 INFO - PROCESS | 2293 | 05:58:50 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:56565/tests/dromaeo/cssquery-ext.html (next: http://localhost:56565/tests/dromaeo/cssquery-jquery.html) 05:58:51 INFO - PROCESS | 2293 | RSS: Main: 368906240 05:58:51 INFO - PROCESS | 2293 | 06:01:54 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:56565/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56565/tests/dromaeo/cssquery-mootools.html) 06:01:54 INFO - PROCESS | 2293 | RSS: Main: 355201024 06:01:54 INFO - PROCESS | 2293 | 06:04:57 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:56565/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56565/tests/dromaeo/cssquery-prototype.html) 06:04:57 INFO - PROCESS | 2293 | RSS: Main: 347336704 06:04:57 INFO - PROCESS | 2293 | 06:08:01 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:56565/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56565/tests/dromaeo/cssquery-yui.html) 06:08:01 INFO - PROCESS | 2293 | RSS: Main: 334925824 06:08:01 INFO - PROCESS | 2293 | 06:11:04 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:56565/tests/dromaeo/cssquery-yui.html (next: http://localhost:56565/tests/dromaeo/cssquery-dojo.html) 06:11:05 INFO - PROCESS | 2293 | RSS: Main: 380645376 06:11:05 INFO - PROCESS | 2293 | 06:11:05 INFO - PROCESS | 2293 | __start_tp_report 06:11:05 INFO - PROCESS | 2293 | _x_x_mozilla_page_load 06:11:05 INFO - PROCESS | 2293 | _x_x_mozilla_page_load_details 06:11:05 INFO - PROCESS | 2293 | |i|pagename|runs| 06:11:05 INFO - PROCESS | 2293 | |0;dojo.html;2899.7970142090057;3243.2054146734054;3253.544503769472;3275.5086736989465;3278.639349671536;5465.562754979597;5479.479449452296;5576.275084239043;5589.189567512713;5606.831795046145;5533.3083364579425;5593.440655934404;5682.602217844753;5733.856653583663;5748.109043098327;4894.486078961711;5108.693478391308;5143.971205758863;5155.3040339554245;5193.766280517386;5086.364204474441;5125.718085505296;5193.766280517386;5216.295800067;5225.529702326789;5183.015227106837;5183.507566781187;5226.216067589873;5242.108841496935;5261.1056120459425;5034.01836641855;5095.439501654815;5100.7449627518845;5123.462036486189;5130.846074617767;5065.037642847848;5074.213496907985;5098.260752190911;5101.387833459971;5123.769430375604;4613.35413042171;5624.381318055051;5652;5660.886782264372;5701.315842098933;5254.7651301944015;6048.123022161762;6143.754249830002;6159.38406159385;6166.537509686791;6061.575689701723;6120.990036643949;6125.264968203799;6204.875902481925;6227.470664993481;5645.435456454322;5671.404502527216;5713.714314284269;5714.428557144252;5778.855528611818;6267.4986001119805;6300.692136628215;6352.078948552434;6360;6363.172787537591;6034.909476357858;6050.818475445743;6053.303870054912;6055.8486037848525;6063.727132279058;6022.759089636365;6062.605930614496;6082.756689732361;6126.754929802847;6143.416375444325;5988.580799344004;6014.188084608521;6018.21763170785;6025.6083354581815;6031.638101713911;6001.399860013964;6041.969790151109;6058.182145410313;6060.757569697163;6079.361667024898;4779.976100119477;4820.542048505386;4827.927581086216;4859.732714700725;4860.246661767432;3355.4463513520486;4269.722468039629;4279.507856596469;4376.540463251313;4446.666500012512;5706.258186435737;5709.600327977002;5732.197492351074;5736.6558006519745;5750.396208398141;6036.094585812076;6117.204763380732;6129.816105516842;6233.812985610439;6242.188515492957;5691.459311365414;5701.173329867146;5710.800121995793;5761.971190144022;5801.245838041028;5321.122014867581;5322.973385133049;5326.36083669962;5330.760115794798;5346.4386239445075;6177.7837775677635;6325.240971083499;6347.143135676624;6363.90454143179;6376.458001069961;6000.489958353589;6106.7640410362355;6111.287884814121;6113.29387775398;6173.506119510518;5188.325517682677;6190.659513726608;6304.432601065926;6331.588446750947;6332.176817013758;6055.1220073090035;6061.605995610271;6113.052476866093;6121.632702037892;6127.111568822585;5960.5231581474245;6077.574569780073;6144.41628045335;6223.782167624111;6226.501879849692;5278.680329917521;5897.203877476486;5943.375945525658;6019.909701354396;6021.849453763691;5967.791127310524;5981.581289309706;5993.460588547052;5998.550108741774;6007.97864363068;5538.556915446674;5718.370979192202;5767.625104368202;5808.651480911159;5851.239338885917;5931.525477961839;5957.970210148921;5984.581079324405;6039.4262545058145;6090.025595904634;4891.31521586971;5850.824475265749;5853.151293062568;5886.23478947734;5923.614965027259 06:11:05 INFO - PROCESS | 2293 | |1;ext.html;470.5647276269451;484.85090335903914;502.4573460662485;509.6407033041705;548.8446819445071;29446.558301625468;30993.225169370777;31011;31016.53475197874;31093.75624975001;40276.59723402765;40978.59021409785;41024.794876025655;42696.57303426965;42718.57281427185;8047.315978141865;8836.337274704407;9384.906150938472;9385.155336019758;9395.060493950585;9130.589123489426;9229;9232.630694772235;9238.39950403225;9264.6294148234;9204.40171388861;9243.907560924406;9247.352685312031;9254.583543740548;9330.440173589606;9184.8622270666;9230.261579073658;9230.492322922237;9248.26013918885;9271.629134834599;9104.726858194199;9194;9263.861042084374;9266.814663706687;9299.674511392137;35606.863588184795;38097;38397.424038639445;38522;38543;2813.4513769814894;3238.4008958342547;3382.2559037012065;3437.432823584106;3549.5563054618174;28336;29942.55086173709;29980.85009574938;29999.700002999907;30011.39977200444;3097.8141311521154;3248.1717162123746;3275.2630658101734;3350.832458377096;3356.043527594624;478279;479721;480065;481207.9999999965;490706;58021;58361;58450;58469.70765146147;58612;61428.69285653633;61487.38512614906;61538.69230653907;61603;61642.691786541676;13173.868261317455;15401.768973465407;15725.292361843745;15733.527994160193;15768.448104316523;11983.34091624969;12092.214006089576;12112.757744845054;12119.575814846614;12121.090918181171;14263.928680356532;17686.823131768775;17942.873139177802;18045.007524586275;18069.54826129331;6092;7365;7461.888071678929;7502.774916752506;7512.511686740454;876.9561521923878;993.3841018568389;1000.3197825478599;1005.2257765044702;1018.1752780248023;26863.328416789347;28877;29270.414591708046;29546.704532954824;29552.96564620228;927.9211267042242;1033.4574348467115;1054;1078.5955266774959;1130.6551501791869;12446.626601201979;12764.489420423264;14325.6418589534;14836;15239.933204675788;659074;664695;666889;667544;674487;51215.48784512182;51219.48780512222;51879;51900.74049629803;51915.740421297654;1193.8686744458103;1206.493272825433;1316.605018494467;1351.0812647399666;1368.2201145347256;23974.880125599262;26426.867865660548;28872.845086196317;29414.264643384086;29492.387562271706;46333.53666463292;46744.766276169765;46793.53206467892;46850.76574617105;46903.76548117238;517123;518496;518574;518793;518907;24327.905244264406;42071.57928420677;42439.94817103484;43684.78157609192;43938;44161.33757993569;44372.778136110406;44519;46134.76932615315;46494.767526162155;37747.81126094462;39114.2177156461;39972.800136000296;40220.59779402168;41569.79215103905;38142.85071515421;38182;38265.617343826205;39566.40650390189;39855;37689;38517.42223866588;38794;38865.417018745306;38869;25089.24732258035;26090;33788.43001639085;33946.32107357888;34256.82871585626;25077.122300719388;25123.869425876244;25275.49449011046;25667.35831604225;25733.613995790452 06:11:05 INFO - PROCESS | 2293 | |2;jquery.html;318.89317078778606;361.82270687363194;371.37423441501073;377.7487970499618;403.75572778469024;14650.560483185494;14918;15823.287952042172;16806.32774689011;19993.60012799743;19753;19757.506062348413;20197.798022019775;20315.796842031574;20598;5700.087985922243;5996.070609055581;6003.099535069731;6004.879902401949;6016.699165041752;4687.2969054641735;5886.293644762634;5919.112133180036;5930.4366085222055;5935.525157987372;5588.357338906017;5851.736671849756;5871.266091738533;5881.588288819784;5897.5550990007505;5685.800996965107;5805;5811;5813.040848260032;5815.098659707731;4837;5081.314022606938;5095.312132862054;5098.4646612105935;5103.770330335144;25481;25502.489950201078;25661;25925.092621758336;25965;2247.3370355745096;2308.122913292938;2341.7072865891764;2352.2472808701223;2961.570572267009;14831.258437078102;14960.251987400696;15109.47116850905;15558.144302063381;15707.978981366288;1809.782826060868;1827.2416946967126;1879.7462342583713;1897.9240830366768;1939.2630800295938;114478;117308;118681;118786;124442.37778811048;10838.78322433547;11403.201775875608;11406.771864562828;11449.198556100993;11545.422728863523;14193.148411095368;14238.077859489043;14248.501302454571;14258.287085645676;14301.78547321791;6850.314968503109;7958.801029974181;8256.876146857801;8394.11861754507;8409.266988228728;8089.110197878229;8095.3523718102415;8130.715424960216;8311.210435008665;8350.290225330793;4437.489688685778;4444.8444304449185;4474.328850672425;4483.50681425043;4521.208788462006;1749.9387521436688;1781.7199781632987;1793.748875157479;1837.3477415517566;1891.754071970635;791.3036527855451;876.7282142535835;883.6597909804772;926.7497775600685;931.8741969834122;19695.60608787816;20350.79649203489;20693.275735349493;20695.79304206939;20752.06615702297;783.1854870934164;884.398608945923;890.4479222881855;917.3853518142861;920.0753242990752;8666.78333041679;10551.788964220672;11048.889511104946;11122.22144449897;11164.497597608128;595765;598660;600837;614564;615866.3653453902;125934;130212.72340957381;131702.68297316905;133172;133744;942.5758408716242;944.3248077624531;978.6085565773748;979.6962941488162;980.4656462228103;18372;18467.445976620722;18541;18599.90700046491;18643.533911652317;46376.608701739;48923.755381222865;52570.9485810274;52618.73690631522;53160.468395315555;76295;77038;77108;77380.61309693416;79799.88420599392;6784.966075169593;7921.1682773307975;7990.520568765893;8095.959520202361;8429.24136827664;11901.553938740666;12580.559680411141;12705.237685738884;12877.356132193165;12962.027847911255;11116.499757511094;11211.943940280247;11246.987771100641;11260.38067906273;11456.968872801488;8257.091719910924;10844.566217351217;10955.83566246523;10999.61501347449;11434.42827858587;11167.38579378142;11317.547298107984;11555.42222888869;11622.84977279206;11630.360330181922;8080.757577272691;8113.432059755761;8155.184481551797;8174.918250817654;8199.196176841086;5464.945350546603;8279.917200827915;11071.72320691989;11336.263142896016;11464.31214127155 06:11:05 INFO - PROCESS | 2293 | |3;mootools.html;1281.135233717241;1467.7798330250464;1602.719524083286;1761.920713567891;1821.6174603333307;6645.634490103049;7072.646367681615;7119.537230080041;8386.790330241747;8429.283510901581;7399.149097853749;7689.384849212063;7700.807479813007;8058.63736131874;8308.169183081689;2284.006457191123;2313.3869524576003;2316.721993360799;2319.3042087373806;2321.698179236697;2278.214016164429;2310.1221535816367;2324.7791459811374;2329.033451117784;2335.602947498929;2286.2683941138844;2292.839501234914;2309.2956648222284;2321.8258630602686;2324.9070037198494;2282.7032485776745;2287.542491501698;2308.7806658367426;2312.7340355859124;2316.7567405422355;2299.9195028173935;2322.047960336254;2324.6280595104704;2325.3489023073566;2332.090484710964;8337.416380853343;8375.49747015175;8443.873341899876;8444.662213511452;8470.872936905951;5025.522575355335;5192.896142077175;5196.662216955886;5198.714070726109;5202.4277329493725;3132.1856317357424;3240.3519296140867;3499.7725147865563;3505.456654218575;3505.964940350615;1025.4308858583483;1034.384541197986;1046.7383154211448;1079.0881704959297;1081.2701426537055;10894.400807955559;11005.339679619168;11046.005859472685;11060.281081729661;11062.05972492339;4001.9399709004388;4398.494173170063;4469.396631454777;4646.581807637262;4659.743714095689;9397.01331360211;9404.24766018717;9404.965453800076;9406.48264345454;9412.15290623847;5167.354080739908;5170.405403378584;5177.301064356264;5186;5203.271541984125;4272.124214536075;4406.1187762447635;4584;4709.7174169549935;4754.23932170851;3624.4200927851944;3690.040589446763;3740.9625903741157;3790.0903783092413;3794.29805485986;1026.779242462874;1224.2776761710634;1230.4647478346947;1243.6206956878239;1248.8501379834297;728.1262485018;813.6663967773185;881.453498830729;883.6774577279247;922.1838672774509;5527.336719593594;5767.942320576741;5774.393688662714;5891.587588868738;5989.760409583655;868.96524139035;890.0165317324393;917.2203626917201;918.8851393575803;929.5212965322864;3788.8105594720155;3806.4996080663177;3879.3599056155417;3909.139989202371;3910.2570511602703;9941.105300522988;10277.640282590073;10282.485875706185;10286.948565257126;10292.845607315896;10169.593216271414;10181.083702466814;10240.283180177315;10257.948710256402;10261.794764104825;7775.611219439005;7879.803004924923;7884.724034658874;7913;7916.960415197887;4375.868723938287;5535.640183388067;5543.530964294546;5613.859653508532;5634.718264086698;8766.780830479289;9204.033576474374;9417.811643767223;9436.245100392089;9525.714228573153;9438.764030899009;9504.144626983605;9524.571394287135;9538.570764315484;9661.323707340418;5509.47659972302;5558.360788509373;6395.584287021329;6395.776147834804;6655.001749737385;7698.807529811799;7931.682732690628;7952.204779522001;8026.354597307654;8038.236367545074;6978.860422791618;7060.011598376334;7092.503524753218;7094.219635839951;7210.459215558749;6849;6989.790306290819;7012.789616311519;7060.929390706233;7095.467839911924;6593;6631.204255489372;6709.7651582194385;6717.261101278953;6717.596944183365;4773.952260477351;4818.71087734737;4864.464908860094;4884.5603895649565;4980.8754781130765;4694.319323697976;4802.639802014793;4909.214525675875;4940.530649588283;4964.553190212754 06:11:05 INFO - PROCESS | 2293 | |4;prototype.html;265.6941134324793;300.896190814169;303.10432671455845;315.0689711901332;316.0975415189632;4692.929606055912;5256.554447526932;5323.041852466559;5342.332208473941;5407.702576358299;4293.914121717564;4966.751662416874;5121.257417674435;5177.404598471167;5305.204219367097;3364.1421437533395;3946.861859834906;3967.02807812085;3985.581513941038;3995.60043995601;3014.849257537125;3394.321135772843;3850.593379138039;3961.039285553655;4057.0871553900433;3232.660570640084;3856.093817952779;3971.682265418774;3999.8200530843324;4011.8395264189544;3467.254540273828;3984.087637933779;3987.2089537323845;3995.9400608990886;3999.6400323970984;2911.199420159452;3358.983205083983;3477.7653932853727;3486.041338631871;3502.807345595991;4941.22116038226;5102.923456148161;5185.977468787176;5195.324607801;5201.167813149916;3768.7361884668085;4389.193832753131;4526.072155208208;4556.863294101182;4596.54034596541;4895.209730453643;4906.754662266872;5316.341829085457;5550.611457197998;5554.555995362028;3200.487921932503;3769.8869033929027;3771.6605505504363;3827.9043023924346;3836.079340958178;4821.638377121695;5610.747516361774;5781.450762177545;5785.971070144665;5826.329972053183;3833.9041523961564;3840.2895464339185;3847.115163512408;3849.480320156799;3849.768074216224;3343.5151902973935;3480.338735640271;3753.7237339304766;3934.5475270344277;3955.7626542407547;4156.8129434175535;4326.437563116775;4367.825286988548;4587.362266696663;4610.700304480198;4709.434867815884;5557.6109672323355;5562.860928476821;5622.606417550732;5629.971850140805;1078.7572796120967;1169.3042639629407;1261.8864302212662;1264.3488603369271;1273.3887733887786;698.7135274537416;796.7012370361115;797.2306724011357;844.6738620365967;859.3769517100177;4238.664820581507;4798.352222449925;4950;4954.430240522314;4970.732463221928;4689.116193540948;5343.839684809462;5460.290162278958;5461.563074953994;5474.698891560923;4905.166121759309;4908.6073114151495;4957.405111386656;5333.346662534626;5385.973070134624;2432.647266146399;2757.310672331917;2850.389529915591;2852.8573571321767;2893.0327377383583;4784.68899521537;4851.781669824866;5574.470425309589;5596.244506991586;5641.774329026793;4436;5284.471552844761;5290.894182116336;5545.947999240227;5586.217929489874;4890.820966371815;4997.775100120431;5202.401723801736;5525.668459892419;5700.287464066992;4407.603315701474;4830.396200474941;4845.55299573713;4858.708477491362;5401.6495876030995;5209.577546440772;5267.868303292448;5336.199570064521;5400.486953739389;5434.3750468695025;4988.825391111293;5110.8756073663735;5288.545649946142;5453.863653408696;5565.912782706485;3106.8601912914423;3148.874045038173;3445.87939422119;3464.8960531184107;3634.436662317345;5324.866878328073;5621.454100122499;5781.884362312814;5790.536757059509;5797.826065218051;4420.823167073409;5232.869178270422;5354.464553544614;5578.414266501959;5616.66300022;4956.3396262252545;5260.658057226268;5583.134614134816;5599.348192283204;5684.289463817023;5048.747562621781;5257.947420525898;5522.22688823573;5523.558115350682;5547.694876781816;5462.415899389292;5522.901297506922;5528.751206195811;5574.609777315549;5642.633228840113;5185.833187532926;5257.342832145981;5269.314989051399;5445.618806683494;5494 06:11:05 INFO - PROCESS | 2293 | |5;yui.html;698.8462538241588;725.6009194942784;743.788020414182;770.1989930472307;777.9649915753797;14259.857401425983;14667.046641968265;14695;14697.926510367446;14703.117812931217;13213.075084744072;13341.866581334183;13344.666383340422;13345.599632011055;13349.733005339911;669.3707914560298;691.87892118879;698.3365802487632;829.3489610655637;854.7734850264685;839.1524560194217;858.8797568340437;859.6174702257498;861.8577934640776;863.3783675753479;901.3330135699567;901.589776651622;903.941243819153;906.7189171356868;907.5235501361805;697.6267696782197;717.8026042838209;868.8349213649386;894.9931327256836;896.2023798819056;665.7436886798569;667.3760034367882;667.6461475418037;668.0813880913744;669.2270427656016;14305.85694143066;14310.069845460011;14326.928365358212;14346.282685865664;14360.712785744226;10964.671059868217;13079.346032698328;13194.274314912676;13277.867221327857;13424.060315777899;10197;12152.817707734392;12251.877481225163;12313.44589493475;12377.133600647958;4394.78026098697;4396.450443694538;4419.447569053868;4439.356293337481;4496.527864574238;100004;100204;100967;101637;104213.4789326056;10464.372137671611;10485;10492.580296788043;10533.157347412189;10909.454527273605;20303.796962030487;20310.22709663228;20355.185792568132;20361.083751230934;20378.89810550938;7420.666070026862;7422.183559808417;7474.476786624884;7562.962185189039;7712.305892469704;4256.106217694319;4300.1399720056115;4311.461067366579;4368.934465983013;4450.287953927421;3166.5408515765544;3264.135004223883;3268.803871767654;3269.035634487784;3288.177955511122;596.263614436171;601.9398060193946;605.5367643752533;606;606.9939300607026;479.5635971266131;539.3527766679999;559.8516393155817;593.8248216776061;609.338867328953;8016.238457346542;8495.66017359311;8581.184787445183;8697.782555436164;8699.173578510032;510.85696005118626;547.026293198108;555.9492559063374;597.7071235094772;631.43486579512;3778.3576791945434;3885.9702178922607;3969.364901615728;4191.517975432804;4194.119234960632;69714.65142674356;94629.52685236529;103656;106598;112378;74479.62760186165;85344.86637834104;97300;99628;99799;65330.67334663296;89845.65231521532;90574.64138037803;90885;90909.09090909007;7168.103987001625;8064.919350806417;8186.549739764132;8374.120717324593;8407.789805254673;50679.74660126676;55066.898662027335;56037;56269.43730562642;57677.501924749515;63928.721425572156;66983.32541686497;69463.30536694569;69936.30063699298;72929;11971.341576213392;12102.792759918804;12123.696907577381;12299.87700123023;12434.81347779766;13836.792448113085;13904.791428128385;14082.366293517021;14347.852171826438;14627;12621.495140194289;12981.545645902348;13072.803907941197;13119.737605248032;13424.664383390493;12662.620121396372;12837.101402901708;13201.801972970605;13248.470061197444;13681.042327037012;9516.238700903772;11129.220954533104;11147;11330.94334528322;11443.42782860837;9349.813003739751;9364.157225849705;9709.223262139154;9729;9850.507474626096;9387.014363491735;9643.228541716506;9701.175400091071;9863.408195508293;10944.343339399662 06:11:05 INFO - PROCESS | 2293 | __end_tp_report 06:11:05 INFO - PROCESS | 2293 | __start_cc_report 06:11:05 INFO - PROCESS | 2293 | _x_x_mozilla_cycle_collect,1704 06:11:05 INFO - PROCESS | 2293 | __end_cc_report 06:11:05 INFO - PROCESS | 2293 | __startTimestamp1478700665158__endTimestamp 06:11:05 INFO - PROCESS | 2293 | 06:11:05 INFO - PROCESS | 2293 | ------- Summary: start ------- 06:11:05 INFO - PROCESS | 2293 | Number of tests: 6 06:11:05 INFO - PROCESS | 2293 | 06:11:05 INFO - PROCESS | 2293 | [#0] dojo.html Cycles:165 Average:5628.11 Median:5808.65 stddev:669.51 (11.5%) stddev-sans-first:636.43 06:11:05 INFO - PROCESS | 2293 | Values: 2899.8 3243.2 3253.5 3275.5 3278.6 5465.6 5479.5 5576.3 5589.2 5606.8 5533.3 5593.4 5682.6 5733.9 5748.1 4894.5 5108.7 5144.0 5155.3 5193.8 5086.4 5125.7 5193.8 5216.3 5225.5 5183.0 5183.5 5226.2 5242.1 5261.1 5034.0 5095.4 5100.7 5123.5 5130.8 5065.0 5074.2 5098.3 5101.4 5123.8 4613.4 5624.4 5652.0 5660.9 5701.3 5254.8 6048.1 6143.8 6159.4 6166.5 6061.6 6121.0 6125.3 6204.9 6227.5 5645.4 5671.4 5713.7 5714.4 5778.9 6267.5 6300.7 6352.1 6360.0 6363.2 6034.9 6050.8 6053.3 6055.8 6063.7 6022.8 6062.6 6082.8 6126.8 6143.4 5988.6 6014.2 6018.2 6025.6 6031.6 6001.4 6042.0 6058.2 6060.8 6079.4 4780.0 4820.5 4827.9 4859.7 4860.2 3355.4 4269.7 4279.5 4376.5 4446.7 5706.3 5709.6 5732.2 5736.7 5750.4 6036.1 6117.2 6129.8 6233.8 6242.2 5691.5 5701.2 5710.8 5762.0 5801.2 5321.1 5323.0 5326.4 5330.8 5346.4 6177.8 6325.2 6347.1 6363.9 6376.5 6000.5 6106.8 6111.3 6113.3 6173.5 5188.3 6190.7 6304.4 6331.6 6332.2 6055.1 6061.6 6113.1 6121.6 6127.1 5960.5 6077.6 6144.4 6223.8 6226.5 5278.7 5897.2 5943.4 6019.9 6021.8 5967.8 5981.6 5993.5 5998.6 6008.0 5538.6 5718.4 5767.6 5808.7 5851.2 5931.5 5958.0 5984.6 6039.4 6090.0 4891.3 5850.8 5853.2 5886.2 5923.6 06:11:05 INFO - PROCESS | 2293 | 06:11:05 INFO - PROCESS | 2293 | [#1] ext.html Cycles:180 Average:68354.49 Median:40096.70 stddev:150135.60 (374.4%) stddev-sans-first:150470.25 06:11:05 INFO - PROCESS | 2293 | Values: 470.6 484.9 502.5 509.6 548.8 29446.6 30993.2 31011.0 31016.5 31093.8 40276.6 40978.6 41024.8 42696.6 42718.6 8047.3 8836.3 9384.9 9385.2 9395.1 9130.6 9229.0 9232.6 9238.4 9264.6 9204.4 9243.9 9247.4 9254.6 9330.4 9184.9 9230.3 9230.5 9248.3 9271.6 9104.7 9194.0 9263.9 9266.8 9299.7 35606.9 38097.0 38397.4 38522.0 38543.0 2813.5 3238.4 3382.3 3437.4 3549.6 28336.0 29942.6 29980.9 29999.7 30011.4 3097.8 3248.2 3275.3 3350.8 3356.0 478279.0 479721.0 480065.0 481208.0 490706.0 58021.0 58361.0 58450.0 58469.7 58612.0 61428.7 61487.4 61538.7 61603.0 61642.7 13173.9 15401.8 15725.3 15733.5 15768.4 11983.3 12092.2 12112.8 12119.6 12121.1 14263.9 17686.8 17942.9 18045.0 18069.5 6092.0 7365.0 7461.9 7502.8 7512.5 877.0 993.4 1000.3 1005.2 1018.2 26863.3 28877.0 29270.4 29546.7 29553.0 927.9 1033.5 1054.0 1078.6 1130.7 12446.6 12764.5 14325.6 14836.0 15239.9 659074.0 664695.0 666889.0 667544.0 674487.0 51215.5 51219.5 51879.0 51900.7 51915.7 1193.9 1206.5 1316.6 1351.1 1368.2 23974.9 26426.9 28872.8 29414.3 29492.4 46333.5 46744.8 46793.5 46850.8 46903.8 517123.0 518496.0 518574.0 518793.0 518907.0 24327.9 42071.6 42439.9 43684.8 43938.0 44161.3 44372.8 44519.0 46134.8 46494.8 37747.8 39114.2 39972.8 40220.6 41569.8 38142.9 38182.0 38265.6 39566.4 39855.0 37689.0 38517.4 38794.0 38865.4 38869.0 25089.2 26090.0 33788.4 33946.3 34256.8 25077.1 25123.9 25275.5 25667.4 25733.6 06:11:05 INFO - PROCESS | 2293 | 06:11:05 INFO - PROCESS | 2293 | [#2] jquery.html Cycles:180 Average:35141.47 Median:1640.23 stddev:101044.80 (6160.4%) stddev-sans-first:101294.43 06:11:05 INFO - PROCESS | 2293 | Values: 318.9 361.8 371.4 377.7 403.8 14650.6 14918.0 15823.3 16806.3 19993.6 19753.0 19757.5 20197.8 20315.8 20598.0 5700.1 5996.1 6003.1 6004.9 6016.7 4687.3 5886.3 5919.1 5930.4 5935.5 5588.4 5851.7 5871.3 5881.6 5897.6 5685.8 5805.0 5811.0 5813.0 5815.1 4837.0 5081.3 5095.3 5098.5 5103.8 25481.0 25502.5 25661.0 25925.1 25965.0 2247.3 2308.1 2341.7 2352.2 2961.6 14831.3 14960.3 15109.5 15558.1 15708.0 1809.8 1827.2 1879.7 1897.9 1939.3 114478.0 117308.0 118681.0 118786.0 124442.4 10838.8 11403.2 11406.8 11449.2 11545.4 14193.1 14238.1 14248.5 14258.3 14301.8 6850.3 7958.8 8256.9 8394.1 8409.3 8089.1 8095.4 8130.7 8311.2 8350.3 4437.5 4444.8 4474.3 4483.5 4521.2 1749.9 1781.7 1793.7 1837.3 1891.8 791.3 876.7 883.7 926.7 931.9 19695.6 20350.8 20693.3 20695.8 20752.1 783.2 884.4 890.4 917.4 920.1 8666.8 10551.8 11048.9 11122.2 11164.5 595765.0 598660.0 600837.0 614564.0 615866.4 125934.0 130212.7 131702.7 133172.0 133744.0 942.6 944.3 978.6 979.7 980.5 18372.0 18467.4 18541.0 18599.9 18643.5 46376.6 48923.8 52570.9 52618.7 53160.5 76295.0 77038.0 77108.0 77380.6 79799.9 6785.0 7921.2 7990.5 8096.0 8429.2 11901.6 12580.6 12705.2 12877.4 12962.0 11116.5 11211.9 11247.0 11260.4 11457.0 8257.1 10844.6 10955.8 10999.6 11434.4 11167.4 11317.5 11555.4 11622.8 11630.4 8080.8 8113.4 8155.2 8174.9 8199.2 5464.9 8279.9 11071.7 11336.3 11464.3 06:11:05 INFO - PROCESS | 2293 | 06:11:05 INFO - PROCESS | 2293 | [#3] mootools.html Cycles:180 Average:5399.83 Median:5096.44 stddev:2983.36 (58.5%) stddev-sans-first:2975.67 06:11:05 INFO - PROCESS | 2293 | Values: 1281.1 1467.8 1602.7 1761.9 1821.6 6645.6 7072.6 7119.5 8386.8 8429.3 7399.1 7689.4 7700.8 8058.6 8308.2 2284.0 2313.4 2316.7 2319.3 2321.7 2278.2 2310.1 2324.8 2329.0 2335.6 2286.3 2292.8 2309.3 2321.8 2324.9 2282.7 2287.5 2308.8 2312.7 2316.8 2299.9 2322.0 2324.6 2325.3 2332.1 8337.4 8375.5 8443.9 8444.7 8470.9 5025.5 5192.9 5196.7 5198.7 5202.4 3132.2 3240.4 3499.8 3505.5 3506.0 1025.4 1034.4 1046.7 1079.1 1081.3 10894.4 11005.3 11046.0 11060.3 11062.1 4001.9 4398.5 4469.4 4646.6 4659.7 9397.0 9404.2 9405.0 9406.5 9412.2 5167.4 5170.4 5177.3 5186.0 5203.3 4272.1 4406.1 4584.0 4709.7 4754.2 3624.4 3690.0 3741.0 3790.1 3794.3 1026.8 1224.3 1230.5 1243.6 1248.9 728.1 813.7 881.5 883.7 922.2 5527.3 5767.9 5774.4 5891.6 5989.8 869.0 890.0 917.2 918.9 929.5 3788.8 3806.5 3879.4 3909.1 3910.3 9941.1 10277.6 10282.5 10286.9 10292.8 10169.6 10181.1 10240.3 10257.9 10261.8 7775.6 7879.8 7884.7 7913.0 7917.0 4375.9 5535.6 5543.5 5613.9 5634.7 8766.8 9204.0 9417.8 9436.2 9525.7 9438.8 9504.1 9524.6 9538.6 9661.3 5509.5 5558.4 6395.6 6395.8 6655.0 7698.8 7931.7 7952.2 8026.4 8038.2 6978.9 7060.0 7092.5 7094.2 7210.5 6849.0 6989.8 7012.8 7060.9 7095.5 6593.0 6631.2 6709.8 6717.3 6717.6 4774.0 4818.7 4864.5 4884.6 4980.9 4694.3 4802.6 4909.2 4940.5 4964.6 06:11:05 INFO - PROCESS | 2293 | 06:11:05 INFO - PROCESS | 2293 | [#4] prototype.html Cycles:180 Average:4375.38 Median:4945.61 stddev:1363.96 (27.6%) stddev-sans-first:1332.45 06:11:05 INFO - PROCESS | 2293 | Values: 265.7 300.9 303.1 315.1 316.1 4692.9 5256.6 5323.0 5342.3 5407.7 4293.9 4966.8 5121.3 5177.4 5305.2 3364.1 3946.9 3967.0 3985.6 3995.6 3014.8 3394.3 3850.6 3961.0 4057.1 3232.7 3856.1 3971.7 3999.8 4011.8 3467.3 3984.1 3987.2 3995.9 3999.6 2911.2 3359.0 3477.8 3486.0 3502.8 4941.2 5102.9 5186.0 5195.3 5201.2 3768.7 4389.2 4526.1 4556.9 4596.5 4895.2 4906.8 5316.3 5550.6 5554.6 3200.5 3769.9 3771.7 3827.9 3836.1 4821.6 5610.7 5781.5 5786.0 5826.3 3833.9 3840.3 3847.1 3849.5 3849.8 3343.5 3480.3 3753.7 3934.5 3955.8 4156.8 4326.4 4367.8 4587.4 4610.7 4709.4 5557.6 5562.9 5622.6 5630.0 1078.8 1169.3 1261.9 1264.3 1273.4 698.7 796.7 797.2 844.7 859.4 4238.7 4798.4 4950.0 4954.4 4970.7 4689.1 5343.8 5460.3 5461.6 5474.7 4905.2 4908.6 4957.4 5333.3 5386.0 2432.6 2757.3 2850.4 2852.9 2893.0 4784.7 4851.8 5574.5 5596.2 5641.8 4436.0 5284.5 5290.9 5545.9 5586.2 4890.8 4997.8 5202.4 5525.7 5700.3 4407.6 4830.4 4845.6 4858.7 5401.6 5209.6 5267.9 5336.2 5400.5 5434.4 4988.8 5110.9 5288.5 5453.9 5565.9 3106.9 3148.9 3445.9 3464.9 3634.4 5324.9 5621.5 5781.9 5790.5 5797.8 4420.8 5232.9 5354.5 5578.4 5616.7 4956.3 5260.7 5583.1 5599.3 5684.3 5048.7 5257.9 5522.2 5523.6 5547.7 5462.4 5522.9 5528.8 5574.6 5642.6 5185.8 5257.3 5269.3 5445.6 5494.0 06:11:05 INFO - PROCESS | 2293 | 06:11:05 INFO - PROCESS | 2293 | [#5] yui.html Cycles:180 Average:20186.49 Median:525.10 stddev:29727.71 (5661.3%) stddev-sans-first:29775.10 06:11:05 INFO - PROCESS | 2293 | Values: 698.8 725.6 743.8 770.2 778.0 14259.9 14667.0 14695.0 14697.9 14703.1 13213.1 13341.9 13344.7 13345.6 13349.7 669.4 691.9 698.3 829.3 854.8 839.2 858.9 859.6 861.9 863.4 901.3 901.6 903.9 906.7 907.5 697.6 717.8 868.8 895.0 896.2 665.7 667.4 667.6 668.1 669.2 14305.9 14310.1 14326.9 14346.3 14360.7 10964.7 13079.3 13194.3 13277.9 13424.1 10197.0 12152.8 12251.9 12313.4 12377.1 4394.8 4396.5 4419.4 4439.4 4496.5 100004.0 100204.0 100967.0 101637.0 104213.5 10464.4 10485.0 10492.6 10533.2 10909.5 20303.8 20310.2 20355.2 20361.1 20378.9 7420.7 7422.2 7474.5 7563.0 7712.3 4256.1 4300.1 4311.5 4368.9 4450.3 3166.5 3264.1 3268.8 3269.0 3288.2 596.3 601.9 605.5 606.0 607.0 479.6 539.4 559.9 593.8 609.3 8016.2 8495.7 8581.2 8697.8 8699.2 510.9 547.0 555.9 597.7 631.4 3778.4 3886.0 3969.4 4191.5 4194.1 69714.7 94629.5 103656.0 106598.0 112378.0 74479.6 85344.9 97300.0 99628.0 99799.0 65330.7 89845.7 90574.6 90885.0 90909.1 7168.1 8064.9 8186.5 8374.1 8407.8 50679.7 55066.9 56037.0 56269.4 57677.5 63928.7 66983.3 69463.3 69936.3 72929.0 11971.3 12102.8 12123.7 12299.9 12434.8 13836.8 13904.8 14082.4 14347.9 14627.0 12621.5 12981.5 13072.8 13119.7 13424.7 12662.6 12837.1 13201.8 13248.5 13681.0 9516.2 11129.2 11147.0 11330.9 11443.4 9349.8 9364.2 9709.2 9729.0 9850.5 9387.0 9643.2 9701.2 9863.4 10944.3 06:11:05 INFO - PROCESS | 2293 | -------- Summary: end -------- 06:11:05 INFO - PROCESS | 2293 | 06:11:05 INFO - TEST-INFO | 2293: exit 0 06:11:05 INFO - TEST-OK | dromaeo_css | took 1118612ms 06:11:05 INFO - TEST-START | kraken 06:11:05 INFO - operating with platform_type : mac_ 06:11:05 INFO - Initialising browser for kraken test... 06:11:05 INFO - TEST-INFO | started process 2295 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpVxBo04/profile http://localhost:56565/getInfo.html) 06:11:08 INFO - PROCESS | 2295 | __metrics Screen width/height:1600/1200 06:11:08 INFO - PROCESS | 2295 | colorDepth:24 06:11:08 INFO - PROCESS | 2295 | Browser inner width/height: 1024/689 06:11:08 INFO - PROCESS | 2295 | __metrics 06:11:08 INFO - PROCESS | 2295 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:11:08 INFO - PROCESS | 2295 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:11:08 INFO - TEST-INFO | 2295: exit 0 06:11:08 INFO - Browser initialized. 06:11:08 INFO - Running cycle 1/1 for kraken test... 06:11:08 INFO - TEST-INFO | started process 2296 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpVxBo04/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:11:20 INFO - PROCESS | 2296 | RSS: Main: 233455616 06:11:20 INFO - PROCESS | 2296 | 06:11:23 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56565/tests/kraken/driver.html?testName=audio-beat-detection) 06:11:24 INFO - PROCESS | 2296 | RSS: Main: 290975744 06:11:24 INFO - PROCESS | 2296 | 06:11:27 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56565/tests/kraken/driver.html?testName=audio-dft) 06:11:27 INFO - PROCESS | 2296 | RSS: Main: 293470208 06:11:27 INFO - PROCESS | 2296 | 06:11:31 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56565/tests/kraken/driver.html?testName=audio-fft) 06:11:31 INFO - PROCESS | 2296 | RSS: Main: 382558208 06:11:31 INFO - PROCESS | 2296 | 06:11:33 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56565/tests/kraken/driver.html?testName=audio-oscillator) 06:11:34 INFO - PROCESS | 2296 | RSS: Main: 286052352 06:11:34 INFO - PROCESS | 2296 | 06:11:36 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56565/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:11:37 INFO - PROCESS | 2296 | RSS: Main: 290582528 06:11:37 INFO - PROCESS | 2296 | 06:11:39 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56565/tests/kraken/driver.html?testName=imaging-darkroom) 06:11:40 INFO - PROCESS | 2296 | RSS: Main: 327004160 06:11:40 INFO - PROCESS | 2296 | 06:11:43 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56565/tests/kraken/driver.html?testName=imaging-desaturate) 06:11:44 INFO - PROCESS | 2296 | RSS: Main: 327839744 06:11:44 INFO - PROCESS | 2296 | 06:11:47 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56565/tests/kraken/driver.html?testName=json-parse-financial) 06:11:47 INFO - PROCESS | 2296 | RSS: Main: 327847936 06:11:47 INFO - PROCESS | 2296 | 06:11:49 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56565/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:11:50 INFO - PROCESS | 2296 | RSS: Main: 301527040 06:11:50 INFO - PROCESS | 2296 | 06:11:51 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56565/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:11:52 INFO - PROCESS | 2296 | RSS: Main: 306663424 06:11:52 INFO - PROCESS | 2296 | 06:11:54 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56565/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:11:55 INFO - PROCESS | 2296 | RSS: Main: 308502528 06:11:55 INFO - PROCESS | 2296 | 06:11:58 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56565/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:11:59 INFO - PROCESS | 2296 | RSS: Main: 327159808 06:11:59 INFO - PROCESS | 2296 | 06:12:02 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56565/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:12:02 INFO - PROCESS | 2296 | RSS: Main: 320012288 06:12:02 INFO - PROCESS | 2296 | 06:12:04 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:56565/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56565/tests/kraken/driver.html?testName=ai-astar) 06:12:04 INFO - PROCESS | 2296 | RSS: Main: 362795008 06:12:04 INFO - PROCESS | 2296 | 06:12:04 INFO - PROCESS | 2296 | __start_tp_report 06:12:04 INFO - PROCESS | 2296 | _x_x_mozilla_page_load 06:12:04 INFO - PROCESS | 2296 | _x_x_mozilla_page_load_details 06:12:04 INFO - PROCESS | 2296 | |i|pagename|runs| 06:12:04 INFO - PROCESS | 2296 | |0;ai-astar;111;115;121;166;154;114;113;113;113;113 06:12:04 INFO - PROCESS | 2296 | |1;audio-beat-detection;147;115;111;147;111;110;113;111;137;126 06:12:04 INFO - PROCESS | 2296 | |2;audio-dft;184;185;184;183;188;182;184;182;182;185 06:12:04 INFO - PROCESS | 2296 | |3;audio-fft;78;61;59;89;60;60;58;58;83;58 06:12:04 INFO - PROCESS | 2296 | |4;audio-oscillator;86;72;92;70;68;69;91;68;70;93 06:12:04 INFO - PROCESS | 2296 | |5;imaging-gaussian-blur;88;85;88;86;87;84;90;85;88;85 06:12:04 INFO - PROCESS | 2296 | |6;imaging-darkroom;95;95;95;110;162;101;151;163;97;96 06:12:04 INFO - PROCESS | 2296 | |7;imaging-desaturate;82;89;123;82;84;82;86;83;89;130 06:12:04 INFO - PROCESS | 2296 | |8;json-parse-financial;57;53;53;54;54;54;54;54;53;54 06:12:04 INFO - PROCESS | 2296 | |9;json-stringify-tinderbox;69;77;70;80;81;75;74;79;79;76 06:12:04 INFO - PROCESS | 2296 | |10;stanford-crypto-aes;88;86;85;90;86;87;98;108;105;89 06:12:04 INFO - PROCESS | 2296 | |11;stanford-crypto-ccm;148;151;159;163;150;149;150;151;212;140 06:12:04 INFO - PROCESS | 2296 | |12;stanford-crypto-pbkdf2;173;176;172;172;176;178;176;171;173;172 06:12:04 INFO - PROCESS | 2296 | |13;stanford-crypto-sha256-iterative;68;72;76;88;70;61;82;63;70;83 06:12:04 INFO - PROCESS | 2296 | __end_tp_report 06:12:04 INFO - PROCESS | 2296 | __start_cc_report 06:12:04 INFO - PROCESS | 2296 | _x_x_mozilla_cycle_collect,2037 06:12:04 INFO - PROCESS | 2296 | __end_cc_report 06:12:04 INFO - PROCESS | 2296 | __startTimestamp1478700724991__endTimestamp 06:12:04 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | ------- Summary: start ------- 06:12:05 INFO - PROCESS | 2296 | Number of tests: 14 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#0] ai-astar Cycles:10 Average:123.30 Median:114.50 stddev:19.73 (17.2%) stddev-sans-first:20.41 06:12:05 INFO - PROCESS | 2296 | Values: 111.0 115.0 121.0 166.0 154.0 114.0 113.0 113.0 113.0 113.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#1] audio-beat-detection Cycles:10 Average:122.80 Median:120.50 stddev:15.35 (12.7%) stddev-sans-first:13.56 06:12:05 INFO - PROCESS | 2296 | Values: 147.0 115.0 111.0 147.0 111.0 110.0 113.0 111.0 137.0 126.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.85 (1.0%) stddev-sans-first:1.96 06:12:05 INFO - PROCESS | 2296 | Values: 184.0 185.0 184.0 183.0 188.0 182.0 184.0 182.0 182.0 185.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#3] audio-fft Cycles:10 Average:66.40 Median:60.50 stddev:12.01 (19.9%) stddev-sans-first:11.98 06:12:05 INFO - PROCESS | 2296 | Values: 78.0 61.0 59.0 89.0 60.0 60.0 58.0 58.0 83.0 58.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:79.00 stddev:11.05 (14.0%) stddev-sans-first:11.32 06:12:05 INFO - PROCESS | 2296 | Values: 86.0 72.0 92.0 70.0 68.0 69.0 91.0 68.0 70.0 93.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:1.90 (2.2%) stddev-sans-first:1.94 06:12:05 INFO - PROCESS | 2296 | Values: 88.0 85.0 88.0 86.0 87.0 84.0 90.0 85.0 88.0 85.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#6] imaging-darkroom Cycles:10 Average:116.50 Median:95.00 stddev:29.61 (31.2%) stddev-sans-first:30.37 06:12:05 INFO - PROCESS | 2296 | Values: 95.0 95.0 95.0 110.0 162.0 101.0 151.0 163.0 97.0 96.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#7] imaging-desaturate Cycles:10 Average:93.00 Median:83.50 stddev:17.93 (21.5%) stddev-sans-first:18.57 06:12:05 INFO - PROCESS | 2296 | Values: 82.0 89.0 123.0 82.0 84.0 82.0 86.0 83.0 89.0 130.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.15 (2.1%) stddev-sans-first:0.50 06:12:05 INFO - PROCESS | 2296 | Values: 57.0 53.0 53.0 54.0 54.0 54.0 54.0 54.0 53.0 54.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#9] json-stringify-tinderbox Cycles:10 Average:76.00 Median:78.00 stddev:4.08 (5.2%) stddev-sans-first:3.46 06:12:05 INFO - PROCESS | 2296 | Values: 69.0 77.0 70.0 80.0 81.0 75.0 74.0 79.0 79.0 76.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:87.50 stddev:8.40 (9.6%) stddev-sans-first:8.77 06:12:05 INFO - PROCESS | 2296 | Values: 88.0 86.0 85.0 90.0 86.0 87.0 98.0 108.0 105.0 89.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#11] stanford-crypto-ccm Cycles:10 Average:157.30 Median:151.00 stddev:20.19 (13.4%) stddev-sans-first:21.13 06:12:05 INFO - PROCESS | 2296 | Values: 148.0 151.0 159.0 163.0 150.0 149.0 150.0 151.0 212.0 140.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:174.50 stddev:2.38 (1.4%) stddev-sans-first:2.50 06:12:05 INFO - PROCESS | 2296 | Values: 173.0 176.0 172.0 172.0 176.0 178.0 176.0 171.0 173.0 172.0 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - PROCESS | 2296 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:74.00 stddev:8.83 (11.9%) stddev-sans-first:9.16 06:12:05 INFO - PROCESS | 2296 | Values: 68.0 72.0 76.0 88.0 70.0 61.0 82.0 63.0 70.0 83.0 06:12:05 INFO - PROCESS | 2296 | -------- Summary: end -------- 06:12:05 INFO - PROCESS | 2296 | 06:12:05 INFO - TEST-INFO | 2296: exit 0 06:12:05 INFO - TEST-OK | kraken | took 60221ms 06:12:05 INFO - SUITE-END | took 1178s 06:12:06 INFO - Completed test suite (00:19:38) 06:12:06 INFO - javascript benchmark 06:12:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2899.7970142090057, 3243.2054146734054, 3253.544503769472, 3275.5086736989465, 3278.639349671536, 5465.562754979597, 5479.479449452296, 5576.275084239043, 5589.189567512713, 5606.831795046145, 5533.3083364579425, 5593.440655934404, 5682.602217844753, 5733.856653583663, 5748.109043098327, 4894.486078961711, 5108.693478391308, 5143.971205758863, 5155.3040339554245, 5193.766280517386, 5086.364204474441, 5125.718085505296, 5193.766280517386, 5216.295800067, 5225.529702326789, 5183.015227106837, 5183.507566781187, 5226.216067589873, 5242.108841496935, 5261.1056120459425, 5034.01836641855, 5095.439501654815, 5100.7449627518845, 5123.462036486189, 5130.846074617767, 5065.037642847848, 5074.213496907985, 5098.260752190911, 5101.387833459971, 5123.769430375604, 4613.35413042171, 5624.381318055051, 5652.0, 5660.886782264372, 5701.315842098933, 5254.7651301944015, 6048.123022161762, 6143.754249830002, 6159.38406159385, 6166.537509686791, 6061.575689701723, 6120.990036643949, 6125.264968203799, 6204.875902481925, 6227.470664993481, 5645.435456454322, 5671.404502527216, 5713.714314284269, 5714.428557144252, 5778.855528611818, 6267.4986001119805, 6300.692136628215, 6352.078948552434, 6360.0, 6363.172787537591, 6034.909476357858, 6050.818475445743, 6053.303870054912, 6055.8486037848525, 6063.727132279058, 6022.759089636365, 6062.605930614496, 6082.756689732361, 6126.754929802847, 6143.416375444325, 5988.580799344004, 6014.188084608521, 6018.21763170785, 6025.6083354581815, 6031.638101713911, 6001.399860013964, 6041.969790151109, 6058.182145410313, 6060.757569697163, 6079.361667024898, 4779.976100119477, 4820.542048505386, 4827.927581086216, 4859.732714700725, 4860.246661767432, 3355.4463513520486, 4269.722468039629, 4279.507856596469, 4376.540463251313, 4446.666500012512, 5706.258186435737, 5709.600327977002, 5732.197492351074, 5736.6558006519745, 5750.396208398141, 6036.094585812076, 6117.204763380732, 6129.816105516842, 6233.812985610439, 6242.188515492957, 5691.459311365414, 5701.173329867146, 5710.800121995793, 5761.971190144022, 5801.245838041028, 5321.122014867581, 5322.973385133049, 5326.36083669962, 5330.760115794798, 5346.4386239445075, 6177.7837775677635, 6325.240971083499, 6347.143135676624, 6363.90454143179, 6376.458001069961, 6000.489958353589, 6106.7640410362355, 6111.287884814121, 6113.29387775398, 6173.506119510518, 5188.325517682677, 6190.659513726608, 6304.432601065926, 6331.588446750947, 6332.176817013758, 6055.1220073090035, 6061.605995610271, 6113.052476866093, 6121.632702037892, 6127.111568822585, 5960.5231581474245, 6077.574569780073, 6144.41628045335, 6223.782167624111, 6226.501879849692, 5278.680329917521, 5897.203877476486, 5943.375945525658, 6019.909701354396, 6021.849453763691, 5967.791127310524, 5981.581289309706, 5993.460588547052, 5998.550108741774, 6007.97864363068, 5538.556915446674, 5718.370979192202, 5767.625104368202, 5808.651480911159, 5851.239338885917, 5931.525477961839, 5957.970210148921, 5984.581079324405, 6039.4262545058145, 6090.025595904634, 4891.31521586971, 5850.824475265749, 5853.151293062568, 5886.23478947734, 5923.614965027259], "value": 5583.291436304084, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.5647276269451, 484.85090335903914, 502.4573460662485, 509.6407033041705, 548.8446819445071, 29446.558301625468, 30993.225169370777, 31011.0, 31016.53475197874, 31093.75624975001, 40276.59723402765, 40978.59021409785, 41024.794876025655, 42696.57303426965, 42718.57281427185, 8047.315978141865, 8836.337274704407, 9384.906150938472, 9385.155336019758, 9395.060493950585, 9130.589123489426, 9229.0, 9232.630694772235, 9238.39950403225, 9264.6294148234, 9204.40171388861, 9243.907560924406, 9247.352685312031, 9254.583543740548, 9330.440173589606, 9184.8622270666, 9230.261579073658, 9230.492322922237, 9248.26013918885, 9271.629134834599, 9104.726858194199, 9194.0, 9263.861042084374, 9266.814663706687, 9299.674511392137, 35606.863588184795, 38097.0, 38397.424038639445, 38522.0, 38543.0, 2813.4513769814894, 3238.4008958342547, 3382.2559037012065, 3437.432823584106, 3549.5563054618174, 28336.0, 29942.55086173709, 29980.85009574938, 29999.700002999907, 30011.39977200444, 3097.8141311521154, 3248.1717162123746, 3275.2630658101734, 3350.832458377096, 3356.043527594624, 478279.0, 479721.0, 480065.0, 481207.9999999965, 490706.0, 58021.0, 58361.0, 58450.0, 58469.70765146147, 58612.0, 61428.69285653633, 61487.38512614906, 61538.69230653907, 61603.0, 61642.691786541676, 13173.868261317455, 15401.768973465407, 15725.292361843745, 15733.527994160193, 15768.448104316523, 11983.34091624969, 12092.214006089576, 12112.757744845054, 12119.575814846614, 12121.090918181171, 14263.928680356532, 17686.823131768775, 17942.873139177802, 18045.007524586275, 18069.54826129331, 6092.0, 7365.0, 7461.888071678929, 7502.774916752506, 7512.511686740454, 876.9561521923878, 993.3841018568389, 1000.3197825478599, 1005.2257765044702, 1018.1752780248023, 26863.328416789347, 28877.0, 29270.414591708046, 29546.704532954824, 29552.96564620228, 927.9211267042242, 1033.4574348467115, 1054.0, 1078.5955266774959, 1130.6551501791869, 12446.626601201979, 12764.489420423264, 14325.6418589534, 14836.0, 15239.933204675788, 659074.0, 664695.0, 666889.0, 667544.0, 674487.0, 51215.48784512182, 51219.48780512222, 51879.0, 51900.74049629803, 51915.740421297654, 1193.8686744458103, 1206.493272825433, 1316.605018494467, 1351.0812647399666, 1368.2201145347256, 23974.880125599262, 26426.867865660548, 28872.845086196317, 29414.264643384086, 29492.387562271706, 46333.53666463292, 46744.766276169765, 46793.53206467892, 46850.76574617105, 46903.76548117238, 517123.0, 518496.0, 518574.0, 518793.0, 518907.0, 24327.905244264406, 42071.57928420677, 42439.94817103484, 43684.78157609192, 43938.0, 44161.33757993569, 44372.778136110406, 44519.0, 46134.76932615315, 46494.767526162155, 37747.81126094462, 39114.2177156461, 39972.800136000296, 40220.59779402168, 41569.79215103905, 38142.85071515421, 38182.0, 38265.617343826205, 39566.40650390189, 39855.0, 37689.0, 38517.42223866588, 38794.0, 38865.417018745306, 38869.0, 25089.24732258035, 26090.0, 33788.43001639085, 33946.32107357888, 34256.82871585626, 25077.122300719388, 25123.869425876244, 25275.49449011046, 25667.35831604225, 25733.613995790452], "value": 19322.725158775873, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.89317078778606, 361.82270687363194, 371.37423441501073, 377.7487970499618, 403.75572778469024, 14650.560483185494, 14918.0, 15823.287952042172, 16806.32774689011, 19993.60012799743, 19753.0, 19757.506062348413, 20197.798022019775, 20315.796842031574, 20598.0, 5700.087985922243, 5996.070609055581, 6003.099535069731, 6004.879902401949, 6016.699165041752, 4687.2969054641735, 5886.293644762634, 5919.112133180036, 5930.4366085222055, 5935.525157987372, 5588.357338906017, 5851.736671849756, 5871.266091738533, 5881.588288819784, 5897.5550990007505, 5685.800996965107, 5805.0, 5811.0, 5813.040848260032, 5815.098659707731, 4837.0, 5081.314022606938, 5095.312132862054, 5098.4646612105935, 5103.770330335144, 25481.0, 25502.489950201078, 25661.0, 25925.092621758336, 25965.0, 2247.3370355745096, 2308.122913292938, 2341.7072865891764, 2352.2472808701223, 2961.570572267009, 14831.258437078102, 14960.251987400696, 15109.47116850905, 15558.144302063381, 15707.978981366288, 1809.782826060868, 1827.2416946967126, 1879.7462342583713, 1897.9240830366768, 1939.2630800295938, 114478.0, 117308.0, 118681.0, 118786.0, 124442.37778811048, 10838.78322433547, 11403.201775875608, 11406.771864562828, 11449.198556100993, 11545.422728863523, 14193.148411095368, 14238.077859489043, 14248.501302454571, 14258.287085645676, 14301.78547321791, 6850.314968503109, 7958.801029974181, 8256.876146857801, 8394.11861754507, 8409.266988228728, 8089.110197878229, 8095.3523718102415, 8130.715424960216, 8311.210435008665, 8350.290225330793, 4437.489688685778, 4444.8444304449185, 4474.328850672425, 4483.50681425043, 4521.208788462006, 1749.9387521436688, 1781.7199781632987, 1793.748875157479, 1837.3477415517566, 1891.754071970635, 791.3036527855451, 876.7282142535835, 883.6597909804772, 926.7497775600685, 931.8741969834122, 19695.60608787816, 20350.79649203489, 20693.275735349493, 20695.79304206939, 20752.06615702297, 783.1854870934164, 884.398608945923, 890.4479222881855, 917.3853518142861, 920.0753242990752, 8666.78333041679, 10551.788964220672, 11048.889511104946, 11122.22144449897, 11164.497597608128, 595765.0, 598660.0, 600837.0, 614564.0, 615866.3653453902, 125934.0, 130212.72340957381, 131702.68297316905, 133172.0, 133744.0, 942.5758408716242, 944.3248077624531, 978.6085565773748, 979.6962941488162, 980.4656462228103, 18372.0, 18467.445976620722, 18541.0, 18599.90700046491, 18643.533911652317, 46376.608701739, 48923.755381222865, 52570.9485810274, 52618.73690631522, 53160.468395315555, 76295.0, 77038.0, 77108.0, 77380.61309693416, 79799.88420599392, 6784.966075169593, 7921.1682773307975, 7990.520568765893, 8095.959520202361, 8429.24136827664, 11901.553938740666, 12580.559680411141, 12705.237685738884, 12877.356132193165, 12962.027847911255, 11116.499757511094, 11211.943940280247, 11246.987771100641, 11260.38067906273, 11456.968872801488, 8257.091719910924, 10844.566217351217, 10955.83566246523, 10999.61501347449, 11434.42827858587, 11167.38579378142, 11317.547298107984, 11555.42222888869, 11622.84977279206, 11630.360330181922, 8080.757577272691, 8113.432059755761, 8155.184481551797, 8174.918250817654, 8199.196176841086, 5464.945350546603, 8279.917200827915, 11071.72320691989, 11336.263142896016, 11464.31214127155], "value": 9432.965433981683, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1281.135233717241, 1467.7798330250464, 1602.719524083286, 1761.920713567891, 1821.6174603333307, 6645.634490103049, 7072.646367681615, 7119.537230080041, 8386.790330241747, 8429.283510901581, 7399.149097853749, 7689.384849212063, 7700.807479813007, 8058.63736131874, 8308.169183081689, 2284.006457191123, 2313.3869524576003, 2316.721993360799, 2319.3042087373806, 2321.698179236697, 2278.214016164429, 2310.1221535816367, 2324.7791459811374, 2329.033451117784, 2335.602947498929, 2286.2683941138844, 2292.839501234914, 2309.2956648222284, 2321.8258630602686, 2324.9070037198494, 2282.7032485776745, 2287.542491501698, 2308.7806658367426, 2312.7340355859124, 2316.7567405422355, 2299.9195028173935, 2322.047960336254, 2324.6280595104704, 2325.3489023073566, 2332.090484710964, 8337.416380853343, 8375.49747015175, 8443.873341899876, 8444.662213511452, 8470.872936905951, 5025.522575355335, 5192.896142077175, 5196.662216955886, 5198.714070726109, 5202.4277329493725, 3132.1856317357424, 3240.3519296140867, 3499.7725147865563, 3505.456654218575, 3505.964940350615, 1025.4308858583483, 1034.384541197986, 1046.7383154211448, 1079.0881704959297, 1081.2701426537055, 10894.400807955559, 11005.339679619168, 11046.005859472685, 11060.281081729661, 11062.05972492339, 4001.9399709004388, 4398.494173170063, 4469.396631454777, 4646.581807637262, 4659.743714095689, 9397.01331360211, 9404.24766018717, 9404.965453800076, 9406.48264345454, 9412.15290623847, 5167.354080739908, 5170.405403378584, 5177.301064356264, 5186.0, 5203.271541984125, 4272.124214536075, 4406.1187762447635, 4584.0, 4709.7174169549935, 4754.23932170851, 3624.4200927851944, 3690.040589446763, 3740.9625903741157, 3790.0903783092413, 3794.29805485986, 1026.779242462874, 1224.2776761710634, 1230.4647478346947, 1243.6206956878239, 1248.8501379834297, 728.1262485018, 813.6663967773185, 881.453498830729, 883.6774577279247, 922.1838672774509, 5527.336719593594, 5767.942320576741, 5774.393688662714, 5891.587588868738, 5989.760409583655, 868.96524139035, 890.0165317324393, 917.2203626917201, 918.8851393575803, 929.5212965322864, 3788.8105594720155, 3806.4996080663177, 3879.3599056155417, 3909.139989202371, 3910.2570511602703, 9941.105300522988, 10277.640282590073, 10282.485875706185, 10286.948565257126, 10292.845607315896, 10169.593216271414, 10181.083702466814, 10240.283180177315, 10257.948710256402, 10261.794764104825, 7775.611219439005, 7879.803004924923, 7884.724034658874, 7913.0, 7916.960415197887, 4375.868723938287, 5535.640183388067, 5543.530964294546, 5613.859653508532, 5634.718264086698, 8766.780830479289, 9204.033576474374, 9417.811643767223, 9436.245100392089, 9525.714228573153, 9438.764030899009, 9504.144626983605, 9524.571394287135, 9538.570764315484, 9661.323707340418, 5509.47659972302, 5558.360788509373, 6395.584287021329, 6395.776147834804, 6655.001749737385, 7698.807529811799, 7931.682732690628, 7952.204779522001, 8026.354597307654, 8038.236367545074, 6978.860422791618, 7060.011598376334, 7092.503524753218, 7094.219635839951, 7210.459215558749, 6849.0, 6989.790306290819, 7012.789616311519, 7060.929390706233, 7095.467839911924, 6593.0, 6631.204255489372, 6709.7651582194385, 6717.261101278953, 6717.596944183365, 4773.952260477351, 4818.71087734737, 4864.464908860094, 4884.5603895649565, 4980.8754781130765, 4694.319323697976, 4802.639802014793, 4909.214525675875, 4940.530649588283, 4964.553190212754], "value": 4371.968442284271, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.6941134324793, 300.896190814169, 303.10432671455845, 315.0689711901332, 316.0975415189632, 4692.929606055912, 5256.554447526932, 5323.041852466559, 5342.332208473941, 5407.702576358299, 4293.914121717564, 4966.751662416874, 5121.257417674435, 5177.404598471167, 5305.204219367097, 3364.1421437533395, 3946.861859834906, 3967.02807812085, 3985.581513941038, 3995.60043995601, 3014.849257537125, 3394.321135772843, 3850.593379138039, 3961.039285553655, 4057.0871553900433, 3232.660570640084, 3856.093817952779, 3971.682265418774, 3999.8200530843324, 4011.8395264189544, 3467.254540273828, 3984.087637933779, 3987.2089537323845, 3995.9400608990886, 3999.6400323970984, 2911.199420159452, 3358.983205083983, 3477.7653932853727, 3486.041338631871, 3502.807345595991, 4941.22116038226, 5102.923456148161, 5185.977468787176, 5195.324607801, 5201.167813149916, 3768.7361884668085, 4389.193832753131, 4526.072155208208, 4556.863294101182, 4596.54034596541, 4895.209730453643, 4906.754662266872, 5316.341829085457, 5550.611457197998, 5554.555995362028, 3200.487921932503, 3769.8869033929027, 3771.6605505504363, 3827.9043023924346, 3836.079340958178, 4821.638377121695, 5610.747516361774, 5781.450762177545, 5785.971070144665, 5826.329972053183, 3833.9041523961564, 3840.2895464339185, 3847.115163512408, 3849.480320156799, 3849.768074216224, 3343.5151902973935, 3480.338735640271, 3753.7237339304766, 3934.5475270344277, 3955.7626542407547, 4156.8129434175535, 4326.437563116775, 4367.825286988548, 4587.362266696663, 4610.700304480198, 4709.434867815884, 5557.6109672323355, 5562.860928476821, 5622.606417550732, 5629.971850140805, 1078.7572796120967, 1169.3042639629407, 1261.8864302212662, 1264.3488603369271, 1273.3887733887786, 698.7135274537416, 796.7012370361115, 797.2306724011357, 844.6738620365967, 859.3769517100177, 4238.664820581507, 4798.352222449925, 4950.0, 4954.430240522314, 4970.732463221928, 4689.116193540948, 5343.839684809462, 5460.290162278958, 5461.563074953994, 5474.698891560923, 4905.166121759309, 4908.6073114151495, 4957.405111386656, 5333.346662534626, 5385.973070134624, 2432.647266146399, 2757.310672331917, 2850.389529915591, 2852.8573571321767, 2893.0327377383583, 4784.68899521537, 4851.781669824866, 5574.470425309589, 5596.244506991586, 5641.774329026793, 4436.0, 5284.471552844761, 5290.894182116336, 5545.947999240227, 5586.217929489874, 4890.820966371815, 4997.775100120431, 5202.401723801736, 5525.668459892419, 5700.287464066992, 4407.603315701474, 4830.396200474941, 4845.55299573713, 4858.708477491362, 5401.6495876030995, 5209.577546440772, 5267.868303292448, 5336.199570064521, 5400.486953739389, 5434.3750468695025, 4988.825391111293, 5110.8756073663735, 5288.545649946142, 5453.863653408696, 5565.912782706485, 3106.8601912914423, 3148.874045038173, 3445.87939422119, 3464.8960531184107, 3634.436662317345, 5324.866878328073, 5621.454100122499, 5781.884362312814, 5790.536757059509, 5797.826065218051, 4420.823167073409, 5232.869178270422, 5354.464553544614, 5578.414266501959, 5616.66300022, 4956.3396262252545, 5260.658057226268, 5583.134614134816, 5599.348192283204, 5684.289463817023, 5048.747562621781, 5257.947420525898, 5522.22688823573, 5523.558115350682, 5547.694876781816, 5462.415899389292, 5522.901297506922, 5528.751206195811, 5574.609777315549, 5642.633228840113, 5185.833187532926, 5257.342832145981, 5269.314989051399, 5445.618806683494, 5494.0], "value": 3934.9632227669135, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.8462538241588, 725.6009194942784, 743.788020414182, 770.1989930472307, 777.9649915753797, 14259.857401425983, 14667.046641968265, 14695.0, 14697.926510367446, 14703.117812931217, 13213.075084744072, 13341.866581334183, 13344.666383340422, 13345.599632011055, 13349.733005339911, 669.3707914560298, 691.87892118879, 698.3365802487632, 829.3489610655637, 854.7734850264685, 839.1524560194217, 858.8797568340437, 859.6174702257498, 861.8577934640776, 863.3783675753479, 901.3330135699567, 901.589776651622, 903.941243819153, 906.7189171356868, 907.5235501361805, 697.6267696782197, 717.8026042838209, 868.8349213649386, 894.9931327256836, 896.2023798819056, 665.7436886798569, 667.3760034367882, 667.6461475418037, 668.0813880913744, 669.2270427656016, 14305.85694143066, 14310.069845460011, 14326.928365358212, 14346.282685865664, 14360.712785744226, 10964.671059868217, 13079.346032698328, 13194.274314912676, 13277.867221327857, 13424.060315777899, 10197.0, 12152.817707734392, 12251.877481225163, 12313.44589493475, 12377.133600647958, 4394.78026098697, 4396.450443694538, 4419.447569053868, 4439.356293337481, 4496.527864574238, 100004.0, 100204.0, 100967.0, 101637.0, 104213.4789326056, 10464.372137671611, 10485.0, 10492.580296788043, 10533.157347412189, 10909.454527273605, 20303.796962030487, 20310.22709663228, 20355.185792568132, 20361.083751230934, 20378.89810550938, 7420.666070026862, 7422.183559808417, 7474.476786624884, 7562.962185189039, 7712.305892469704, 4256.106217694319, 4300.1399720056115, 4311.461067366579, 4368.934465983013, 4450.287953927421, 3166.5408515765544, 3264.135004223883, 3268.803871767654, 3269.035634487784, 3288.177955511122, 596.263614436171, 601.9398060193946, 605.5367643752533, 606.0, 606.9939300607026, 479.5635971266131, 539.3527766679999, 559.8516393155817, 593.8248216776061, 609.338867328953, 8016.238457346542, 8495.66017359311, 8581.184787445183, 8697.782555436164, 8699.173578510032, 510.85696005118626, 547.026293198108, 555.9492559063374, 597.7071235094772, 631.43486579512, 3778.3576791945434, 3885.9702178922607, 3969.364901615728, 4191.517975432804, 4194.119234960632, 69714.65142674356, 94629.52685236529, 103656.0, 106598.0, 112378.0, 74479.62760186165, 85344.86637834104, 97300.0, 99628.0, 99799.0, 65330.67334663296, 89845.65231521532, 90574.64138037803, 90885.0, 90909.09090909007, 7168.103987001625, 8064.919350806417, 8186.549739764132, 8374.120717324593, 8407.789805254673, 50679.74660126676, 55066.898662027335, 56037.0, 56269.43730562642, 57677.501924749515, 63928.721425572156, 66983.32541686497, 69463.30536694569, 69936.30063699298, 72929.0, 11971.341576213392, 12102.792759918804, 12123.696907577381, 12299.87700123023, 12434.81347779766, 13836.792448113085, 13904.791428128385, 14082.366293517021, 14347.852171826438, 14627.0, 12621.495140194289, 12981.545645902348, 13072.803907941197, 13119.737605248032, 13424.664383390493, 12662.620121396372, 12837.101402901708, 13201.801972970605, 13248.470061197444, 13681.042327037012, 9516.238700903772, 11129.220954533104, 11147.0, 11330.94334528322, 11443.42782860837, 9349.813003739751, 9364.157225849705, 9709.223262139154, 9729.0, 9850.507474626096, 9387.014363491735, 9643.228541716506, 9701.175400091071, 9863.408195508293, 10944.343339399662], "value": 7113.5602454003365, "unit": "score"}], "name": "dromaeo_css", "value": 7066.86274964599, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 115.0, 121.0, 166.0, 154.0, 114.0, 113.0, 113.0, 113.0, 113.0], "value": 123.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 111.0, 147.0, 111.0, 110.0, 113.0, 111.0, 137.0, 126.0], "value": 122.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 184.0, 183.0, 188.0, 182.0, 184.0, 182.0, 182.0, 185.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 61.0, 59.0, 89.0, 60.0, 60.0, 58.0, 58.0, 83.0, 58.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 92.0, 70.0, 68.0, 69.0, 91.0, 68.0, 70.0, 93.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 87.0, 84.0, 90.0, 85.0, 88.0, 85.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 110.0, 162.0, 101.0, 151.0, 163.0, 97.0, 96.0], "value": 116.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 89.0, 123.0, 82.0, 84.0, 82.0, 86.0, 83.0, 89.0, 130.0], "value": 93.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0, 53.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 70.0, 80.0, 81.0, 75.0, 74.0, 79.0, 79.0, 76.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 85.0, 90.0, 86.0, 87.0, 98.0, 108.0, 105.0, 89.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 151.0, 159.0, 163.0, 150.0, 149.0, 150.0, 151.0, 212.0, 140.0], "value": 157.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 176.0, 172.0, 172.0, 176.0, 178.0, 176.0, 171.0, 173.0, 172.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 76.0, 88.0, 70.0, 61.0, 82.0, 63.0, 70.0, 83.0], "value": 73.3, "unit": "score"}], "name": "kraken", "value": 1497.1, "alertThreshold": 2.0}]} 06:12:06 INFO - Return code: 0 06:12:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:12:06 INFO - # TBPL SUCCESS # 06:12:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:12:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:12:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:12:06 INFO - 2016-11-09 06:12:06.279 screenresolution[2300:27467] starting screenresolution argv=screenresolution get 06:12:06 INFO - 2016-11-09 06:12:06.284 screenresolution[2300:27467] Display 0: 1600x1200x32@60 06:12:06 INFO - 2016-11-09 06:12:06.290 screenresolution[2301:27470] starting screenresolution argv=screenresolution list 06:12:06 INFO - Available Modes on Display 0 06:12:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:12:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:12:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:12:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:12:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:12:06 INFO - Intel Iris: 06:12:06 INFO - Chipset Model: Intel Iris 06:12:06 INFO - Type: GPU 06:12:06 INFO - Bus: Built-In 06:12:06 INFO - VRAM (Dynamic, Max): 1536 MB 06:12:06 INFO - Vendor: Intel (0x8086) 06:12:06 INFO - Device ID: 0x0a2e 06:12:06 INFO - Revision ID: 0x0009 06:12:06 INFO - Displays: 06:12:06 INFO - Display: 06:12:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:12:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:12:06 INFO - Display Serial Number: 1600x1200 60 06:12:06 INFO - Main Display: Yes 06:12:06 INFO - Mirror: Off 06:12:06 INFO - Online: Yes 06:12:06 INFO - Rotation: Supported 06:12:06 INFO - Return code: 0 06:12:06 INFO - Running post-action listener: _resource_record_post_action 06:12:06 INFO - [mozharness: 2016-11-09 14:12:06.535600Z] Finished run-tests step (success) 06:12:06 INFO - Running post-run listener: _resource_record_post_run 06:12:06 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 495616; Write bytes: 702398464; Read time: 3; Write time: 1942 06:12:06 INFO - TinderboxPrint: CPU usage
53.8% 06:12:06 INFO - TinderboxPrint: I/O read bytes / time
495,616 / 3 06:12:06 INFO - TinderboxPrint: I/O write bytes / time
702,398,464 / 1,942 06:12:06 INFO - TinderboxPrint: CPU idle
2,242.3 (46.2%) 06:12:06 INFO - TinderboxPrint: CPU system
973.2 (20.1%) 06:12:06 INFO - TinderboxPrint: CPU user
1,638.2 (33.8%) 06:12:06 INFO - TinderboxPrint: Swap in / out
403,197,952 / 0 06:12:06 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398504448; Write bytes: 393643008; Read time: 29703; Write time: 800 06:12:06 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 495616; Write bytes: 294403072; Read time: 3; Write time: 1099 06:12:07 INFO - Running post-run listener: _upload_blobber_files 06:12:07 INFO - Blob upload gear active. 06:12:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:12:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:12:07 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:12:07 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:12:07 INFO - (blobuploader) - INFO - Open directory for files ... 06:12:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:12:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:12:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:08 INFO - (blobuploader) - INFO - Done attempting. 06:12:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:12:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:12:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:09 INFO - (blobuploader) - INFO - Done attempting. 06:12:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:12:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:12:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:11 INFO - (blobuploader) - INFO - Done attempting. 06:12:11 INFO - (blobuploader) - INFO - Iteration through files over. 06:12:11 INFO - Return code: 0 06:12:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:12:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:12:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6bb8e19e732f9ff5d8dbb8db37510ece3cc7d9e746d4f0b5f4e021eaede016048ed6e49995e84d2a3a150396cbb473046606d6e8d937813e439f82f2991aa29c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d8961c9b630ad20bc192e21bd1db7a41d433db591aafc6b51c69329858913edc2f28834d10da188c2771254f929156228f07b6a11f3b77f722434ec692223b50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/5f4b4b4ee192c536419fc5ab053f51d65dab13ab3f17caeac6692bd47fb5f21851f4e7ff7b49e3156e020fe3814d6017b19539d3de8b8d75677ec30935dd3ab1"} 06:12:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:12:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:12:11 INFO - Contents: 06:12:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6bb8e19e732f9ff5d8dbb8db37510ece3cc7d9e746d4f0b5f4e021eaede016048ed6e49995e84d2a3a150396cbb473046606d6e8d937813e439f82f2991aa29c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d8961c9b630ad20bc192e21bd1db7a41d433db591aafc6b51c69329858913edc2f28834d10da188c2771254f929156228f07b6a11f3b77f722434ec692223b50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/5f4b4b4ee192c536419fc5ab053f51d65dab13ab3f17caeac6692bd47fb5f21851f4e7ff7b49e3156e020fe3814d6017b19539d3de8b8d75677ec30935dd3ab1"} 06:12:11 INFO - Running post-run listener: copy_logs_to_upload_dir 06:12:11 INFO - Copying logs to upload dir... 06:12:11 INFO - mkdir: /builds/slave/test/build/upload/logs 06:12:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.912988 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-09 06:12:11.693912) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-09 06:12:11.697416) ========= 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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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/Larch/sha512/6bb8e19e732f9ff5d8dbb8db37510ece3cc7d9e746d4f0b5f4e021eaede016048ed6e49995e84d2a3a150396cbb473046606d6e8d937813e439f82f2991aa29c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d8961c9b630ad20bc192e21bd1db7a41d433db591aafc6b51c69329858913edc2f28834d10da188c2771254f929156228f07b6a11f3b77f722434ec692223b50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/5f4b4b4ee192c536419fc5ab053f51d65dab13ab3f17caeac6692bd47fb5f21851f4e7ff7b49e3156e020fe3814d6017b19539d3de8b8d75677ec30935dd3ab1"} build_url:https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010990 build_url: 'https://queue.taskcluster.net/v1/task/RHTtiONrTIGO0hNLjJ7mzw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6bb8e19e732f9ff5d8dbb8db37510ece3cc7d9e746d4f0b5f4e021eaede016048ed6e49995e84d2a3a150396cbb473046606d6e8d937813e439f82f2991aa29c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d8961c9b630ad20bc192e21bd1db7a41d433db591aafc6b51c69329858913edc2f28834d10da188c2771254f929156228f07b6a11f3b77f722434ec692223b50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/5f4b4b4ee192c536419fc5ab053f51d65dab13ab3f17caeac6692bd47fb5f21851f4e7ff7b49e3156e020fe3814d6017b19539d3de8b8d75677ec30935dd3ab1"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-09 06:12:11.734366) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 06:12:11.735341) ========= 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.UpzPBFzIsL/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.shnW5AXBhJ/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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.005226 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 06:12:11.787153) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-09 06:12:11.787881) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-09 06:12:11.788624) ========= ========= Total master_lag: 0.19 =========