builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0221 starttime: 1472540138.22 results: success (0) buildid: 20160829213422 builduid: f0faef083a7a4bdf9f0a42a6cb8003f9 revision: 26e22af660e543ebb69930f082188b69ec756185 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:38.220247) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:38.220822) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:38.221103) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:38.291038) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:38.291397) ========= 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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-08-29 23:55:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-08-29 23:55:38 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.168960 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:38.520508) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:38.520816) ========= 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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.025335 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:38.614645) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 23:55:38.614953) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 26e22af660e543ebb69930f082188b69ec756185 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 26e22af660e543ebb69930f082188b69ec756185 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cagBMjNEg6/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.dRnUOK0Dhe/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-08-29 23:55:38,695 truncating revision to first 12 chars 2016-08-29 23:55:38,695 Setting DEBUG logging. 2016-08-29 23:55:38,695 attempt 1/10 2016-08-29 23:55:38,695 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/26e22af660e5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 23:55:40,400 unpacking tar archive at: fx-team-26e22af660e5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.957999 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 23:55:40.595491) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:40.595800) ========= script_repo_revision: 26e22af660e543ebb69930f082188b69ec756185 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:40.596174) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:40.596455) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 23:55:40.614871) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-29 23:55:40.615140) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cagBMjNEg6/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.dRnUOK0Dhe/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 23:55:40 INFO - MultiFileLogger online at 20160829 23:55:40 in /builds/slave/test 23:55:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:55:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:55:40 INFO - {'append_to_log': False, 23:55:40 INFO - 'base_work_dir': '/builds/slave/test', 23:55:40 INFO - 'blob_upload_branch': 'Fx-Team', 23:55:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:55:40 INFO - 'branch': 'Fx-Team', 23:55:40 INFO - 'buildbot_json_path': 'buildprops.json', 23:55:40 INFO - 'config_files': ('talos/mac_config.py',), 23:55:40 INFO - 'default_actions': ('clobber', 23:55:40 INFO - 'read-buildbot-config', 23:55:40 INFO - 'download-and-extract', 23:55:40 INFO - 'populate-webroot', 23:55:40 INFO - 'create-virtualenv', 23:55:40 INFO - 'install', 23:55:40 INFO - 'run-tests'), 23:55:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:55:40 INFO - 'download_minidump_stackwalk': True, 23:55:40 INFO - 'download_symbols': 'ondemand', 23:55:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:55:40 INFO - 'tooltool.py': '/tools/tooltool.py', 23:55:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:55:40 INFO - '/tools/misc-python/virtualenv.py')}, 23:55:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:55:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:55:40 INFO - 'installer_path': 'installer.exe', 23:55:40 INFO - 'log_level': 'info', 23:55:40 INFO - 'log_name': 'talos', 23:55:40 INFO - 'log_to_console': True, 23:55:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:55:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:55:40 INFO - 'opt_config_files': (), 23:55:40 INFO - 'pip_index': False, 23:55:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:55:40 INFO - 'cmd': ('bash', 23:55:40 INFO - '-c', 23:55:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:55:40 INFO - 'enabled': True, 23:55:40 INFO - 'halt_on_failure': False, 23:55:40 INFO - 'name': 'check_screen_resolution'},), 23:55:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:55:40 INFO - 'cmd': ('bash', 23:55:40 INFO - '-c', 23:55:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:55:40 INFO - 'enabled': True, 23:55:40 INFO - 'halt_on_failure': False, 23:55:40 INFO - 'name': 'check_screen_resolution'},), 23:55:40 INFO - 'run_cmd_checks_enabled': True, 23:55:40 INFO - 'sps_profile': False, 23:55:40 INFO - 'sps_profile_interval': 0, 23:55:40 INFO - 'suite': 'dromaeojs', 23:55:40 INFO - 'system_bits': '32', 23:55:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:55:40 INFO - 'title': 't-yosemite-r7-0221', 23:55:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:55:40 INFO - 'use_talos_json': True, 23:55:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:55:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:55:40 INFO - 'work_dir': 'build'} 23:55:40 INFO - [mozharness: 2016-08-30 06:55:40.805687Z] Running clobber step. 23:55:40 INFO - Running pre-action listener: _resource_record_pre_action 23:55:40 INFO - Running main action method: clobber 23:55:40 INFO - rmtree: /builds/slave/test/build 23:55:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:55:42 INFO - Running post-action listener: _resource_record_post_action 23:55:42 INFO - [mozharness: 2016-08-30 06:55:42.942472Z] Finished clobber step (success) 23:55:42 INFO - [mozharness: 2016-08-30 06:55:42.942711Z] Running read-buildbot-config step. 23:55:42 INFO - Running pre-action listener: _resource_record_pre_action 23:55:42 INFO - Running main action method: read_buildbot_config 23:55:42 INFO - Using buildbot properties: 23:55:42 INFO - { 23:55:42 INFO - "project": "", 23:55:42 INFO - "product": "firefox", 23:55:42 INFO - "script_repo_revision": "production", 23:55:42 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 23:55:42 INFO - "repository": "", 23:55:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 23:55:42 INFO - "buildid": "20160829213422", 23:55:42 INFO - "pgo_build": "False", 23:55:42 INFO - "basedir": "/builds/slave/test", 23:55:42 INFO - "buildnumber": 128, 23:55:42 INFO - "slavename": "t-yosemite-r7-0221", 23:55:42 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 23:55:42 INFO - "platform": "yosemite_r7", 23:55:42 INFO - "scheduler": "tests-fx-team-macosx64-talos", 23:55:42 INFO - "branch": "fx-team", 23:55:42 INFO - "repo_path": "integration/fx-team", 23:55:42 INFO - "revision": "26e22af660e543ebb69930f082188b69ec756185", 23:55:42 INFO - "stage_platform": "macosx64", 23:55:42 INFO - "builduid": "f0faef083a7a4bdf9f0a42a6cb8003f9", 23:55:42 INFO - "slavebuilddir": "test" 23:55:42 INFO - } 23:55:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 23:55:42 INFO - Running post-action listener: _resource_record_post_action 23:55:42 INFO - [mozharness: 2016-08-30 06:55:42.948375Z] Finished read-buildbot-config step (success) 23:55:42 INFO - [mozharness: 2016-08-30 06:55:42.948603Z] Running download-and-extract step. 23:55:42 INFO - Running pre-action listener: _resource_record_pre_action 23:55:42 INFO - Running main action method: download_and_extract 23:55:42 INFO - mkdir: /builds/slave/test/build/tests 23:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:55:42 INFO - trying https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 23:55:42 INFO - Downloading https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 23:55:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 23:55:44 INFO - Downloaded 1199 bytes. 23:55:44 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 23:55:44 INFO - Using the following test package requirements: 23:55:44 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:55:44 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:55:44 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 23:55:44 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:55:44 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 23:55:44 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:55:44 INFO - u'jsshell-mac.zip'], 23:55:44 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:55:44 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 23:55:44 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:55:44 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:55:44 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 23:55:44 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:55:44 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 23:55:44 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:55:44 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 23:55:44 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:55:44 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 23:55:44 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:55:44 INFO - trying https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:55:44 INFO - Downloading https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:55:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:55:45 INFO - Downloaded 16380506 bytes. 23:55:45 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 23:55:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:55:52 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')]} 23:55:52 INFO - trying https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:55:52 INFO - Downloading https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:55:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:55:53 INFO - Downloaded 16380506 bytes. 23:55:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 23:55:59 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')]} 23:55:59 INFO - trying https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 23:55:59 INFO - Downloading https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 23:55:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:56:00 INFO - Downloaded 14413998 bytes. 23:56:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 23:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:00 INFO - trying https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:56:00 INFO - Downloading https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 23:56:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 23:56:03 INFO - Downloaded 111551627 bytes. 23:56:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:56:03 INFO - mkdir: /builds/slave/test/properties 23:56:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:56:03 INFO - Writing to file /builds/slave/test/properties/build_url 23:56:03 INFO - Contents: 23:56:03 INFO - build_url:https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:56:04 INFO - Running post-action listener: _resource_record_post_action 23:56:04 INFO - Running post-action listener: set_extra_try_arguments 23:56:04 INFO - [mozharness: 2016-08-30 06:56:04.229389Z] Finished download-and-extract step (success) 23:56:04 INFO - [mozharness: 2016-08-30 06:56:04.229506Z] Running populate-webroot step. 23:56:04 INFO - Running pre-action listener: _resource_record_pre_action 23:56:04 INFO - Running main action method: populate_webroot 23:56:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:56:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:56:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:56:04 INFO - '--tppagecycles', 23:56:04 INFO - '7'], 23:56:04 INFO - 'tests': ['tsvgm']}}, 23:56:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:56:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:56:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:56:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:56:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:56:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:56:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:56:04 INFO - 'talos_options': ['--disable-e10s'], 23:56:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:56:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:56:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:56:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:56:04 INFO - 'talos_options': ['--disable-e10s'], 23:56:04 INFO - 'tests': ['damp', 'tps']}, 23:56:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:56:04 INFO - 'tests': ['damp', 'tps']}, 23:56:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:56:04 INFO - 'tests': ['dromaeo_dom']}, 23:56:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:56:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:56:04 INFO - 'tests': ['basic_compositor_video']}, 23:56:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:56:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:56:04 INFO - 'tests': ['a11yr', 23:56:04 INFO - 'ts_paint', 23:56:04 INFO - 'tpaint', 23:56:04 INFO - 'sessionrestore', 23:56:04 INFO - 'sessionrestore_no_auto_restore', 23:56:04 INFO - 'tabpaint']}, 23:56:04 INFO - 'other-e10s': {'tests': ['a11yr', 23:56:04 INFO - 'ts_paint', 23:56:04 INFO - 'tpaint', 23:56:04 INFO - 'sessionrestore', 23:56:04 INFO - 'sessionrestore_no_auto_restore', 23:56:04 INFO - 'tabpaint']}, 23:56:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:56:04 INFO - 'ts_paint', 23:56:04 INFO - 'tpaint', 23:56:04 INFO - 'sessionrestore', 23:56:04 INFO - 'sessionrestore_no_auto_restore', 23:56:04 INFO - 'tabpaint']}, 23:56:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:56:04 INFO - 'ts_paint', 23:56:04 INFO - 'tpaint', 23:56:04 INFO - 'sessionrestore', 23:56:04 INFO - 'sessionrestore_no_auto_restore', 23:56:04 INFO - 'tabpaint']}, 23:56:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:56:04 INFO - 'tests': ['a11yr', 23:56:04 INFO - 'ts_paint', 23:56:04 INFO - 'tpaint', 23:56:04 INFO - 'sessionrestore', 23:56:04 INFO - 'sessionrestore_no_auto_restore', 23:56:04 INFO - 'tabpaint']}, 23:56:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:56:04 INFO - 'tests': ['a11yr', 23:56:04 INFO - 'ts_paint', 23:56:04 INFO - 'tpaint', 23:56:04 INFO - 'sessionrestore', 23:56:04 INFO - 'sessionrestore_no_auto_restore', 23:56:04 INFO - 'tabpaint']}, 23:56:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:56:04 INFO - 'tests': ['tsvgx', 23:56:04 INFO - 'tsvgr_opacity', 23:56:04 INFO - 'tart', 23:56:04 INFO - 'tscrollx', 23:56:04 INFO - 'cart']}, 23:56:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:56:04 INFO - 'tsvgr_opacity', 23:56:04 INFO - 'tart', 23:56:04 INFO - 'tscrollx', 23:56:04 INFO - 'cart']}, 23:56:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:56:04 INFO - 'talos_options': ['--disable-e10s'], 23:56:04 INFO - 'tests': ['tp5o']}, 23:56:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:56:04 INFO - 'tests': ['tp5o']}, 23:56:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:56:04 INFO - 'talos_options': ['--disable-e10s', 23:56:04 INFO - '--xperf_path', 23:56:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:56:04 INFO - 'tests': ['tp5n']}, 23:56:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:56:04 INFO - 'talos_options': ['--xperf_path', 23:56:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:56:04 INFO - 'tests': ['tp5n']}}, 23:56:04 INFO - 'talos.zip': {'path': '', 23:56:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:56:04 INFO - Running post-action listener: _resource_record_post_action 23:56:04 INFO - [mozharness: 2016-08-30 06:56:04.248240Z] Finished populate-webroot step (success) 23:56:04 INFO - [mozharness: 2016-08-30 06:56:04.248381Z] Running create-virtualenv step. 23:56:04 INFO - Running pre-action listener: _resource_record_pre_action 23:56:04 INFO - Running main action method: create_virtualenv 23:56:04 INFO - Creating virtualenv /builds/slave/test/build/venv 23:56:04 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 23:56:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:56:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:56:04 INFO - Using real prefix '/tools/python27' 23:56:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:56:05 INFO - Installing distribute.............................................................................................................................................................................................done. 23:56:07 INFO - Installing pip.................done. 23:56:07 INFO - Return code: 0 23:56:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d9f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f91ad30>, '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 0x10f67fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f912b20>, '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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 23:56:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:56:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cagBMjNEg6/Render', 23:56:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:56:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:56:07 INFO - 'HOME': '/Users/cltbld', 23:56:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:56:07 INFO - 'LOGNAME': 'cltbld', 23:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:56:07 INFO - 'MOZ_NO_REMOTE': '1', 23:56:07 INFO - 'NO_EM_RESTART': '1', 23:56:07 INFO - 'PAGER': '/bin/cat', 23:56:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:56:07 INFO - 'PWD': '/builds/slave/test', 23:56:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:56:07 INFO - 'SHELL': '/bin/bash', 23:56:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dRnUOK0Dhe/Listeners', 23:56:07 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 23:56:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:56:07 INFO - 'USER': 'cltbld', 23:56:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:56:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:56:07 INFO - 'XPC_FLAGS': '0x0', 23:56:07 INFO - 'XPC_SERVICE_NAME': '0', 23:56:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:56:08 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 23:56:08 INFO - Cleaning up... 23:56:08 INFO - Return code: 0 23:56:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:56:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:56:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f9b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d9f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f91ad30>, '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 0x10f67fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f912b20>, '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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 23:56:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:56:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cagBMjNEg6/Render', 23:56:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:56:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:56:08 INFO - 'HOME': '/Users/cltbld', 23:56:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:56:08 INFO - 'LOGNAME': 'cltbld', 23:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:56:08 INFO - 'MOZ_NO_REMOTE': '1', 23:56:08 INFO - 'NO_EM_RESTART': '1', 23:56:08 INFO - 'PAGER': '/bin/cat', 23:56:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:56:08 INFO - 'PWD': '/builds/slave/test', 23:56:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:56:08 INFO - 'SHELL': '/bin/bash', 23:56:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dRnUOK0Dhe/Listeners', 23:56:08 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 23:56:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:56:08 INFO - 'USER': 'cltbld', 23:56:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:56:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:56:08 INFO - 'XPC_FLAGS': '0x0', 23:56:08 INFO - 'XPC_SERVICE_NAME': '0', 23:56:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:56:08 INFO - Downloading/unpacking psutil>=3.1.1 23:56:08 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 23:56:08 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 23:56:08 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 23:56:08 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 23:56:08 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 23:56:08 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 23:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:56:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:56:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:56:12 INFO - Installing collected packages: psutil 23:56:12 INFO - Running setup.py install for psutil 23:56:12 INFO - building 'psutil._psutil_osx' extension 23:56:12 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 23:56:13 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 23:56:13 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 23:56:13 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 23:56:13 INFO - building 'psutil._psutil_posix' extension 23:56:13 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 23:56:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:56:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:56:13 INFO - ^ 23:56:13 INFO - 1 warning generated. 23:56:13 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 23:56:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:56:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:56:13 INFO - Successfully installed psutil 23:56:13 INFO - Cleaning up... 23:56:13 INFO - Return code: 0 23:56:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:56:13 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')]} 23:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:13 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')]} 23:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:13 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 0x10f9b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d9f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f91ad30>, '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 0x10f67fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f912b20>, '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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 23:56:13 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 23:56:13 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 23:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cagBMjNEg6/Render', 23:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:56:13 INFO - 'HOME': '/Users/cltbld', 23:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:56:13 INFO - 'LOGNAME': 'cltbld', 23:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:56:13 INFO - 'MOZ_NO_REMOTE': '1', 23:56:13 INFO - 'NO_EM_RESTART': '1', 23:56:13 INFO - 'PAGER': '/bin/cat', 23:56:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:56:13 INFO - 'PWD': '/builds/slave/test', 23:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:56:13 INFO - 'SHELL': '/bin/bash', 23:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dRnUOK0Dhe/Listeners', 23:56:13 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 23:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:56:13 INFO - 'USER': 'cltbld', 23:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:56:13 INFO - 'XPC_FLAGS': '0x0', 23:56:13 INFO - 'XPC_SERVICE_NAME': '0', 23:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:56:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:56:13 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 23:56:13 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 23:56:13 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 23:56:13 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 23:56:13 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 23:56:13 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 23:56:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:56:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:56:16 INFO - Installing collected packages: mozsystemmonitor 23:56:16 INFO - Running setup.py install for mozsystemmonitor 23:56:16 INFO - Successfully installed mozsystemmonitor 23:56:16 INFO - Cleaning up... 23:56:16 INFO - Return code: 0 23:56:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d9f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f91ad30>, '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 0x10f67fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f912b20>, '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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 23:56:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:56:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cagBMjNEg6/Render', 23:56:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:56:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:56:16 INFO - 'HOME': '/Users/cltbld', 23:56:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:56:16 INFO - 'LOGNAME': 'cltbld', 23:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:56:16 INFO - 'MOZ_NO_REMOTE': '1', 23:56:16 INFO - 'NO_EM_RESTART': '1', 23:56:16 INFO - 'PAGER': '/bin/cat', 23:56:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:56:16 INFO - 'PWD': '/builds/slave/test', 23:56:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:56:16 INFO - 'SHELL': '/bin/bash', 23:56:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dRnUOK0Dhe/Listeners', 23:56:16 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 23:56:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:56:16 INFO - 'USER': 'cltbld', 23:56:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:56:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:56:16 INFO - 'XPC_FLAGS': '0x0', 23:56:16 INFO - 'XPC_SERVICE_NAME': '0', 23:56:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:56:16 INFO - Downloading/unpacking jsonschema==2.5.1 23:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:56:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:19 INFO - Downloading functools32-3.2.3-2.tar.gz 23:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:56:19 INFO - warning: no files found matching '*.txt' 23:56:19 INFO - no previously-included directories found matching 'build' 23:56:19 INFO - no previously-included directories found matching 'dist' 23:56:19 INFO - no previously-included directories found matching '.git*' 23:56:19 INFO - Installing collected packages: jsonschema, functools32 23:56:19 INFO - Running setup.py install for functools32 23:56:19 INFO - warning: no files found matching '*.txt' 23:56:19 INFO - no previously-included directories found matching 'build' 23:56:19 INFO - no previously-included directories found matching 'dist' 23:56:19 INFO - no previously-included directories found matching '.git*' 23:56:19 INFO - Successfully installed jsonschema functools32 23:56:19 INFO - Cleaning up... 23:56:20 INFO - Return code: 0 23:56:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d9f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f91ad30>, '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 0x10f67fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f912b20>, '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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 23:56:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:56:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cagBMjNEg6/Render', 23:56:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:56:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:56:20 INFO - 'HOME': '/Users/cltbld', 23:56:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:56:20 INFO - 'LOGNAME': 'cltbld', 23:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:56:20 INFO - 'MOZ_NO_REMOTE': '1', 23:56:20 INFO - 'NO_EM_RESTART': '1', 23:56:20 INFO - 'PAGER': '/bin/cat', 23:56:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:56:20 INFO - 'PWD': '/builds/slave/test', 23:56:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:56:20 INFO - 'SHELL': '/bin/bash', 23:56:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dRnUOK0Dhe/Listeners', 23:56:20 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 23:56:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:56:20 INFO - 'USER': 'cltbld', 23:56:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:56:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:56:20 INFO - 'XPC_FLAGS': '0x0', 23:56:20 INFO - 'XPC_SERVICE_NAME': '0', 23:56:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:56:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:56:20 INFO - Cleaning up... 23:56:20 INFO - Return code: 0 23:56:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d9f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f91ad30>, '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 0x10f67fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f912b20>, '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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 23:56:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:56:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cagBMjNEg6/Render', 23:56:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:56:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:56:20 INFO - 'HOME': '/Users/cltbld', 23:56:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:56:20 INFO - 'LOGNAME': 'cltbld', 23:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:56:20 INFO - 'MOZ_NO_REMOTE': '1', 23:56:20 INFO - 'NO_EM_RESTART': '1', 23:56:20 INFO - 'PAGER': '/bin/cat', 23:56:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:56:20 INFO - 'PWD': '/builds/slave/test', 23:56:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:56:20 INFO - 'SHELL': '/bin/bash', 23:56:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dRnUOK0Dhe/Listeners', 23:56:20 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 23:56:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:56:20 INFO - 'USER': 'cltbld', 23:56:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:56:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:56:20 INFO - 'XPC_FLAGS': '0x0', 23:56:20 INFO - 'XPC_SERVICE_NAME': '0', 23:56:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:56:20 INFO - Downloading/unpacking blobuploader==1.2.4 23:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:22 INFO - Downloading blobuploader-1.2.4.tar.gz 23:56:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:56:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:56:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:24 INFO - Downloading docopt-0.6.1.tar.gz 23:56:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:56:24 INFO - Installing collected packages: blobuploader, requests, docopt 23:56:24 INFO - Running setup.py install for blobuploader 23:56:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:56:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:56:24 INFO - Running setup.py install for requests 23:56:24 INFO - Running setup.py install for docopt 23:56:25 INFO - Successfully installed blobuploader requests docopt 23:56:25 INFO - Cleaning up... 23:56:25 INFO - Return code: 0 23:56:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d9f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f91ad30>, '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 0x10f67fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f912b20>, '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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 23:56:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:56:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cagBMjNEg6/Render', 23:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:56:25 INFO - 'HOME': '/Users/cltbld', 23:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:56:25 INFO - 'LOGNAME': 'cltbld', 23:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:56:25 INFO - 'MOZ_NO_REMOTE': '1', 23:56:25 INFO - 'NO_EM_RESTART': '1', 23:56:25 INFO - 'PAGER': '/bin/cat', 23:56:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:56:25 INFO - 'PWD': '/builds/slave/test', 23:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:56:25 INFO - 'SHELL': '/bin/bash', 23:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dRnUOK0Dhe/Listeners', 23:56:25 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 23:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:56:25 INFO - 'USER': 'cltbld', 23:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:56:25 INFO - 'XPC_FLAGS': '0x0', 23:56:25 INFO - 'XPC_SERVICE_NAME': '0', 23:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:56:25 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-NxSbR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:56:25 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-_bCYCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:56:25 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-mBsFRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:56:25 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-kfSKUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:56:25 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-3cZp1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:56:25 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Uef4p6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:56:26 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-tT5qRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:56:26 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Ye6tTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:56:26 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-fvcPOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:56:26 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-z2ijiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:56:26 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-RrR3DQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:56:26 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-B4Ug68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:56:26 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-JsFINu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:56:26 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-u3R51F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:56:26 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-g1N60c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:56:26 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-5xKPwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:56:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-fBgiRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:56:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:56:27 INFO - Running setup.py install for manifestparser 23:56:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:56:27 INFO - Running setup.py install for mozcrash 23:56:27 INFO - Running setup.py install for mozdebug 23:56:27 INFO - Running setup.py install for mozdevice 23:56:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:56:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:56:27 INFO - Running setup.py install for mozfile 23:56:27 INFO - Running setup.py install for mozhttpd 23:56:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:56:28 INFO - Running setup.py install for mozinfo 23:56:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:56:28 INFO - Running setup.py install for mozInstall 23:56:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:56:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:56:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:56:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:56:28 INFO - Running setup.py install for mozleak 23:56:28 INFO - Running setup.py install for mozlog 23:56:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:56:28 INFO - Running setup.py install for moznetwork 23:56:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:56:28 INFO - Running setup.py install for mozprocess 23:56:28 INFO - Running setup.py install for mozprofile 23:56:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:56:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:56:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:56:29 INFO - Running setup.py install for mozrunner 23:56:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:56:29 INFO - Running setup.py install for mozscreenshot 23:56:29 INFO - Running setup.py install for moztest 23:56:29 INFO - Running setup.py install for mozversion 23:56:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:56:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:56:29 INFO - Cleaning up... 23:56:29 INFO - Return code: 0 23:56:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:56:29 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')]} 23:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:56:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:29 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')]} 23:56:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:56:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:29 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 0x10f9b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d9f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f91ad30>, '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 0x10f67fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f912b20>, '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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 23:56:29 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 23:56:29 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 23:56:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cagBMjNEg6/Render', 23:56:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:56:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:56:29 INFO - 'HOME': '/Users/cltbld', 23:56:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:56:29 INFO - 'LOGNAME': 'cltbld', 23:56:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:56:29 INFO - 'MOZ_NO_REMOTE': '1', 23:56:29 INFO - 'NO_EM_RESTART': '1', 23:56:29 INFO - 'PAGER': '/bin/cat', 23:56:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:56:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:56:29 INFO - 'PWD': '/builds/slave/test', 23:56:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:56:29 INFO - 'SHELL': '/bin/bash', 23:56:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dRnUOK0Dhe/Listeners', 23:56:29 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 23:56:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:56:29 INFO - 'USER': 'cltbld', 23:56:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:56:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:56:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:56:29 INFO - 'XPC_FLAGS': '0x0', 23:56:29 INFO - 'XPC_SERVICE_NAME': '0', 23:56:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:56:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:56:30 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-1oA7fE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:56:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:56:30 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-RFzM9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:56:30 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)) 23:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:56:30 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-u171k1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:56:30 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)) 23:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:56:30 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-W4q1Sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:56:30 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)) 23:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:56:30 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Bav5uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:56:30 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)) 23:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:56:30 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-7OTKiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:56:30 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)) 23:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:56:30 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-BUuGlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:56:30 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)) 23:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:56:30 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-O8vSvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:56:30 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)) 23:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:56:30 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-CoGDwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:56:30 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)) 23:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:56:30 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-lWa4dQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:56:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 23:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:56:31 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-BaveAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:56:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:56:31 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-60D6JG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:56:31 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)) 23:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:56:31 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Vm0iam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:56:31 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)) 23:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:56:31 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-x2YXL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:56:31 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)) 23:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:56:31 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-pIUPqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:56:31 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)) 23:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:56:31 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-5uRQeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:56:31 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)) 23:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:56:31 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-DzHVwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:56:31 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)) 23:56:31 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)) 23:56:31 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)) 23:56:31 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)) 23:56:31 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)) 23:56:31 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)) 23:56:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:56:34 INFO - Downloading blessings-1.6.tar.gz 23:56:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:56:34 INFO - Installing collected packages: blessings 23:56:34 INFO - Running setup.py install for blessings 23:56:34 INFO - Successfully installed blessings 23:56:34 INFO - Cleaning up... 23:56:34 INFO - Return code: 0 23:56:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:56:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:56:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:56:34 INFO - Reading from file tmpfile_stdout 23:56:34 INFO - Current package versions: 23:56:34 INFO - blessings == 1.6 23:56:34 INFO - blobuploader == 1.2.4 23:56:34 INFO - docopt == 0.6.1 23:56:34 INFO - functools32 == 3.2.3-2 23:56:34 INFO - jsonschema == 2.5.1 23:56:34 INFO - manifestparser == 1.1 23:56:34 INFO - mozInstall == 1.12 23:56:34 INFO - mozcrash == 0.17 23:56:34 INFO - mozdebug == 0.1 23:56:34 INFO - mozdevice == 0.48 23:56:34 INFO - mozfile == 1.2 23:56:34 INFO - mozhttpd == 0.7 23:56:34 INFO - mozinfo == 0.9 23:56:34 INFO - mozleak == 0.1 23:56:34 INFO - mozlog == 3.3 23:56:34 INFO - moznetwork == 0.27 23:56:34 INFO - mozprocess == 0.23 23:56:34 INFO - mozprofile == 0.28 23:56:34 INFO - mozrunner == 6.12 23:56:34 INFO - mozscreenshot == 0.1 23:56:34 INFO - mozsystemmonitor == 0.3 23:56:34 INFO - moztest == 0.7 23:56:34 INFO - mozversion == 1.4 23:56:34 INFO - psutil == 3.1.1 23:56:34 INFO - requests == 1.2.3 23:56:34 INFO - wsgiref == 0.1.2 23:56:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:56:34 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')]} 23:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:34 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')]} 23:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56:34 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 0x10f9b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d9f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f91ad30>, '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 0x10f67fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f912b20>, '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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 23:56:34 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 23:56:34 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 23:56:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cagBMjNEg6/Render', 23:56:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:56:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:56:34 INFO - 'HOME': '/Users/cltbld', 23:56:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:56:34 INFO - 'LOGNAME': 'cltbld', 23:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:56:34 INFO - 'MOZ_NO_REMOTE': '1', 23:56:34 INFO - 'NO_EM_RESTART': '1', 23:56:34 INFO - 'PAGER': '/bin/cat', 23:56:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:56:34 INFO - 'PWD': '/builds/slave/test', 23:56:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:56:34 INFO - 'SHELL': '/bin/bash', 23:56:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dRnUOK0Dhe/Listeners', 23:56:34 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 23:56:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:56:34 INFO - 'USER': 'cltbld', 23:56:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:56:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:56:34 INFO - 'XPC_FLAGS': '0x0', 23:56:34 INFO - 'XPC_SERVICE_NAME': '0', 23:56:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:56:35 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)) 23:56:35 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)) 23:56:35 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)) 23:56:35 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)) 23:56:35 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)) 23:56:35 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)) 23:56:35 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)) 23:56:35 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)) 23:56:35 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)) 23:56:35 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)) 23:56:35 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)) 23:56:35 INFO - Cleaning up... 23:56:35 INFO - Return code: 0 23:56:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:56: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')]} 23:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56: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')]} 23:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:56: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d9f530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f91ad30>, '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 0x10f67fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f912b20>, '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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 23:56: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', 'jsonschema'] in /builds/slave/test/build 23:56: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 jsonschema 23:56:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cagBMjNEg6/Render', 23:56:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:56:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:56:35 INFO - 'HOME': '/Users/cltbld', 23:56:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:56:35 INFO - 'LOGNAME': 'cltbld', 23:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:56:35 INFO - 'MOZ_NO_REMOTE': '1', 23:56:35 INFO - 'NO_EM_RESTART': '1', 23:56:35 INFO - 'PAGER': '/bin/cat', 23:56:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:56:35 INFO - 'PWD': '/builds/slave/test', 23:56:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:56:35 INFO - 'SHELL': '/bin/bash', 23:56:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dRnUOK0Dhe/Listeners', 23:56:35 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 23:56:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:56:35 INFO - 'USER': 'cltbld', 23:56:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:56:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:56:35 INFO - 'XPC_FLAGS': '0x0', 23:56:35 INFO - 'XPC_SERVICE_NAME': '0', 23:56:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:56:35 INFO - Cleaning up... 23:56:35 INFO - Return code: 0 23:56:35 INFO - Running post-action listener: _resource_record_post_action 23:56:35 INFO - Running post-action listener: _start_resource_monitoring 23:56:35 INFO - Starting resource monitoring. 23:56:35 INFO - [mozharness: 2016-08-30 06:56:35.482537Z] Finished create-virtualenv step (success) 23:56:35 INFO - [mozharness: 2016-08-30 06:56:35.483492Z] Running install step. 23:56:35 INFO - Running pre-action listener: _resource_record_pre_action 23:56:35 INFO - Running main action method: install 23:56:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:56:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:56:35 INFO - Reading from file tmpfile_stdout 23:56:35 INFO - Detecting whether we're running mozinstall >=1.0... 23:56:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:56:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:56:35 INFO - Reading from file tmpfile_stdout 23:56:35 INFO - Output received: 23:56:35 INFO - Usage: mozinstall [options] installer 23:56:35 INFO - Options: 23:56:35 INFO - -h, --help show this help message and exit 23:56:35 INFO - -d DEST, --destination=DEST 23:56:35 INFO - Directory to install application into. [default: 23:56:35 INFO - "/builds/slave/test"] 23:56:35 INFO - --app=APP Application being installed. [default: firefox] 23:56:35 INFO - mkdir: /builds/slave/test/build/application 23:56:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:56:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:57:10 INFO - Reading from file tmpfile_stdout 23:57:10 INFO - Output received: 23:57:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:57:10 INFO - Running post-action listener: _resource_record_post_action 23:57:10 INFO - [mozharness: 2016-08-30 06:57:10.196883Z] Finished install step (success) 23:57:10 INFO - [mozharness: 2016-08-30 06:57:10.197041Z] Running run-tests step. 23:57:10 INFO - Running pre-action listener: _resource_record_pre_action 23:57:10 INFO - Running main action method: run_tests 23:57:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:57:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:57:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:57:10 INFO - 2016-08-29 23:57:10.211 screenresolution[1633:8585] starting screenresolution argv=screenresolution get 23:57:10 INFO - 2016-08-29 23:57:10.213 screenresolution[1633:8585] Display 0: 1600x1200x32@60 23:57:10 INFO - 2016-08-29 23:57:10.219 screenresolution[1634:8587] starting screenresolution argv=screenresolution list 23:57:10 INFO - Available Modes on Display 0 23:57:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:57:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:57:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:57:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:57:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:57:10 INFO - Intel Iris: 23:57:10 INFO - Chipset Model: Intel Iris 23:57:10 INFO - Type: GPU 23:57:10 INFO - Bus: Built-In 23:57:10 INFO - VRAM (Dynamic, Max): 1536 MB 23:57:10 INFO - Vendor: Intel (0x8086) 23:57:10 INFO - Device ID: 0x0a2e 23:57:10 INFO - Revision ID: 0x0009 23:57:10 INFO - Displays: 23:57:10 INFO - Display: 23:57:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:57:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:57:10 INFO - Display Serial Number: 1600x1200 60 23:57:10 INFO - Main Display: Yes 23:57:10 INFO - Mirror: Off 23:57:10 INFO - Online: Yes 23:57:10 INFO - Rotation: Supported 23:57:10 INFO - Return code: 0 23:57:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:57:10 INFO - Python 2.7.3 23:57:10 INFO - Return code: 0 23:57:10 INFO - grabbing minidump binary from tooltool 23:57:10 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')]} 23:57:10 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 0x10f91ad30>, '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 0x10f67fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f912b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:57:10 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 23:57:10 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 23:57:10 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 23:57:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:57:10 INFO - Return code: 0 23:57:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:57:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:57:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:57:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:57:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:57:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--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 23:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0221 --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 23:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cagBMjNEg6/Render', 23:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:57:10 INFO - 'HOME': '/Users/cltbld', 23:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:57:10 INFO - 'LOGNAME': 'cltbld', 23:57:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:57:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:57:10 INFO - 'MOZ_NO_REMOTE': '1', 23:57:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:57:10 INFO - 'NO_EM_RESTART': '1', 23:57:10 INFO - 'PAGER': '/bin/cat', 23:57:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:57:10 INFO - 'PWD': '/builds/slave/test', 23:57:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:57:10 INFO - 'SHELL': '/bin/bash', 23:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dRnUOK0Dhe/Listeners', 23:57:10 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 23:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:57:10 INFO - 'USER': 'cltbld', 23:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:57:10 INFO - 'XPC_FLAGS': '0x0', 23:57:10 INFO - 'XPC_SERVICE_NAME': '0', 23:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:57:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--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 23:57:10 INFO - mozversion application_buildid: 20160829213422 23:57:10 INFO - mozversion application_changeset: 26e22af660e543ebb69930f082188b69ec756185 23:57:10 INFO - mozversion application_display_name: Nightly 23:57:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:57:10 INFO - mozversion application_name: Firefox 23:57:10 INFO - mozversion application_remotingname: firefox 23:57:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:57:10 INFO - mozversion application_vendor: Mozilla 23:57:10 INFO - mozversion application_version: 51.0a1 23:57:10 INFO - mozversion platform_buildid: 20160829213422 23:57:10 INFO - mozversion platform_changeset: 26e22af660e543ebb69930f082188b69ec756185 23:57:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:57:10 INFO - mozversion platform_version: 51.0a1 23:57:10 INFO - using testdate: 1472540230 23:57:10 INFO - actual date: 1472540230 23:57:10 INFO - starting webserver on 'localhost:49272' 23:57:10 INFO - SUITE-START | Running 2 tests 23:57:10 INFO - TEST-START | dromaeo_css 23:57:10 INFO - operating with platform_type : mac_ 23:57:10 INFO - Initialising browser for dromaeo_css test... 23:57:10 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpvyueRr/profile http://localhost:49272/getInfo.html) 23:57:13 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 23:57:13 INFO - PROCESS | 1644 | colorDepth:24 23:57:13 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 23:57:13 INFO - PROCESS | 1644 | __metrics 23:57:13 INFO - PROCESS | 1644 | 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" 23:57:13 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:57:14 INFO - TEST-INFO | 1644: exit 0 23:57:14 INFO - Browser initialized. 23:57:14 INFO - Running cycle 1/1 for dromaeo_css test... 23:57:14 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpvyueRr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:57:26 INFO - PROCESS | 1647 | RSS: Main: 234799104 23:57:26 INFO - PROCESS | 1647 | 23:58:17 INFO - PROCESS | 1647 | 1472540297892 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] 00:00:29 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 00:00:30 INFO - PROCESS | 1647 | RSS: Main: 334086144 00:00:30 INFO - PROCESS | 1647 | 00:03:33 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 00:03:34 INFO - PROCESS | 1647 | RSS: Main: 378179584 00:03:34 INFO - PROCESS | 1647 | 00:06:36 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 00:06:37 INFO - PROCESS | 1647 | RSS: Main: 348946432 00:06:37 INFO - PROCESS | 1647 | 00:09:39 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 00:09:40 INFO - PROCESS | 1647 | RSS: Main: 322564096 00:09:40 INFO - PROCESS | 1647 | 00:12:43 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 00:12:44 INFO - PROCESS | 1647 | RSS: Main: 321568768 00:12:44 INFO - PROCESS | 1647 | 00:15:47 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 00:15:47 INFO - PROCESS | 1647 | RSS: Main: 492929024 00:15:47 INFO - PROCESS | 1647 | 00:15:47 INFO - PROCESS | 1647 | __start_tp_report 00:15:47 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 00:15:47 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 00:15:47 INFO - PROCESS | 1647 | |i|pagename|runs| 00:15:47 INFO - PROCESS | 1647 | |0;dojo.html;3338.73290136789;3368.00643810076;3377.054424761066;3432.5709286339206;3448.7585868989163;5625.156226566017;5739.741711622976;5748;5864.442877926595;5874.441928016842;5744.885102297962;5746.712664366786;5850.502707269877;5867.794627188049;5940.257467816523;5188.455212202721;5235.738213089349;5237.633365664405;5246.554042906358;5379.300690910196;5384.111621582434;5384.3807962084275;5387.811426600069;5396.97457483079;5420.430854760252;4176.540580536139;5151.716655583941;5214.296070030536;5215.348081489814;5308.23030660552;4329.285667864799;5254.395744489394;5259.132243179871;5264.552513036397;5277.736113194325;5183.5853131749745;5204.68771873685;5210.166373380241;5236.9738151309;5253.763580638881;5693.202951586781;5694.032014557535;5726.656400615976;5852.941470585282;5896.938551060807;6277.340879207663;6326.304106548276;6327.778527751507;6338.809835704937;6351.872962540723;5038.571721403685;6208.161898143738;6252;6314.842128946767;6396.616203027833;5832.708364581838;5835.299764028301;5871.060630299132;5912.379200184005;5927.881442371172;6256.217972753406;6268.968655156695;6280.685965701697;6294.527910406738;6304.558680892385;6074.058520929263;6124.755009799647;6129.693515324216;6202.844928876724;6211.192544969215;6204.162438070893;6222.377762223741;6238.189035425366;6263.498920086382;6268.059791031382;6210.7515699372425;6219.595726277777;6220.7511699532415;6223.782167624111;6228.128062071313;4956.727379994135;6110.2550082019825;6188.659623720739;6203.2556093269095;6262.968685156545;4964.106460837084;4975.004999000214;5023.522765337287;5024.0956627807345;5044.520770526794;4478.171538265431;4478.596926276586;4482.148391805546;4525.502194758574;4560.543945605413;5753.712314384264;5794.072948328331;5800.071988481823;5801.12983052537;5814.156947242711;6131.110988906584;6201.6899155042065;6206.565540412128;6254.155688981929;6275.96862015687;5669.34802497718;5700.25896633435;5717.942820571741;5747.339056008529;5752.884942301131;5501.559875209973;5504.256925315111;5531.308586426697;5540.556755459554;5547.944520554824;5334.893302133858;6325.146105275729;6329.430351268408;6352.618842869442;6356.269029061532;6119.807225076679;6364.904526432199;6378.968105159444;6380.840478988062;6384.521160912857;5689.260396148474;5997.970010150096;6247.531435142473;6333.90499142504;6351.015592583157;6183.938160618516;6217.316095229611;6248.562600618095;6255.9374406257175;6261.436470717657;6029.246344206974;6127.892078906731;6336.049592561153;6337.9366206337345;6385.106085148176;6106.491003209229;6129.3564175762685;6131.223750760645;6200.658963757037;6201.069839524108;6162.476189523941;6168.19813424252;6173.32093469727;6179.351168127282;6202.038684003986;5803.506701930383;5991.310999234969;6028.703828676856;6047.765985553472;6054.594508808104;4792.832250871203;4921.8523444296725;5751.74117164737;6079.452849243589;6163.445289923749;5941.732622032106;5971.880562388815;5973.104034394875;5984.551158663031;6031.095335699505 00:15:47 INFO - PROCESS | 1647 | |1;ext.html;513.8252993982045;530.1993989076493;534.4495169974924;550.5347980956091;552.6822077305548;30311.848440757793;38385.80807095964;39737.8013109934;39762.40356394649;39897.401538976905;41659.37510937331;42014.15971680565;42056.57943420565;42079.57920420795;42237.36643950343;9732.805343893118;9917.90082099177;9917.90082099177;9925.057119573628;9942.701718948407;8761.299096072331;8903.554822258893;11910.583129590466;12137.757244855098;12179.51281948721;12092;12133.939330303336;12134.817977730341;12156.270623762603;12164.817527737046;8768.561571921378;12114.939425302906;12170.208936419192;12195.634130976085;12198.268103913704;11644.59243926467;11974.820377694341;12012.399380030964;12236.327002014885;12237.816432753516;39495.21009579821;39695.40456893149;39785.20429591392;39852;39862.202755944716;3230.1924518870283;3404.4042292598942;3409.829508524564;3496.6153723090692;3695.815209239554;30026.549601755993;30173.698263017308;30910.072697818876;30998;31030.534541981888;3280.2291461506525;3282.917927051819;3354.3626710924846;3392.7625066245373;3400.25194457219;494595;495882.5205873984;497508;499105;500033.99999999633;56336.15495767485;56596.15105773417;56783;56882;56993.71503142458;59222;59228;59262;59298.703506483056;59419;15948;16140.35438582467;16165.272562734704;16167.676646467005;16174.595635109217;11414.828777568344;11678.824817627743;12648.114631975857;12730;12747.171433856769;15021.399144034116;15305.923470382577;15420.383184672712;15605.063696178266;15764.921175394278;5884.350005492006;5893.850699113632;5973.163757074013;6010.699465026819;6048.36492168316;748.1321666866409;871.4205053639386;948.2698322291934;976.5361453309621;981.2885657897967;28858.567121493194;29062.41875162485;29275.414491710046;29963.101106966828;30054.69945300563;1000.7998400319965;1002.3811544355958;1049.6378749331468;1099.3513826842202;1116.821308590638;14636.853631463762;15262.923685381502;15314.208059793897;15462.304196311194;15590.298436570154;628449;629269;630882;634175;634370.7036620423;55492.445075549535;55618.88762224733;55663.443365566636;55811.44188558062;55850;1206.6078524479578;1246.4827096754746;1300.5123078845434;1331.6670832291927;1345.7510360583324;27904.720952790212;29519.26201844885;30213.09360719182;30588.694113058584;31067.067987960396;49685.50314496809;54146;54273;54327;54391;568194;572193;573805;575380;575920;24882.626760598967;25244.49511009806;25328.366790830376;25545.361365966;25637.846296917058;44678;44998.87502812456;45060;45922.851928700715;45947.540524594326;37211.6278837219;37314.44028339631;38144.340118383225;38199.80900095482;38278.61721382751;37298.627013729514;37467.43798843074;37521;38561.421578676905;38698.03254918649;36104.097397565274;36120.09699757422;36308.09229769277;36314.8184259077;36353.636463635026;32025.519617206228;32695.836520817244;33473.665263348026;33880.66119338775;33984.490232647026;20258.092781965202;20612.587748245252;24733.505329893662;25101.623475648244;25313.873430632728 00:15:47 INFO - PROCESS | 1647 | |2;jquery.html;310.250744452148;370.28719714549504;371.49662207708553;386.66939766499627;398.3844959537514;14689.265536723162;19932.302369417073;20249.886256255937;20789.68815467767;21136.260230891923;19777.307794227207;20118.792872427664;20610.793892061076;20712.585748285026;20738.481537961557;4931.457539670633;5330.733463326837;5507.9449205507835;5514;6199.907001394983;3915.569287378386;4109.979450102745;5820.243368362107;6056.878862422749;6192.81421557354;6125.754969801203;6149.2313460817395;6153.292371377282;6156.6613836238985;6161.599496032766;6170.352113028135;7699.191584883591;8134.389920755967;8180.6318715657935;8193;7666.616669166575;7748.2251774822635;7798.9220107798765;7865.488743231729;7891.092524359715;24128;29419.264518386997;29801.95693150751;29836.403271934658;30052.54921176184;2717.4565086982484;3358.664133586646;3414.163529935157;3486;3524.3832329342263;16451.25969331383;17088.829111708972;17441.912790436094;17836;18308.9930053847;1822.3621732393556;1829.231722676479;1829.4054432309554;1871.721744680003;1894.5737209127974;135691;152115;152714.23642881715;152805.23597381942;156070.21964890216;21142.260020899503;21289.574208515744;21565.56868862614;21672.78327216739;21890.89054554717;35111.94664160079;35195.120121996646;35322.823385882904;35347.11632209164;35366.46950295748;10668.199885008655;10717.35695858251;10866.348019118877;11046.502907369188;11201.495932683049;7506.512076714996;8904.19862212404;8956.193942545202;8974.910250897537;9213.81572368549;9500.809983800285;9575.473348965734;9620.422774633544;9782.51087445625;9814;2818.873150708223;3208.989168411943;3369.4440417330866;3649.8905032849057;3685.6682898538725;366.4741096526493;390.78897395406733;398.33875766227925;803.1406395157243;916.9587368568297;11460.656180314603;11700.2979821211;12021.639350819489;12033.39833008346;12214.389280536116;419.8383622305373;419.9496060472763;422.9534751177368;433.89586499239954;447.917135329965;5996.5502587305255;6023.69881505923;6034.366391528914;6196.907046394308;6326.810195694136;290570;292133;302045;600248;607213;74961;80073;82604;133763;152825;982.5774916785708;993.1806259835664;1008.0876806490138;1022.1311884897926;1060.734816295926;18926.242950282387;19124;19184.520386990436;19241.4227573173;19254.22983080661;42906;54144.187837183264;54682.726586366814;56239.71880140573;56656.71671641615;83449.58275208827;85462;85951;86297.62405122604;86889.1311086881;7817.413693972861;8075.192480751878;8330.416870818985;8368.83262334762;8516.74449766508;9306.416984732674;13140.540081097115;13153.736925261632;13204.669883252996;13566.253856038013;10527;11267.492962816528;11348.659540213806;11414.543418263176;11628.88371116278;11077.390743509184;11209.271397359145;11448.026917712088;11592.942035289769;11634.36011019402;9894.010598940049;10035.19718422539;10208.897911020795;11560.364179970182;11740.882591173979;7233;7371;7443.36731377839;7585.582792946441;7602.961985190039;9070.092990700878;11206;11269.66191014271;11547.653570392902;11704.414779261173 00:15:47 INFO - PROCESS | 1647 | |3;mootools.html;1451.3614009835671;1471.7056588682262;1599.264338404334;1758.4724582625208;1796.1917137288215;8337.708180213695;8892.8666070009;8901.109889011095;8908.153725396092;8923.464592124468;7847.92152078479;8080.757577272677;8425.494470331785;8691.869621955675;8708.303335733142;2312.5952958232283;2355.8350915435926;2360.374500757292;2362.893669784864;2368.988155059222;2327.2785436514737;2345.413646588353;2372.8694921779297;2381.988090059556;2384.403899025244;2071.91224607081;2312.3294244669028;2355.187460326185;2366.680498132756;2374.4538756086113;2335.404471859682;2349.753275906031;2359.634256690216;2361.2207971369407;2363.373705967911;2312.9421764456024;2313.363824948136;2317.2816426907543;2317.43222910388;2327.755585663498;8831.646734130627;8834.249088827457;8931.97282312536;8932.687355942577;8937.106289371075;4984.327115839388;5038.949610503884;5161.1742121260795;5164.070467315882;5170.896582068338;3459.844307006191;3541.5041894134843;3605.0446631642635;3626.546681664792;3633.8001409922445;1048.8479170520231;1086.2721976275914;1086.8858769829212;1102.73534351755;1108.279618248137;10579.682609521727;10674.62638807638;10686.412247326394;10690.251682382237;10692.358458492594;4254.851080212177;4317.784110794448;4495.123450927038;4498.6626003049905;4510.909781804346;8993.865092023625;8995.055519170393;9007.63969441228;9019.639214431481;9026.729198123936;5086.21163719624;5090.821821236313;5092.872678183001;5095.133827249377;5095.847124586194;3653.2693461307845;4524.524924882906;4725.976370118196;4726.338312636233;4843.806247750051;3822.140018495816;3859.498265225559;3929.31237033518;3971.980140099281;3978.8010599469912;1257.7295881385546;1261.0983147049903;1265.544404014554;1273.1597145883857;1282.7172827172826;786.0920636664662;857.8327226190958;891.0955380288882;895.6462197431978;925.9999200863186;5561.360443549043;5667.319921609434;5798.188253644493;5926.348101708809;5929.436703513159;871.8953725552974;878.6748902905965;901.7835719427421;921.2583869984727;968.3076600230728;3853.8265778040054;3858.382658774639;3890.2997460456822;3995.820188091543;3996.780177090288;10259.025392587691;10740.516676749565;10755.40845253503;10756;10761.354318740901;10678.466076696133;10685.679429617125;10693.144548436107;10713.892861071445;10721.142308615292;7699.807504812313;7974.561399123104;8066.032076150899;8110.837783244302;8143.470674406145;5348.7593058311695;5847.7368518417625;5982.169109402317;6036.8188954331445;6089.786857459968;8382.203690649378;9268.165865072177;9931.0068993103;9967.700968970943;10042.895281519173;9965;10149.79700405973;10165.79668406613;10268.332558383681;10304;6949.2008419030435;6954.826129346807;6993.4754893384215;7496.587687677012;7617.352525035212;8188.181181881764;8468.280196183394;8471;8474.66101355939;8475.110113438;7336.486445948945;7463.141738699902;7484.139323977813;7543.170251272465;7586;7194.251773897983;7238.565686058853;7305.269473052652;7436.367908727602;7445.7766267012075;6784.118064651564;6945.548539344926;6946.340097690711;6949.027136200835;6958.860822783618;4884.682495637772;4941.975290123526;5038.042771873333;5046.1673823818655;5066.138756411346;4913.582345500672;4928.354857377543;5098.7705553250935;5207.791688332424;5214 00:15:47 INFO - PROCESS | 1647 | |4;prototype.html;296.29925226838526;300.11765409695494;305.0634551925588;309.0804855554725;311.66496016781934;4382.868513944582;4714.905701885965;4819.590334821544;5023.944971555978;5303.734813259336;4374.818798924289;4426.535213802553;4486.394336764536;5248.748513703902;5282.577393808496;3559.4838748381535;3981.820818063194;3982.5818289079666;3997.4203740457615;3998.4402183694306;3390.4405773047542;3638.1268495561008;3903.2974064668347;4031.6371526562602;4080.2859499587603;3976.3439032559736;3993.161436098408;4013.8394464221396;4016.3373043447796;4039.5960403959516;3815.4055242435134;3992.8402863885553;4038.414429907676;4053.655439287664;4067.877963661095;2887.812292201;3416.214270717749;3432.648635684564;3548.361294966905;3567.1438854674952;4660.883477913046;4755.645955252137;5202.531772140488;5225.686458812445;5260.421353651133;4562.517181915858;4571.451425828922;4574.947762014755;4584.807949933008;4627.444706635191;5040.42035165957;5318.946810531884;5465.125579907235;5543.722813859291;5654;3381.5773028371455;3700.6862563790005;3767.1147280389087;3843.2313537292375;3894.5006172623744;4812.422509298872;5224.876651519903;5581.218629391929;5901.0558310670085;5955.880882382328;3490.947635785465;3769.792661403649;3822.522184726909;3932.960670393317;3947.802609869495;3299.406106900733;3859.5947425520476;3873.65137137659;3880.7477513961503;3889.6888248940018;3783.540698545912;4075.3275709508193;4277.844981854882;4530.207213737583;4544.659325498986;5202.465272744549;5251.927622340973;5537.556995440355;5650.237217975603;5659.688717120515;1291.9935400323127;1319.7954317080869;1320.9603711888658;1321.0356439799;1328.8936885049172;707.6426404665611;793.8332950080547;799.544259771936;805.5086397297644;814.6130587970666;3949.763014219156;3975.5229372475487;4129.525104612948;4451.3323001550025;4483.798229079699;4273.935386566928;4371.825126994884;4705.788239529229;4786.994731106507;4843.609473368656;4203.873883783492;4324.3513472978675;4348.804303806273;4356.586124318184;4364.869053928387;2568.563344231485;2710.8509032003035;2886.278430392402;3010.864511097006;3061.224489795913;5276.63063585553;5370.248165256867;5815.680137592389;5838.299404071538;5979.611325263844;4977.601791856643;5580.553555715533;5899.793507227312;5915.970420147958;5970.686269120592;4686.695364801345;5248.501392367795;5929.73316200772;6010.549208809357;6042.425969532887;4707.4351077869505;5845.720169134126;5845.795397161079;5882.911756323744;6018.428249316308;5551.750171242385;5774.451427114417;5798.652080875161;5834.737436815269;5859.736311866061;5178.275041494269;5636.351819540805;5638.7462564185535;5885.646861188342;5911.733971971185;3780.4140358244517;3829.291581057513;3844.884653460401;3864.2271545691538;3876;4891.874868780129;5346.652467589594;5825;5829;5833.504152146946;5312;5344.866378340417;5627.88744225105;5638.5771067170945;5643.548516118783;5596.768710883599;5610.326760788732;5615.410381909841;5633.577481688808;5642.181883626933;4620.745858977654;4652.720836749806;5152.922706159336;5464.890702186013;5508.752106155152;4659.161350956861;4823.638227133049;5543.861403464945;5617.522510586646;5622.409646987007;5554.2779438672715;5560.858115165081;5561.443855614406;5572.442755724395;5588.3573389060775 00:15:47 INFO - PROCESS | 1647 | |5;yui.html;648.4326214562258;693.1681981622053;718.9737470167064;728.9817754556135;743.6653505922333;15265.771013434793;16006.679866402665;16007.279672414741;16028.519144425656;16066.27701753421;14380.712385752278;14465.915056370788;14467.204303763288;14512.637184070403;15010.774838377405;834.5451728807784;856.4732689396008;858.107568129148;858.6436628799072;861.3410740783274;853.9359548033891;856.3320609924269;856.5845564901018;857.536930057768;858.9184027517376;892.6072528087653;897.5915958238986;898.074982767747;899.7705585075829;901.603294550399;777.1529033353615;883.0463099852176;885.9689910853153;887.6715615222345;887.7203680840514;589.6933594530863;671.4292851076546;672.4385138409434;673.1081317254657;675.1493118670461;12986.09097363185;13035.217886926719;13045.152065115737;13052.21686698801;13073.542426015043;11755.882441175565;11915.225510341887;11930.463129159209;12183.93908030463;12227;10751.354918704903;10928.836067458995;11072.335659860435;11170;11433.314001159957;3598.4962105305276;3622.2393297407575;3663.230721548478;3666.431703086026;3795.4876091727538;115186.42406787912;115370;115533;116082;117335.41332293369;8770;9847.606095756233;10462.372257664565;10801.891981080089;11002;21539.353819385444;21595.352139435843;22070.33788986333;22123.115075397127;22125.446863828533;7904.367650588054;8077.111517733045;8083.514989100555;8194.959025204837;8226.547539885243;4479;4492.73043617384;4494.10117976406;4630.21286381316;4716.504766999416;3040.467918114321;3721.627837216257;3731.4029755239576;3733.4586484959527;3847.6537111660086;715.9176694680074;718.6909628859536;718.7592156627588;728.3626826526789;739.8372358081214;595.746807606774;624.2758400255773;667.1893649216225;669.7220653428869;674.1775034457953;8816.603252853636;8842.115788421235;9567.712968610953;9703.272254580934;9843.557039933221;543.8151028650278;544.6242092955848;557.4509108528097;585.6427579176699;624.2633692243197;4336.436263285753;4500.144972455223;4682.929756053662;4821.397325334333;5004.824831130893;87675.12324876797;122650.38674806569;163377.18311408366;163391.18304408403;165735;135510;135845;137175;142466;145065.8240126399;97507.51246243865;117548.41225793817;126060.1090983636;126523;127821.36089319494;9941.602335906482;9989.750256243651;10000.199984001118;10019.649312274036;10032.247581431277;64705.67647161734;65089;66494.33505664881;66920.33079669336;68244;65851.34148658453;91556;93921;94423;102130.48934755576;11394.886051139714;12954.28751418681;13220;13227;13666;14943.332589340898;15009;15214.923925380303;15427.768583471032;15588.766168507254;13627.931860340635;13752.65618359509;13812;13854.792178117135;13883.444662213398;13770.724585508033;13987.790183147057;14103.647408814451;14566.85433145655;14580.562583122522;13510.594682159552;13828.377723002288;13836.723265534432;13885.444582217002;14010.649733756738;11380.374079425712;11466.31202127875;11566.479508421973;11701.531938722695;11942;10312.742181445525;10935.453227338443;11102.666919992413;11427.657170284905;11640.708982275512 00:15:47 INFO - PROCESS | 1647 | __end_tp_report 00:15:47 INFO - PROCESS | 1647 | __start_cc_report 00:15:47 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1421 00:15:47 INFO - PROCESS | 1647 | __end_cc_report 00:15:47 INFO - PROCESS | 1647 | __startTimestamp1472541347891__endTimestamp 00:15:47 INFO - PROCESS | 1647 | 00:15:47 INFO - PROCESS | 1647 | ------- Summary: start ------- 00:15:47 INFO - PROCESS | 1647 | Number of tests: 6 00:15:47 INFO - PROCESS | 1647 | 00:15:47 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5741.44 Median:5940.26 stddev:653.49 (11.0%) stddev-sans-first:627.73 00:15:47 INFO - PROCESS | 1647 | Values: 3338.7 3368.0 3377.1 3432.6 3448.8 5625.2 5739.7 5748.0 5864.4 5874.4 5744.9 5746.7 5850.5 5867.8 5940.3 5188.5 5235.7 5237.6 5246.6 5379.3 5384.1 5384.4 5387.8 5397.0 5420.4 4176.5 5151.7 5214.3 5215.3 5308.2 4329.3 5254.4 5259.1 5264.6 5277.7 5183.6 5204.7 5210.2 5237.0 5253.8 5693.2 5694.0 5726.7 5852.9 5896.9 6277.3 6326.3 6327.8 6338.8 6351.9 5038.6 6208.2 6252.0 6314.8 6396.6 5832.7 5835.3 5871.1 5912.4 5927.9 6256.2 6269.0 6280.7 6294.5 6304.6 6074.1 6124.8 6129.7 6202.8 6211.2 6204.2 6222.4 6238.2 6263.5 6268.1 6210.8 6219.6 6220.8 6223.8 6228.1 4956.7 6110.3 6188.7 6203.3 6263.0 4964.1 4975.0 5023.5 5024.1 5044.5 4478.2 4478.6 4482.1 4525.5 4560.5 5753.7 5794.1 5800.1 5801.1 5814.2 6131.1 6201.7 6206.6 6254.2 6276.0 5669.3 5700.3 5717.9 5747.3 5752.9 5501.6 5504.3 5531.3 5540.6 5547.9 5334.9 6325.1 6329.4 6352.6 6356.3 6119.8 6364.9 6379.0 6380.8 6384.5 5689.3 5998.0 6247.5 6333.9 6351.0 6183.9 6217.3 6248.6 6255.9 6261.4 6029.2 6127.9 6336.0 6337.9 6385.1 6106.5 6129.4 6131.2 6200.7 6201.1 6162.5 6168.2 6173.3 6179.4 6202.0 5803.5 5991.3 6028.7 6047.8 6054.6 4792.8 4921.9 5751.7 6079.5 6163.4 5941.7 5971.9 5973.1 5984.6 6031.1 00:15:47 INFO - PROCESS | 1647 | 00:15:47 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:69645.39 Median:36334.23 stddev:152286.31 (419.1%) stddev-sans-first:152625.06 00:15:47 INFO - PROCESS | 1647 | Values: 513.8 530.2 534.4 550.5 552.7 30311.8 38385.8 39737.8 39762.4 39897.4 41659.4 42014.2 42056.6 42079.6 42237.4 9732.8 9917.9 9917.9 9925.1 9942.7 8761.3 8903.6 11910.6 12137.8 12179.5 12092.0 12133.9 12134.8 12156.3 12164.8 8768.6 12114.9 12170.2 12195.6 12198.3 11644.6 11974.8 12012.4 12236.3 12237.8 39495.2 39695.4 39785.2 39852.0 39862.2 3230.2 3404.4 3409.8 3496.6 3695.8 30026.5 30173.7 30910.1 30998.0 31030.5 3280.2 3282.9 3354.4 3392.8 3400.3 494595.0 495882.5 497508.0 499105.0 500034.0 56336.2 56596.2 56783.0 56882.0 56993.7 59222.0 59228.0 59262.0 59298.7 59419.0 15948.0 16140.4 16165.3 16167.7 16174.6 11414.8 11678.8 12648.1 12730.0 12747.2 15021.4 15305.9 15420.4 15605.1 15764.9 5884.4 5893.9 5973.2 6010.7 6048.4 748.1 871.4 948.3 976.5 981.3 28858.6 29062.4 29275.4 29963.1 30054.7 1000.8 1002.4 1049.6 1099.4 1116.8 14636.9 15262.9 15314.2 15462.3 15590.3 628449.0 629269.0 630882.0 634175.0 634370.7 55492.4 55618.9 55663.4 55811.4 55850.0 1206.6 1246.5 1300.5 1331.7 1345.8 27904.7 29519.3 30213.1 30588.7 31067.1 49685.5 54146.0 54273.0 54327.0 54391.0 568194.0 572193.0 573805.0 575380.0 575920.0 24882.6 25244.5 25328.4 25545.4 25637.8 44678.0 44998.9 45060.0 45922.9 45947.5 37211.6 37314.4 38144.3 38199.8 38278.6 37298.6 37467.4 37521.0 38561.4 38698.0 36104.1 36120.1 36308.1 36314.8 36353.6 32025.5 32695.8 33473.7 33880.7 33984.5 20258.1 20612.6 24733.5 25101.6 25313.9 00:15:47 INFO - PROCESS | 1647 | 00:15:47 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:31565.57 Median:19423.60 stddev:77009.45 (396.5%) stddev-sans-first:77189.72 00:15:47 INFO - PROCESS | 1647 | Values: 310.3 370.3 371.5 386.7 398.4 14689.3 19932.3 20249.9 20789.7 21136.3 19777.3 20118.8 20610.8 20712.6 20738.5 4931.5 5330.7 5507.9 5514.0 6199.9 3915.6 4110.0 5820.2 6056.9 6192.8 6125.8 6149.2 6153.3 6156.7 6161.6 6170.4 7699.2 8134.4 8180.6 8193.0 7666.6 7748.2 7798.9 7865.5 7891.1 24128.0 29419.3 29802.0 29836.4 30052.5 2717.5 3358.7 3414.2 3486.0 3524.4 16451.3 17088.8 17441.9 17836.0 18309.0 1822.4 1829.2 1829.4 1871.7 1894.6 135691.0 152115.0 152714.2 152805.2 156070.2 21142.3 21289.6 21565.6 21672.8 21890.9 35111.9 35195.1 35322.8 35347.1 35366.5 10668.2 10717.4 10866.3 11046.5 11201.5 7506.5 8904.2 8956.2 8974.9 9213.8 9500.8 9575.5 9620.4 9782.5 9814.0 2818.9 3209.0 3369.4 3649.9 3685.7 366.5 390.8 398.3 803.1 917.0 11460.7 11700.3 12021.6 12033.4 12214.4 419.8 419.9 423.0 433.9 447.9 5996.6 6023.7 6034.4 6196.9 6326.8 290570.0 292133.0 302045.0 600248.0 607213.0 74961.0 80073.0 82604.0 133763.0 152825.0 982.6 993.2 1008.1 1022.1 1060.7 18926.2 19124.0 19184.5 19241.4 19254.2 42906.0 54144.2 54682.7 56239.7 56656.7 83449.6 85462.0 85951.0 86297.6 86889.1 7817.4 8075.2 8330.4 8368.8 8516.7 9306.4 13140.5 13153.7 13204.7 13566.3 10527.0 11267.5 11348.7 11414.5 11628.9 11077.4 11209.3 11448.0 11592.9 11634.4 9894.0 10035.2 10208.9 11560.4 11740.9 7233.0 7371.0 7443.4 7585.6 7603.0 9070.1 11206.0 11269.7 11547.7 11704.4 00:15:47 INFO - PROCESS | 1647 | 00:15:47 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5603.91 Median:5038.50 stddev:3107.02 (61.7%) stddev-sans-first:3100.07 00:15:47 INFO - PROCESS | 1647 | Values: 1451.4 1471.7 1599.3 1758.5 1796.2 8337.7 8892.9 8901.1 8908.2 8923.5 7847.9 8080.8 8425.5 8691.9 8708.3 2312.6 2355.8 2360.4 2362.9 2369.0 2327.3 2345.4 2372.9 2382.0 2384.4 2071.9 2312.3 2355.2 2366.7 2374.5 2335.4 2349.8 2359.6 2361.2 2363.4 2312.9 2313.4 2317.3 2317.4 2327.8 8831.6 8834.2 8932.0 8932.7 8937.1 4984.3 5038.9 5161.2 5164.1 5170.9 3459.8 3541.5 3605.0 3626.5 3633.8 1048.8 1086.3 1086.9 1102.7 1108.3 10579.7 10674.6 10686.4 10690.3 10692.4 4254.9 4317.8 4495.1 4498.7 4510.9 8993.9 8995.1 9007.6 9019.6 9026.7 5086.2 5090.8 5092.9 5095.1 5095.8 3653.3 4524.5 4726.0 4726.3 4843.8 3822.1 3859.5 3929.3 3972.0 3978.8 1257.7 1261.1 1265.5 1273.2 1282.7 786.1 857.8 891.1 895.6 926.0 5561.4 5667.3 5798.2 5926.3 5929.4 871.9 878.7 901.8 921.3 968.3 3853.8 3858.4 3890.3 3995.8 3996.8 10259.0 10740.5 10755.4 10756.0 10761.4 10678.5 10685.7 10693.1 10713.9 10721.1 7699.8 7974.6 8066.0 8110.8 8143.5 5348.8 5847.7 5982.2 6036.8 6089.8 8382.2 9268.2 9931.0 9967.7 10042.9 9965.0 10149.8 10165.8 10268.3 10304.0 6949.2 6954.8 6993.5 7496.6 7617.4 8188.2 8468.3 8471.0 8474.7 8475.1 7336.5 7463.1 7484.1 7543.2 7586.0 7194.3 7238.6 7305.3 7436.4 7445.8 6784.1 6945.5 6946.3 6949.0 6958.9 4884.7 4942.0 5038.0 5046.2 5066.1 4913.6 4928.4 5098.8 5207.8 5214.0 00:15:47 INFO - PROCESS | 1647 | 00:15:47 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4415.09 Median:4696.24 stddev:1379.12 (29.4%) stddev-sans-first:1347.89 00:15:47 INFO - PROCESS | 1647 | Values: 296.3 300.1 305.1 309.1 311.7 4382.9 4714.9 4819.6 5023.9 5303.7 4374.8 4426.5 4486.4 5248.7 5282.6 3559.5 3981.8 3982.6 3997.4 3998.4 3390.4 3638.1 3903.3 4031.6 4080.3 3976.3 3993.2 4013.8 4016.3 4039.6 3815.4 3992.8 4038.4 4053.7 4067.9 2887.8 3416.2 3432.6 3548.4 3567.1 4660.9 4755.6 5202.5 5225.7 5260.4 4562.5 4571.5 4574.9 4584.8 4627.4 5040.4 5318.9 5465.1 5543.7 5654.0 3381.6 3700.7 3767.1 3843.2 3894.5 4812.4 5224.9 5581.2 5901.1 5955.9 3490.9 3769.8 3822.5 3933.0 3947.8 3299.4 3859.6 3873.7 3880.7 3889.7 3783.5 4075.3 4277.8 4530.2 4544.7 5202.5 5251.9 5537.6 5650.2 5659.7 1292.0 1319.8 1321.0 1321.0 1328.9 707.6 793.8 799.5 805.5 814.6 3949.8 3975.5 4129.5 4451.3 4483.8 4273.9 4371.8 4705.8 4787.0 4843.6 4203.9 4324.4 4348.8 4356.6 4364.9 2568.6 2710.9 2886.3 3010.9 3061.2 5276.6 5370.2 5815.7 5838.3 5979.6 4977.6 5580.6 5899.8 5916.0 5970.7 4686.7 5248.5 5929.7 6010.5 6042.4 4707.4 5845.7 5845.8 5882.9 6018.4 5551.8 5774.5 5798.7 5834.7 5859.7 5178.3 5636.4 5638.7 5885.6 5911.7 3780.4 3829.3 3844.9 3864.2 3876.0 4891.9 5346.7 5825.0 5829.0 5833.5 5312.0 5344.9 5627.9 5638.6 5643.5 5596.8 5610.3 5615.4 5633.6 5642.2 4620.7 4652.7 5152.9 5464.9 5508.8 4659.2 4823.6 5543.9 5617.5 5622.4 5554.3 5560.9 5561.4 5572.4 5588.4 00:15:47 INFO - PROCESS | 1647 | 00:15:47 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:25350.81 Median:3319.48 stddev:41057.28 (1236.9%) stddev-sans-first:41130.56 00:15:47 INFO - PROCESS | 1647 | Values: 648.4 693.2 719.0 729.0 743.7 15265.8 16006.7 16007.3 16028.5 16066.3 14380.7 14465.9 14467.2 14512.6 15010.8 834.5 856.5 858.1 858.6 861.3 853.9 856.3 856.6 857.5 858.9 892.6 897.6 898.1 899.8 901.6 777.2 883.0 886.0 887.7 887.7 589.7 671.4 672.4 673.1 675.1 12986.1 13035.2 13045.2 13052.2 13073.5 11755.9 11915.2 11930.5 12183.9 12227.0 10751.4 10928.8 11072.3 11170.0 11433.3 3598.5 3622.2 3663.2 3666.4 3795.5 115186.4 115370.0 115533.0 116082.0 117335.4 8770.0 9847.6 10462.4 10801.9 11002.0 21539.4 21595.4 22070.3 22123.1 22125.4 7904.4 8077.1 8083.5 8195.0 8226.5 4479.0 4492.7 4494.1 4630.2 4716.5 3040.5 3721.6 3731.4 3733.5 3847.7 715.9 718.7 718.8 728.4 739.8 595.7 624.3 667.2 669.7 674.2 8816.6 8842.1 9567.7 9703.3 9843.6 543.8 544.6 557.5 585.6 624.3 4336.4 4500.1 4682.9 4821.4 5004.8 87675.1 122650.4 163377.2 163391.2 165735.0 135510.0 135845.0 137175.0 142466.0 145065.8 97507.5 117548.4 126060.1 126523.0 127821.4 9941.6 9989.8 10000.2 10019.6 10032.2 64705.7 65089.0 66494.3 66920.3 68244.0 65851.3 91556.0 93921.0 94423.0 102130.5 11394.9 12954.3 13220.0 13227.0 13666.0 14943.3 15009.0 15214.9 15427.8 15588.8 13627.9 13752.7 13812.0 13854.8 13883.4 13770.7 13987.8 14103.6 14566.9 14580.6 13510.6 13828.4 13836.7 13885.4 14010.6 11380.4 11466.3 11566.5 11701.5 11942.0 10312.7 10935.5 11102.7 11427.7 11640.7 00:15:47 INFO - PROCESS | 1647 | -------- Summary: end -------- 00:15:47 INFO - PROCESS | 1647 | 00:15:48 INFO - TEST-INFO | 1647: exit 0 00:15:48 INFO - TEST-OK | dromaeo_css | took 1117740ms 00:15:48 INFO - TEST-START | kraken 00:15:48 INFO - operating with platform_type : mac_ 00:15:48 INFO - Initialising browser for kraken test... 00:15:48 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpmy1Cst/profile http://localhost:49272/getInfo.html) 00:15:50 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 00:15:50 INFO - PROCESS | 1654 | colorDepth:24 00:15:50 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 00:15:50 INFO - PROCESS | 1654 | __metrics 00:15:51 INFO - PROCESS | 1654 | 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" 00:15:51 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:15:51 INFO - TEST-INFO | 1654: exit 0 00:15:51 INFO - Browser initialized. 00:15:51 INFO - Running cycle 1/1 for kraken test... 00:15:51 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpmy1Cst/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:16:03 INFO - PROCESS | 1655 | RSS: Main: 234668032 00:16:03 INFO - PROCESS | 1655 | 00:16:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 00:16:07 INFO - PROCESS | 1655 | RSS: Main: 291840000 00:16:07 INFO - PROCESS | 1655 | 00:16:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 00:16:10 INFO - PROCESS | 1655 | RSS: Main: 287875072 00:16:10 INFO - PROCESS | 1655 | 00:16:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 00:16:14 INFO - PROCESS | 1655 | RSS: Main: 378589184 00:16:14 INFO - PROCESS | 1655 | 00:16:16 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 00:16:17 INFO - PROCESS | 1655 | RSS: Main: 280932352 00:16:17 INFO - PROCESS | 1655 | 00:16:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:16:20 INFO - PROCESS | 1655 | RSS: Main: 283144192 00:16:20 INFO - PROCESS | 1655 | 00:16:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 00:16:23 INFO - PROCESS | 1655 | RSS: Main: 321671168 00:16:23 INFO - PROCESS | 1655 | 00:16:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 00:16:27 INFO - PROCESS | 1655 | RSS: Main: 321875968 00:16:27 INFO - PROCESS | 1655 | 00:16:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 00:16:30 INFO - PROCESS | 1655 | RSS: Main: 320802816 00:16:30 INFO - PROCESS | 1655 | 00:16:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:16:32 INFO - PROCESS | 1655 | RSS: Main: 298717184 00:16:32 INFO - PROCESS | 1655 | 00:16:34 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:16:35 INFO - PROCESS | 1655 | RSS: Main: 303448064 00:16:35 INFO - PROCESS | 1655 | 00:16:37 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:16:38 INFO - PROCESS | 1655 | RSS: Main: 298713088 00:16:38 INFO - PROCESS | 1655 | 00:16:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:16:41 INFO - PROCESS | 1655 | RSS: Main: 318472192 00:16:41 INFO - PROCESS | 1655 | 00:16:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:16:45 INFO - PROCESS | 1655 | RSS: Main: 314429440 00:16:45 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 00:16:47 INFO - PROCESS | 1655 | RSS: Main: 357961728 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | __start_tp_report 00:16:47 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 00:16:47 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 00:16:47 INFO - PROCESS | 1655 | |i|pagename|runs| 00:16:47 INFO - PROCESS | 1655 | |0;ai-astar;111;113;148;168;143;112;114;112;113;114 00:16:47 INFO - PROCESS | 1655 | |1;audio-beat-detection;149;119;163;197;124;130;144;108;159;107 00:16:47 INFO - PROCESS | 1655 | |2;audio-dft;184;183;185;184;185;182;181;180;185;182 00:16:47 INFO - PROCESS | 1655 | |3;audio-fft;74;77;73;177;82;58;57;59;137;58 00:16:47 INFO - PROCESS | 1655 | |4;audio-oscillator;82;83;136;69;71;76;114;69;68;112 00:16:47 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;88;85;87;86;87;85;89;85;92;130 00:16:47 INFO - PROCESS | 1655 | |6;imaging-darkroom;97;96;97;97;126;159;106;146;160;112 00:16:47 INFO - PROCESS | 1655 | |7;imaging-desaturate;85;86;87;86;84;86;86;84;86;84 00:16:47 INFO - PROCESS | 1655 | |8;json-parse-financial;51;53;53;54;53;53;54;52;54;55 00:16:47 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;64;66;71;70;73;78;73;70;70;72 00:16:47 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;88;86;84;82;92;87;89;85;89;107 00:16:47 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;137;142;136;139;137;138;138;143;202;154 00:16:47 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;171;170;172;171;175;172;171;171;171;173 00:16:47 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;66;67;69;68;66;66;65;66;68;67 00:16:47 INFO - PROCESS | 1655 | __end_tp_report 00:16:47 INFO - PROCESS | 1655 | __start_cc_report 00:16:47 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2016 00:16:47 INFO - PROCESS | 1655 | __end_cc_report 00:16:47 INFO - PROCESS | 1655 | __startTimestamp1472541407350__endTimestamp 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | ------- Summary: start ------- 00:16:47 INFO - PROCESS | 1655 | Number of tests: 14 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:124.80 Median:114.00 stddev:20.45 (17.9%) stddev-sans-first:21.08 00:16:47 INFO - PROCESS | 1655 | Values: 111.0 113.0 148.0 168.0 143.0 112.0 114.0 112.0 113.0 114.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:146.50 stddev:28.18 (19.2%) stddev-sans-first:29.70 00:16:47 INFO - PROCESS | 1655 | Values: 149.0 119.0 163.0 197.0 124.0 130.0 144.0 108.0 159.0 107.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:1.79 (1.0%) stddev-sans-first:1.87 00:16:47 INFO - PROCESS | 1655 | Values: 184.0 183.0 185.0 184.0 185.0 182.0 181.0 180.0 185.0 182.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:85.20 Median:66.00 stddev:40.03 (60.7%) stddev-sans-first:42.26 00:16:47 INFO - PROCESS | 1655 | Values: 74.0 77.0 73.0 177.0 82.0 58.0 57.0 59.0 137.0 58.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:88.00 Median:70.00 stddev:23.97 (34.2%) stddev-sans-first:25.33 00:16:47 INFO - PROCESS | 1655 | Values: 82.0 83.0 136.0 69.0 71.0 76.0 114.0 69.0 68.0 112.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:91.40 Median:87.00 stddev:13.74 (15.8%) stddev-sans-first:14.52 00:16:47 INFO - PROCESS | 1655 | Values: 88.0 85.0 87.0 86.0 87.0 85.0 89.0 85.0 92.0 130.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:119.60 Median:128.00 stddev:26.36 (20.6%) stddev-sans-first:26.66 00:16:47 INFO - PROCESS | 1655 | Values: 97.0 96.0 97.0 97.0 126.0 159.0 106.0 146.0 160.0 112.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:1.07 (1.2%) stddev-sans-first:1.13 00:16:47 INFO - PROCESS | 1655 | Values: 85.0 86.0 87.0 86.0 84.0 86.0 86.0 84.0 86.0 84.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.50 stddev:1.14 (2.1%) stddev-sans-first:0.88 00:16:47 INFO - PROCESS | 1655 | Values: 51.0 53.0 53.0 54.0 53.0 53.0 54.0 52.0 54.0 55.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.50 stddev:3.86 (5.4%) stddev-sans-first:3.24 00:16:47 INFO - PROCESS | 1655 | Values: 64.0 66.0 71.0 70.0 73.0 78.0 73.0 70.0 70.0 72.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:87.50 stddev:6.97 (8.0%) stddev-sans-first:7.38 00:16:47 INFO - PROCESS | 1655 | Values: 88.0 86.0 84.0 82.0 92.0 87.0 89.0 85.0 89.0 107.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:140.50 stddev:20.17 (14.4%) stddev-sans-first:21.09 00:16:47 INFO - PROCESS | 1655 | Values: 137.0 142.0 136.0 139.0 137.0 138.0 138.0 143.0 202.0 154.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:171.50 stddev:1.42 (0.8%) stddev-sans-first:1.48 00:16:47 INFO - PROCESS | 1655 | Values: 171.0 170.0 172.0 171.0 175.0 172.0 171.0 171.0 171.0 173.0 00:16:47 INFO - PROCESS | 1655 | 00:16:47 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.23 (1.8%) stddev-sans-first:1.27 00:16:47 INFO - PROCESS | 1655 | Values: 66.0 67.0 69.0 68.0 66.0 66.0 65.0 66.0 68.0 67.0 00:16:47 INFO - PROCESS | 1655 | -------- Summary: end -------- 00:16:47 INFO - PROCESS | 1655 | 00:16:48 INFO - TEST-INFO | 1655: exit 0 00:16:48 INFO - TEST-OK | kraken | took 59592ms 00:16:48 INFO - SUITE-END | took 1177s 00:16:48 INFO - Completed test suite (00:19:37) 00:16:48 INFO - javascript benchmark 00:16:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3338.73290136789, 3368.00643810076, 3377.054424761066, 3432.5709286339206, 3448.7585868989163, 5625.156226566017, 5739.741711622976, 5748.0, 5864.442877926595, 5874.441928016842, 5744.885102297962, 5746.712664366786, 5850.502707269877, 5867.794627188049, 5940.257467816523, 5188.455212202721, 5235.738213089349, 5237.633365664405, 5246.554042906358, 5379.300690910196, 5384.111621582434, 5384.3807962084275, 5387.811426600069, 5396.97457483079, 5420.430854760252, 4176.540580536139, 5151.716655583941, 5214.296070030536, 5215.348081489814, 5308.23030660552, 4329.285667864799, 5254.395744489394, 5259.132243179871, 5264.552513036397, 5277.736113194325, 5183.5853131749745, 5204.68771873685, 5210.166373380241, 5236.9738151309, 5253.763580638881, 5693.202951586781, 5694.032014557535, 5726.656400615976, 5852.941470585282, 5896.938551060807, 6277.340879207663, 6326.304106548276, 6327.778527751507, 6338.809835704937, 6351.872962540723, 5038.571721403685, 6208.161898143738, 6252.0, 6314.842128946767, 6396.616203027833, 5832.708364581838, 5835.299764028301, 5871.060630299132, 5912.379200184005, 5927.881442371172, 6256.217972753406, 6268.968655156695, 6280.685965701697, 6294.527910406738, 6304.558680892385, 6074.058520929263, 6124.755009799647, 6129.693515324216, 6202.844928876724, 6211.192544969215, 6204.162438070893, 6222.377762223741, 6238.189035425366, 6263.498920086382, 6268.059791031382, 6210.7515699372425, 6219.595726277777, 6220.7511699532415, 6223.782167624111, 6228.128062071313, 4956.727379994135, 6110.2550082019825, 6188.659623720739, 6203.2556093269095, 6262.968685156545, 4964.106460837084, 4975.004999000214, 5023.522765337287, 5024.0956627807345, 5044.520770526794, 4478.171538265431, 4478.596926276586, 4482.148391805546, 4525.502194758574, 4560.543945605413, 5753.712314384264, 5794.072948328331, 5800.071988481823, 5801.12983052537, 5814.156947242711, 6131.110988906584, 6201.6899155042065, 6206.565540412128, 6254.155688981929, 6275.96862015687, 5669.34802497718, 5700.25896633435, 5717.942820571741, 5747.339056008529, 5752.884942301131, 5501.559875209973, 5504.256925315111, 5531.308586426697, 5540.556755459554, 5547.944520554824, 5334.893302133858, 6325.146105275729, 6329.430351268408, 6352.618842869442, 6356.269029061532, 6119.807225076679, 6364.904526432199, 6378.968105159444, 6380.840478988062, 6384.521160912857, 5689.260396148474, 5997.970010150096, 6247.531435142473, 6333.90499142504, 6351.015592583157, 6183.938160618516, 6217.316095229611, 6248.562600618095, 6255.9374406257175, 6261.436470717657, 6029.246344206974, 6127.892078906731, 6336.049592561153, 6337.9366206337345, 6385.106085148176, 6106.491003209229, 6129.3564175762685, 6131.223750760645, 6200.658963757037, 6201.069839524108, 6162.476189523941, 6168.19813424252, 6173.32093469727, 6179.351168127282, 6202.038684003986, 5803.506701930383, 5991.310999234969, 6028.703828676856, 6047.765985553472, 6054.594508808104, 4792.832250871203, 4921.8523444296725, 5751.74117164737, 6079.452849243589, 6163.445289923749, 5941.732622032106, 5971.880562388815, 5973.104034394875, 5984.551158663031, 6031.095335699505], "value": 5701.961318399739, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.8252993982045, 530.1993989076493, 534.4495169974924, 550.5347980956091, 552.6822077305548, 30311.848440757793, 38385.80807095964, 39737.8013109934, 39762.40356394649, 39897.401538976905, 41659.37510937331, 42014.15971680565, 42056.57943420565, 42079.57920420795, 42237.36643950343, 9732.805343893118, 9917.90082099177, 9917.90082099177, 9925.057119573628, 9942.701718948407, 8761.299096072331, 8903.554822258893, 11910.583129590466, 12137.757244855098, 12179.51281948721, 12092.0, 12133.939330303336, 12134.817977730341, 12156.270623762603, 12164.817527737046, 8768.561571921378, 12114.939425302906, 12170.208936419192, 12195.634130976085, 12198.268103913704, 11644.59243926467, 11974.820377694341, 12012.399380030964, 12236.327002014885, 12237.816432753516, 39495.21009579821, 39695.40456893149, 39785.20429591392, 39852.0, 39862.202755944716, 3230.1924518870283, 3404.4042292598942, 3409.829508524564, 3496.6153723090692, 3695.815209239554, 30026.549601755993, 30173.698263017308, 30910.072697818876, 30998.0, 31030.534541981888, 3280.2291461506525, 3282.917927051819, 3354.3626710924846, 3392.7625066245373, 3400.25194457219, 494595.0, 495882.5205873984, 497508.0, 499105.0, 500033.99999999633, 56336.15495767485, 56596.15105773417, 56783.0, 56882.0, 56993.71503142458, 59222.0, 59228.0, 59262.0, 59298.703506483056, 59419.0, 15948.0, 16140.35438582467, 16165.272562734704, 16167.676646467005, 16174.595635109217, 11414.828777568344, 11678.824817627743, 12648.114631975857, 12730.0, 12747.171433856769, 15021.399144034116, 15305.923470382577, 15420.383184672712, 15605.063696178266, 15764.921175394278, 5884.350005492006, 5893.850699113632, 5973.163757074013, 6010.699465026819, 6048.36492168316, 748.1321666866409, 871.4205053639386, 948.2698322291934, 976.5361453309621, 981.2885657897967, 28858.567121493194, 29062.41875162485, 29275.414491710046, 29963.101106966828, 30054.69945300563, 1000.7998400319965, 1002.3811544355958, 1049.6378749331468, 1099.3513826842202, 1116.821308590638, 14636.853631463762, 15262.923685381502, 15314.208059793897, 15462.304196311194, 15590.298436570154, 628449.0, 629269.0, 630882.0, 634175.0, 634370.7036620423, 55492.445075549535, 55618.88762224733, 55663.443365566636, 55811.44188558062, 55850.0, 1206.6078524479578, 1246.4827096754746, 1300.5123078845434, 1331.6670832291927, 1345.7510360583324, 27904.720952790212, 29519.26201844885, 30213.09360719182, 30588.694113058584, 31067.067987960396, 49685.50314496809, 54146.0, 54273.0, 54327.0, 54391.0, 568194.0, 572193.0, 573805.0, 575380.0, 575920.0, 24882.626760598967, 25244.49511009806, 25328.366790830376, 25545.361365966, 25637.846296917058, 44678.0, 44998.87502812456, 45060.0, 45922.851928700715, 45947.540524594326, 37211.6278837219, 37314.44028339631, 38144.340118383225, 38199.80900095482, 38278.61721382751, 37298.627013729514, 37467.43798843074, 37521.0, 38561.421578676905, 38698.03254918649, 36104.097397565274, 36120.09699757422, 36308.09229769277, 36314.8184259077, 36353.636463635026, 32025.519617206228, 32695.836520817244, 33473.665263348026, 33880.66119338775, 33984.490232647026, 20258.092781965202, 20612.587748245252, 24733.505329893662, 25101.623475648244, 25313.873430632728], "value": 19852.840263547674, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.250744452148, 370.28719714549504, 371.49662207708553, 386.66939766499627, 398.3844959537514, 14689.265536723162, 19932.302369417073, 20249.886256255937, 20789.68815467767, 21136.260230891923, 19777.307794227207, 20118.792872427664, 20610.793892061076, 20712.585748285026, 20738.481537961557, 4931.457539670633, 5330.733463326837, 5507.9449205507835, 5514.0, 6199.907001394983, 3915.569287378386, 4109.979450102745, 5820.243368362107, 6056.878862422749, 6192.81421557354, 6125.754969801203, 6149.2313460817395, 6153.292371377282, 6156.6613836238985, 6161.599496032766, 6170.352113028135, 7699.191584883591, 8134.389920755967, 8180.6318715657935, 8193.0, 7666.616669166575, 7748.2251774822635, 7798.9220107798765, 7865.488743231729, 7891.092524359715, 24128.0, 29419.264518386997, 29801.95693150751, 29836.403271934658, 30052.54921176184, 2717.4565086982484, 3358.664133586646, 3414.163529935157, 3486.0, 3524.3832329342263, 16451.25969331383, 17088.829111708972, 17441.912790436094, 17836.0, 18308.9930053847, 1822.3621732393556, 1829.231722676479, 1829.4054432309554, 1871.721744680003, 1894.5737209127974, 135691.0, 152115.0, 152714.23642881715, 152805.23597381942, 156070.21964890216, 21142.260020899503, 21289.574208515744, 21565.56868862614, 21672.78327216739, 21890.89054554717, 35111.94664160079, 35195.120121996646, 35322.823385882904, 35347.11632209164, 35366.46950295748, 10668.199885008655, 10717.35695858251, 10866.348019118877, 11046.502907369188, 11201.495932683049, 7506.512076714996, 8904.19862212404, 8956.193942545202, 8974.910250897537, 9213.81572368549, 9500.809983800285, 9575.473348965734, 9620.422774633544, 9782.51087445625, 9814.0, 2818.873150708223, 3208.989168411943, 3369.4440417330866, 3649.8905032849057, 3685.6682898538725, 366.4741096526493, 390.78897395406733, 398.33875766227925, 803.1406395157243, 916.9587368568297, 11460.656180314603, 11700.2979821211, 12021.639350819489, 12033.39833008346, 12214.389280536116, 419.8383622305373, 419.9496060472763, 422.9534751177368, 433.89586499239954, 447.917135329965, 5996.5502587305255, 6023.69881505923, 6034.366391528914, 6196.907046394308, 6326.810195694136, 290570.0, 292133.0, 302045.0, 600248.0, 607213.0, 74961.0, 80073.0, 82604.0, 133763.0, 152825.0, 982.5774916785708, 993.1806259835664, 1008.0876806490138, 1022.1311884897926, 1060.734816295926, 18926.242950282387, 19124.0, 19184.520386990436, 19241.4227573173, 19254.22983080661, 42906.0, 54144.187837183264, 54682.726586366814, 56239.71880140573, 56656.71671641615, 83449.58275208827, 85462.0, 85951.0, 86297.62405122604, 86889.1311086881, 7817.413693972861, 8075.192480751878, 8330.416870818985, 8368.83262334762, 8516.74449766508, 9306.416984732674, 13140.540081097115, 13153.736925261632, 13204.669883252996, 13566.253856038013, 10527.0, 11267.492962816528, 11348.659540213806, 11414.543418263176, 11628.88371116278, 11077.390743509184, 11209.271397359145, 11448.026917712088, 11592.942035289769, 11634.36011019402, 9894.010598940049, 10035.19718422539, 10208.897911020795, 11560.364179970182, 11740.882591173979, 7233.0, 7371.0, 7443.36731377839, 7585.582792946441, 7602.961985190039, 9070.092990700878, 11206.0, 11269.66191014271, 11547.653570392902, 11704.414779261173], "value": 10073.005534764476, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.3614009835671, 1471.7056588682262, 1599.264338404334, 1758.4724582625208, 1796.1917137288215, 8337.708180213695, 8892.8666070009, 8901.109889011095, 8908.153725396092, 8923.464592124468, 7847.92152078479, 8080.757577272677, 8425.494470331785, 8691.869621955675, 8708.303335733142, 2312.5952958232283, 2355.8350915435926, 2360.374500757292, 2362.893669784864, 2368.988155059222, 2327.2785436514737, 2345.413646588353, 2372.8694921779297, 2381.988090059556, 2384.403899025244, 2071.91224607081, 2312.3294244669028, 2355.187460326185, 2366.680498132756, 2374.4538756086113, 2335.404471859682, 2349.753275906031, 2359.634256690216, 2361.2207971369407, 2363.373705967911, 2312.9421764456024, 2313.363824948136, 2317.2816426907543, 2317.43222910388, 2327.755585663498, 8831.646734130627, 8834.249088827457, 8931.97282312536, 8932.687355942577, 8937.106289371075, 4984.327115839388, 5038.949610503884, 5161.1742121260795, 5164.070467315882, 5170.896582068338, 3459.844307006191, 3541.5041894134843, 3605.0446631642635, 3626.546681664792, 3633.8001409922445, 1048.8479170520231, 1086.2721976275914, 1086.8858769829212, 1102.73534351755, 1108.279618248137, 10579.682609521727, 10674.62638807638, 10686.412247326394, 10690.251682382237, 10692.358458492594, 4254.851080212177, 4317.784110794448, 4495.123450927038, 4498.6626003049905, 4510.909781804346, 8993.865092023625, 8995.055519170393, 9007.63969441228, 9019.639214431481, 9026.729198123936, 5086.21163719624, 5090.821821236313, 5092.872678183001, 5095.133827249377, 5095.847124586194, 3653.2693461307845, 4524.524924882906, 4725.976370118196, 4726.338312636233, 4843.806247750051, 3822.140018495816, 3859.498265225559, 3929.31237033518, 3971.980140099281, 3978.8010599469912, 1257.7295881385546, 1261.0983147049903, 1265.544404014554, 1273.1597145883857, 1282.7172827172826, 786.0920636664662, 857.8327226190958, 891.0955380288882, 895.6462197431978, 925.9999200863186, 5561.360443549043, 5667.319921609434, 5798.188253644493, 5926.348101708809, 5929.436703513159, 871.8953725552974, 878.6748902905965, 901.7835719427421, 921.2583869984727, 968.3076600230728, 3853.8265778040054, 3858.382658774639, 3890.2997460456822, 3995.820188091543, 3996.780177090288, 10259.025392587691, 10740.516676749565, 10755.40845253503, 10756.0, 10761.354318740901, 10678.466076696133, 10685.679429617125, 10693.144548436107, 10713.892861071445, 10721.142308615292, 7699.807504812313, 7974.561399123104, 8066.032076150899, 8110.837783244302, 8143.470674406145, 5348.7593058311695, 5847.7368518417625, 5982.169109402317, 6036.8188954331445, 6089.786857459968, 8382.203690649378, 9268.165865072177, 9931.0068993103, 9967.700968970943, 10042.895281519173, 9965.0, 10149.79700405973, 10165.79668406613, 10268.332558383681, 10304.0, 6949.2008419030435, 6954.826129346807, 6993.4754893384215, 7496.587687677012, 7617.352525035212, 8188.181181881764, 8468.280196183394, 8471.0, 8474.66101355939, 8475.110113438, 7336.486445948945, 7463.141738699902, 7484.139323977813, 7543.170251272465, 7586.0, 7194.251773897983, 7238.565686058853, 7305.269473052652, 7436.367908727602, 7445.7766267012075, 6784.118064651564, 6945.548539344926, 6946.340097690711, 6949.027136200835, 6958.860822783618, 4884.682495637772, 4941.975290123526, 5038.042771873333, 5046.1673823818655, 5066.138756411346, 4913.582345500672, 4928.354857377543, 5098.7705553250935, 5207.791688332424, 5214.0], "value": 4518.281064207894, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.29925226838526, 300.11765409695494, 305.0634551925588, 309.0804855554725, 311.66496016781934, 4382.868513944582, 4714.905701885965, 4819.590334821544, 5023.944971555978, 5303.734813259336, 4374.818798924289, 4426.535213802553, 4486.394336764536, 5248.748513703902, 5282.577393808496, 3559.4838748381535, 3981.820818063194, 3982.5818289079666, 3997.4203740457615, 3998.4402183694306, 3390.4405773047542, 3638.1268495561008, 3903.2974064668347, 4031.6371526562602, 4080.2859499587603, 3976.3439032559736, 3993.161436098408, 4013.8394464221396, 4016.3373043447796, 4039.5960403959516, 3815.4055242435134, 3992.8402863885553, 4038.414429907676, 4053.655439287664, 4067.877963661095, 2887.812292201, 3416.214270717749, 3432.648635684564, 3548.361294966905, 3567.1438854674952, 4660.883477913046, 4755.645955252137, 5202.531772140488, 5225.686458812445, 5260.421353651133, 4562.517181915858, 4571.451425828922, 4574.947762014755, 4584.807949933008, 4627.444706635191, 5040.42035165957, 5318.946810531884, 5465.125579907235, 5543.722813859291, 5654.0, 3381.5773028371455, 3700.6862563790005, 3767.1147280389087, 3843.2313537292375, 3894.5006172623744, 4812.422509298872, 5224.876651519903, 5581.218629391929, 5901.0558310670085, 5955.880882382328, 3490.947635785465, 3769.792661403649, 3822.522184726909, 3932.960670393317, 3947.802609869495, 3299.406106900733, 3859.5947425520476, 3873.65137137659, 3880.7477513961503, 3889.6888248940018, 3783.540698545912, 4075.3275709508193, 4277.844981854882, 4530.207213737583, 4544.659325498986, 5202.465272744549, 5251.927622340973, 5537.556995440355, 5650.237217975603, 5659.688717120515, 1291.9935400323127, 1319.7954317080869, 1320.9603711888658, 1321.0356439799, 1328.8936885049172, 707.6426404665611, 793.8332950080547, 799.544259771936, 805.5086397297644, 814.6130587970666, 3949.763014219156, 3975.5229372475487, 4129.525104612948, 4451.3323001550025, 4483.798229079699, 4273.935386566928, 4371.825126994884, 4705.788239529229, 4786.994731106507, 4843.609473368656, 4203.873883783492, 4324.3513472978675, 4348.804303806273, 4356.586124318184, 4364.869053928387, 2568.563344231485, 2710.8509032003035, 2886.278430392402, 3010.864511097006, 3061.224489795913, 5276.63063585553, 5370.248165256867, 5815.680137592389, 5838.299404071538, 5979.611325263844, 4977.601791856643, 5580.553555715533, 5899.793507227312, 5915.970420147958, 5970.686269120592, 4686.695364801345, 5248.501392367795, 5929.73316200772, 6010.549208809357, 6042.425969532887, 4707.4351077869505, 5845.720169134126, 5845.795397161079, 5882.911756323744, 6018.428249316308, 5551.750171242385, 5774.451427114417, 5798.652080875161, 5834.737436815269, 5859.736311866061, 5178.275041494269, 5636.351819540805, 5638.7462564185535, 5885.646861188342, 5911.733971971185, 3780.4140358244517, 3829.291581057513, 3844.884653460401, 3864.2271545691538, 3876.0, 4891.874868780129, 5346.652467589594, 5825.0, 5829.0, 5833.504152146946, 5312.0, 5344.866378340417, 5627.88744225105, 5638.5771067170945, 5643.548516118783, 5596.768710883599, 5610.326760788732, 5615.410381909841, 5633.577481688808, 5642.181883626933, 4620.745858977654, 4652.720836749806, 5152.922706159336, 5464.890702186013, 5508.752106155152, 4659.161350956861, 4823.638227133049, 5543.861403464945, 5617.522510586646, 5622.409646987007, 5554.2779438672715, 5560.858115165081, 5561.443855614406, 5572.442755724395, 5588.3573389060775], "value": 3974.9517524996722, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.4326214562258, 693.1681981622053, 718.9737470167064, 728.9817754556135, 743.6653505922333, 15265.771013434793, 16006.679866402665, 16007.279672414741, 16028.519144425656, 16066.27701753421, 14380.712385752278, 14465.915056370788, 14467.204303763288, 14512.637184070403, 15010.774838377405, 834.5451728807784, 856.4732689396008, 858.107568129148, 858.6436628799072, 861.3410740783274, 853.9359548033891, 856.3320609924269, 856.5845564901018, 857.536930057768, 858.9184027517376, 892.6072528087653, 897.5915958238986, 898.074982767747, 899.7705585075829, 901.603294550399, 777.1529033353615, 883.0463099852176, 885.9689910853153, 887.6715615222345, 887.7203680840514, 589.6933594530863, 671.4292851076546, 672.4385138409434, 673.1081317254657, 675.1493118670461, 12986.09097363185, 13035.217886926719, 13045.152065115737, 13052.21686698801, 13073.542426015043, 11755.882441175565, 11915.225510341887, 11930.463129159209, 12183.93908030463, 12227.0, 10751.354918704903, 10928.836067458995, 11072.335659860435, 11170.0, 11433.314001159957, 3598.4962105305276, 3622.2393297407575, 3663.230721548478, 3666.431703086026, 3795.4876091727538, 115186.42406787912, 115370.0, 115533.0, 116082.0, 117335.41332293369, 8770.0, 9847.606095756233, 10462.372257664565, 10801.891981080089, 11002.0, 21539.353819385444, 21595.352139435843, 22070.33788986333, 22123.115075397127, 22125.446863828533, 7904.367650588054, 8077.111517733045, 8083.514989100555, 8194.959025204837, 8226.547539885243, 4479.0, 4492.73043617384, 4494.10117976406, 4630.21286381316, 4716.504766999416, 3040.467918114321, 3721.627837216257, 3731.4029755239576, 3733.4586484959527, 3847.6537111660086, 715.9176694680074, 718.6909628859536, 718.7592156627588, 728.3626826526789, 739.8372358081214, 595.746807606774, 624.2758400255773, 667.1893649216225, 669.7220653428869, 674.1775034457953, 8816.603252853636, 8842.115788421235, 9567.712968610953, 9703.272254580934, 9843.557039933221, 543.8151028650278, 544.6242092955848, 557.4509108528097, 585.6427579176699, 624.2633692243197, 4336.436263285753, 4500.144972455223, 4682.929756053662, 4821.397325334333, 5004.824831130893, 87675.12324876797, 122650.38674806569, 163377.18311408366, 163391.18304408403, 165735.0, 135510.0, 135845.0, 137175.0, 142466.0, 145065.8240126399, 97507.51246243865, 117548.41225793817, 126060.1090983636, 126523.0, 127821.36089319494, 9941.602335906482, 9989.750256243651, 10000.199984001118, 10019.649312274036, 10032.247581431277, 64705.67647161734, 65089.0, 66494.33505664881, 66920.33079669336, 68244.0, 65851.34148658453, 91556.0, 93921.0, 94423.0, 102130.48934755576, 11394.886051139714, 12954.28751418681, 13220.0, 13227.0, 13666.0, 14943.332589340898, 15009.0, 15214.923925380303, 15427.768583471032, 15588.766168507254, 13627.931860340635, 13752.65618359509, 13812.0, 13854.792178117135, 13883.444662213398, 13770.724585508033, 13987.790183147057, 14103.647408814451, 14566.85433145655, 14580.562583122522, 13510.594682159552, 13828.377723002288, 13836.723265534432, 13885.444582217002, 14010.649733756738, 11380.374079425712, 11466.31202127875, 11566.479508421973, 11701.531938722695, 11942.0, 10312.742181445525, 10935.453227338443, 11102.666919992413, 11427.657170284905, 11640.708982275512], "value": 7828.761872156309, "unit": "score"}], "name": "dromaeo_css", "value": 7370.700648673555, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 148.0, 168.0, 143.0, 112.0, 114.0, 112.0, 113.0, 114.0], "value": 124.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 119.0, 163.0, 197.0, 124.0, 130.0, 144.0, 108.0, 159.0, 107.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 185.0, 184.0, 185.0, 182.0, 181.0, 180.0, 185.0, 182.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 73.0, 177.0, 82.0, 58.0, 57.0, 59.0, 137.0, 58.0], "value": 85.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 136.0, 69.0, 71.0, 76.0, 114.0, 69.0, 68.0, 112.0], "value": 88.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 86.0, 87.0, 85.0, 89.0, 85.0, 92.0, 130.0], "value": 91.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 97.0, 126.0, 159.0, 106.0, 146.0, 160.0, 112.0], "value": 119.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 87.0, 86.0, 84.0, 86.0, 86.0, 84.0, 86.0, 84.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 53.0, 53.0, 54.0, 53.0, 53.0, 54.0, 52.0, 54.0, 55.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 71.0, 70.0, 73.0, 78.0, 73.0, 70.0, 70.0, 72.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 84.0, 82.0, 92.0, 87.0, 89.0, 85.0, 89.0, 107.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 142.0, 136.0, 139.0, 137.0, 138.0, 138.0, 143.0, 202.0, 154.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 172.0, 171.0, 175.0, 172.0, 171.0, 171.0, 171.0, 173.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 68.0, 66.0, 66.0, 65.0, 66.0, 68.0, 67.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1515.4, "alertThreshold": 2.0}]} 00:16:48 INFO - Return code: 0 00:16:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:16:48 INFO - # TBPL SUCCESS # 00:16:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:16:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:16:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:16:48 INFO - 2016-08-30 00:16:48.650 screenresolution[1659:10881] starting screenresolution argv=screenresolution get 00:16:48 INFO - 2016-08-30 00:16:48.653 screenresolution[1659:10881] Display 0: 1600x1200x32@60 00:16:48 INFO - 2016-08-30 00:16:48.659 screenresolution[1660:10885] starting screenresolution argv=screenresolution list 00:16:48 INFO - Available Modes on Display 0 00:16:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:16:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:16:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:16:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:16:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:16:48 INFO - Intel Iris: 00:16:48 INFO - Chipset Model: Intel Iris 00:16:48 INFO - Type: GPU 00:16:48 INFO - Bus: Built-In 00:16:48 INFO - VRAM (Dynamic, Max): 1536 MB 00:16:48 INFO - Vendor: Intel (0x8086) 00:16:48 INFO - Device ID: 0x0a2e 00:16:48 INFO - Revision ID: 0x0009 00:16:48 INFO - Displays: 00:16:48 INFO - Display: 00:16:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:16:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:16:48 INFO - Display Serial Number: 1600x1200 60 00:16:48 INFO - Main Display: Yes 00:16:48 INFO - Mirror: Off 00:16:48 INFO - Online: Yes 00:16:48 INFO - Rotation: Supported 00:16:48 INFO - Return code: 0 00:16:48 INFO - Running post-action listener: _resource_record_post_action 00:16:48 INFO - [mozharness: 2016-08-30 07:16:48.895048Z] Finished run-tests step (success) 00:16:48 INFO - Running post-run listener: _resource_record_post_run 00:16:48 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38284288; Write bytes: 710278656; Read time: 355; Write time: 2002 00:16:48 INFO - TinderboxPrint: CPU usage
53.7% 00:16:48 INFO - TinderboxPrint: I/O read bytes / time
38,284,288 / 355 00:16:48 INFO - TinderboxPrint: I/O write bytes / time
710,278,656 / 2,002 00:16:48 INFO - TinderboxPrint: CPU idle
2,246.3 (46.3%) 00:16:48 INFO - TinderboxPrint: CPU system
969.6 (20.0%) 00:16:48 INFO - TinderboxPrint: CPU user
1,633.8 (33.7%) 00:16:48 INFO - TinderboxPrint: Swap in / out
439,455,744 / 0 00:16:48 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392423936; Write bytes: 388898304; Read time: 29102; Write time: 807 00:16:48 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37325824; Write bytes: 299356160; Read time: 337; Write time: 1140 00:16:49 INFO - Running post-run listener: _upload_blobber_files 00:16:49 INFO - Blob upload gear active. 00:16:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:16:49 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 00:16:49 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:16:49 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:16:49 INFO - (blobuploader) - INFO - Open directory for files ... 00:16:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:16:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:16:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:16:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:16:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:16:51 INFO - (blobuploader) - INFO - Done attempting. 00:16:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:16:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:16:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:16:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:16:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:16:52 INFO - (blobuploader) - INFO - Done attempting. 00:16:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:16:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:16:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:16:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:16:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:16:54 INFO - (blobuploader) - INFO - Done attempting. 00:16:54 INFO - (blobuploader) - INFO - Iteration through files over. 00:16:54 INFO - Return code: 0 00:16:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:16:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:16:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4b328af99f03c4b5a85f75c95bd2f2a1bd6a77955323fb9d9e14ec6e3250f2edbf5ca2aa82b669a960f710f6c30faac0c8d288f035889e26897e3376b463d55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc53d6bb019b5a2728e897bcbd6fefbb8eac721186c75de61faead616928eff2b1fa685d8a73606ce5482214e08bb54fc520bcc95bc84019c17bbbc109d07f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55ca6b1fd09cdebf6d5964ce09e2595c2db07ee33cf8740780d1d33d1e8f4fc2cbd0ec8a15ae44077d6e67afee619e2ea1e604dd734c28f9a4ec6077ca0d0871"} 00:16:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:16:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:16:54 INFO - Contents: 00:16:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4b328af99f03c4b5a85f75c95bd2f2a1bd6a77955323fb9d9e14ec6e3250f2edbf5ca2aa82b669a960f710f6c30faac0c8d288f035889e26897e3376b463d55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc53d6bb019b5a2728e897bcbd6fefbb8eac721186c75de61faead616928eff2b1fa685d8a73606ce5482214e08bb54fc520bcc95bc84019c17bbbc109d07f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55ca6b1fd09cdebf6d5964ce09e2595c2db07ee33cf8740780d1d33d1e8f4fc2cbd0ec8a15ae44077d6e67afee619e2ea1e604dd734c28f9a4ec6077ca0d0871"} 00:16:54 INFO - Running post-run listener: copy_logs_to_upload_dir 00:16:54 INFO - Copying logs to upload dir... 00:16:54 INFO - mkdir: /builds/slave/test/build/upload/logs 00:16:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.679847 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-30 00:16:54.333338) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 00:16:54.336709) ========= 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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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/Fx-Team/sha512/f4b328af99f03c4b5a85f75c95bd2f2a1bd6a77955323fb9d9e14ec6e3250f2edbf5ca2aa82b669a960f710f6c30faac0c8d288f035889e26897e3376b463d55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc53d6bb019b5a2728e897bcbd6fefbb8eac721186c75de61faead616928eff2b1fa685d8a73606ce5482214e08bb54fc520bcc95bc84019c17bbbc109d07f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55ca6b1fd09cdebf6d5964ce09e2595c2db07ee33cf8740780d1d33d1e8f4fc2cbd0ec8a15ae44077d6e67afee619e2ea1e604dd734c28f9a4ec6077ca0d0871"} build_url:https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010505 build_url: 'https://queue.taskcluster.net/v1/task/bHvsR6g7RmCy14w2acRrZg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4b328af99f03c4b5a85f75c95bd2f2a1bd6a77955323fb9d9e14ec6e3250f2edbf5ca2aa82b669a960f710f6c30faac0c8d288f035889e26897e3376b463d55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc53d6bb019b5a2728e897bcbd6fefbb8eac721186c75de61faead616928eff2b1fa685d8a73606ce5482214e08bb54fc520bcc95bc84019c17bbbc109d07f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55ca6b1fd09cdebf6d5964ce09e2595c2db07ee33cf8740780d1d33d1e8f4fc2cbd0ec8a15ae44077d6e67afee619e2ea1e604dd734c28f9a4ec6077ca0d0871"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 00:16:54.368040) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 00:16:54.368325) ========= 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.cagBMjNEg6/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.dRnUOK0Dhe/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.005106 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 00:16:54.416042) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 00:16:54.416338) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 00:16:54.416887) ========= ========= Total master_lag: 0.25 =========