builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0315 starttime: 1470930753.54 results: success (0) buildid: 20160811071321 builduid: 62b54b34e9cb4daaa77f856968cee5cd revision: 039f759b2bc799a861fd32d0bba4558d2e9d1639 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:33.541401) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:33.542325) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:33.542765) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:33.636518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:33.636904) ========= 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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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-11 08:52:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.24M=0.001s 2016-08-11 08:52:34 (8.24 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.524178 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:34.273490) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:34.274028) ========= 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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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.020867 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:34.324510) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 08:52:34.325070) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 039f759b2bc799a861fd32d0bba4558d2e9d1639 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 039f759b2bc799a861fd32d0bba4558d2e9d1639 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SeQCAsEMpx/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.YuP1wDxu1v/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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-11 08:52:34,402 truncating revision to first 12 chars 2016-08-11 08:52:34,402 Setting DEBUG logging. 2016-08-11 08:52:34,402 attempt 1/10 2016-08-11 08:52:34,402 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/039f759b2bc7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-11 08:52:35,597 unpacking tar archive at: autoland-039f759b2bc7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.457358 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 08:52:35.881084) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:35.881349) ========= script_repo_revision: 039f759b2bc799a861fd32d0bba4558d2e9d1639 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:35.881731) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:35.881989) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 08:52:35.903153) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-11 08:52:35.903449) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SeQCAsEMpx/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.YuP1wDxu1v/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 08:52:36 INFO - MultiFileLogger online at 20160811 08:52:36 in /builds/slave/test 08:52:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 08:52:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:52:36 INFO - {'append_to_log': False, 08:52:36 INFO - 'base_work_dir': '/builds/slave/test', 08:52:36 INFO - 'blob_upload_branch': 'Autoland', 08:52:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:52:36 INFO - 'branch': 'Autoland', 08:52:36 INFO - 'buildbot_json_path': 'buildprops.json', 08:52:36 INFO - 'config_files': ('talos/mac_config.py',), 08:52:36 INFO - 'default_actions': ('clobber', 08:52:36 INFO - 'read-buildbot-config', 08:52:36 INFO - 'download-and-extract', 08:52:36 INFO - 'populate-webroot', 08:52:36 INFO - 'create-virtualenv', 08:52:36 INFO - 'install', 08:52:36 INFO - 'run-tests'), 08:52:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:52:36 INFO - 'download_minidump_stackwalk': True, 08:52:36 INFO - 'download_symbols': 'ondemand', 08:52:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:52:36 INFO - 'tooltool.py': '/tools/tooltool.py', 08:52:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:52:36 INFO - '/tools/misc-python/virtualenv.py')}, 08:52:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:52:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:52:36 INFO - 'installer_path': 'installer.exe', 08:52:36 INFO - 'log_level': 'info', 08:52:36 INFO - 'log_name': 'talos', 08:52:36 INFO - 'log_to_console': True, 08:52:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:52:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:52:36 INFO - 'opt_config_files': (), 08:52:36 INFO - 'pip_index': False, 08:52:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:36 INFO - 'cmd': ('bash', 08:52:36 INFO - '-c', 08:52:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:36 INFO - 'enabled': True, 08:52:36 INFO - 'halt_on_failure': False, 08:52:36 INFO - 'name': 'check_screen_resolution'},), 08:52:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:36 INFO - 'cmd': ('bash', 08:52:36 INFO - '-c', 08:52:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:36 INFO - 'enabled': True, 08:52:36 INFO - 'halt_on_failure': False, 08:52:36 INFO - 'name': 'check_screen_resolution'},), 08:52:36 INFO - 'run_cmd_checks_enabled': True, 08:52:36 INFO - 'sps_profile': False, 08:52:36 INFO - 'sps_profile_interval': 0, 08:52:36 INFO - 'suite': 'dromaeojs', 08:52:36 INFO - 'system_bits': '32', 08:52:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:52:36 INFO - 'title': 't-yosemite-r7-0315', 08:52:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:52:36 INFO - 'use_talos_json': True, 08:52:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:52:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:52:36 INFO - 'work_dir': 'build'} 08:52:36 INFO - [mozharness: 2016-08-11 15:52:36.110243Z] Running clobber step. 08:52:36 INFO - Running pre-action listener: _resource_record_pre_action 08:52:36 INFO - Running main action method: clobber 08:52:36 INFO - rmtree: /builds/slave/test/build 08:52:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:52:37 INFO - Running post-action listener: _resource_record_post_action 08:52:37 INFO - [mozharness: 2016-08-11 15:52:37.998875Z] Finished clobber step (success) 08:52:37 INFO - [mozharness: 2016-08-11 15:52:37.999012Z] Running read-buildbot-config step. 08:52:37 INFO - Running pre-action listener: _resource_record_pre_action 08:52:37 INFO - Running main action method: read_buildbot_config 08:52:37 INFO - Using buildbot properties: 08:52:37 INFO - { 08:52:37 INFO - "project": "", 08:52:37 INFO - "product": "firefox", 08:52:37 INFO - "script_repo_revision": "production", 08:52:38 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 08:52:38 INFO - "repository": "", 08:52:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 08:52:38 INFO - "buildid": "20160811071321", 08:52:38 INFO - "pgo_build": "False", 08:52:38 INFO - "basedir": "/builds/slave/test", 08:52:38 INFO - "buildnumber": 74, 08:52:38 INFO - "slavename": "t-yosemite-r7-0315", 08:52:38 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:52:38 INFO - "platform": "yosemite_r7", 08:52:38 INFO - "scheduler": "tests-autoland-macosx64-talos", 08:52:38 INFO - "branch": "autoland", 08:52:38 INFO - "repo_path": "integration/autoland", 08:52:38 INFO - "revision": "039f759b2bc799a861fd32d0bba4558d2e9d1639", 08:52:38 INFO - "stage_platform": "macosx64", 08:52:38 INFO - "builduid": "62b54b34e9cb4daaa77f856968cee5cd", 08:52:38 INFO - "slavebuilddir": "test" 08:52:38 INFO - } 08:52:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:52:38 INFO - Running post-action listener: _resource_record_post_action 08:52:38 INFO - [mozharness: 2016-08-11 15:52:38.002262Z] Finished read-buildbot-config step (success) 08:52:38 INFO - [mozharness: 2016-08-11 15:52:38.002375Z] Running download-and-extract step. 08:52:38 INFO - Running pre-action listener: _resource_record_pre_action 08:52:38 INFO - Running main action method: download_and_extract 08:52:38 INFO - mkdir: /builds/slave/test/build/tests 08:52:38 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')]} 08:52:38 INFO - trying https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:52:38 INFO - Downloading https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/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 08:52:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/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 08:52:39 INFO - Downloaded 1199 bytes. 08:52:39 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:52:39 INFO - Using the following test package requirements: 08:52:39 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:52:39 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:52:39 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:52:39 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:52:39 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:52:39 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:52:39 INFO - u'jsshell-mac.zip'], 08:52:39 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:52:39 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:52:39 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:52:39 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:52:39 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:52:39 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:52:39 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:52:39 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:52:39 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:52:39 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:52:39 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:52:39 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:52:39 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')]} 08:52:39 INFO - trying https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:52:39 INFO - Downloading https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/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 08:52:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/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 08:52:40 INFO - Downloaded 16328247 bytes. 08:52:40 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:52:46 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 08:52:46 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')]} 08:52:46 INFO - trying https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:52:46 INFO - Downloading https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/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 08:52:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/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 08:52:47 INFO - Downloaded 16328247 bytes. 08:52:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:52:54 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')]} 08:52:54 INFO - trying https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:52:54 INFO - Downloading https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/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 08:52:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/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 08:52:56 INFO - Downloaded 14413719 bytes. 08:52:56 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 08:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:56 INFO - trying https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:52:56 INFO - Downloading https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:52:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/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 08:52:59 INFO - Downloaded 110904989 bytes. 08:52:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:52:59 INFO - mkdir: /builds/slave/test/properties 08:52:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:52:59 INFO - Writing to file /builds/slave/test/properties/build_url 08:52:59 INFO - Contents: 08:52:59 INFO - build_url:https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:53:00 INFO - Running post-action listener: _resource_record_post_action 08:53:00 INFO - Running post-action listener: set_extra_try_arguments 08:53:00 INFO - [mozharness: 2016-08-11 15:53:00.111063Z] Finished download-and-extract step (success) 08:53:00 INFO - [mozharness: 2016-08-11 15:53:00.111188Z] Running populate-webroot step. 08:53:00 INFO - Running pre-action listener: _resource_record_pre_action 08:53:00 INFO - Running main action method: populate_webroot 08:53:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:53:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:53:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:53:00 INFO - '--tppagecycles', 08:53:00 INFO - '7'], 08:53:00 INFO - 'tests': ['tsvgm']}}, 08:53:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:53:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:53:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:53:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:53:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:53:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:53:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:00 INFO - 'talos_options': ['--disable-e10s'], 08:53:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:00 INFO - 'talos_options': ['--disable-e10s'], 08:53:00 INFO - 'tests': ['damp', 'tps']}, 08:53:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:00 INFO - 'tests': ['damp', 'tps']}, 08:53:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:53:00 INFO - 'tests': ['dromaeo_dom']}, 08:53:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:53:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:53:00 INFO - 'tests': ['basic_compositor_video']}, 08:53:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:53:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:53:00 INFO - 'tests': ['a11yr', 08:53:00 INFO - 'ts_paint', 08:53:00 INFO - 'tpaint', 08:53:00 INFO - 'sessionrestore', 08:53:00 INFO - 'sessionrestore_no_auto_restore', 08:53:00 INFO - 'tabpaint']}, 08:53:00 INFO - 'other-e10s': {'tests': ['a11yr', 08:53:00 INFO - 'ts_paint', 08:53:00 INFO - 'tpaint', 08:53:00 INFO - 'sessionrestore', 08:53:00 INFO - 'sessionrestore_no_auto_restore', 08:53:00 INFO - 'tabpaint']}, 08:53:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:53:00 INFO - 'ts_paint', 08:53:00 INFO - 'tpaint', 08:53:00 INFO - 'sessionrestore', 08:53:00 INFO - 'sessionrestore_no_auto_restore', 08:53:00 INFO - 'tabpaint']}, 08:53:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:53:00 INFO - 'ts_paint', 08:53:00 INFO - 'tpaint', 08:53:00 INFO - 'sessionrestore', 08:53:00 INFO - 'sessionrestore_no_auto_restore', 08:53:00 INFO - 'tabpaint']}, 08:53:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:53:00 INFO - 'tests': ['a11yr', 08:53:00 INFO - 'ts_paint', 08:53:00 INFO - 'tpaint', 08:53:00 INFO - 'sessionrestore', 08:53:00 INFO - 'sessionrestore_no_auto_restore', 08:53:00 INFO - 'tabpaint']}, 08:53:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:53:00 INFO - 'tests': ['a11yr', 08:53:00 INFO - 'ts_paint', 08:53:00 INFO - 'tpaint', 08:53:00 INFO - 'sessionrestore', 08:53:00 INFO - 'sessionrestore_no_auto_restore', 08:53:00 INFO - 'tabpaint']}, 08:53:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:53:00 INFO - 'tests': ['tsvgx', 08:53:00 INFO - 'tsvgr_opacity', 08:53:00 INFO - 'tart', 08:53:00 INFO - 'tscrollx', 08:53:00 INFO - 'cart']}, 08:53:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:53:00 INFO - 'tsvgr_opacity', 08:53:00 INFO - 'tart', 08:53:00 INFO - 'tscrollx', 08:53:00 INFO - 'cart']}, 08:53:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:00 INFO - 'talos_options': ['--disable-e10s'], 08:53:00 INFO - 'tests': ['tp5o']}, 08:53:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:00 INFO - 'tests': ['tp5o']}, 08:53:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:00 INFO - 'talos_options': ['--disable-e10s', 08:53:00 INFO - '--xperf_path', 08:53:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:00 INFO - 'tests': ['tp5n']}, 08:53:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:00 INFO - 'talos_options': ['--xperf_path', 08:53:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:00 INFO - 'tests': ['tp5n']}}, 08:53:00 INFO - 'talos.zip': {'path': '', 08:53:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:53:00 INFO - Running post-action listener: _resource_record_post_action 08:53:00 INFO - [mozharness: 2016-08-11 15:53:00.130487Z] Finished populate-webroot step (success) 08:53:00 INFO - [mozharness: 2016-08-11 15:53:00.130605Z] Running create-virtualenv step. 08:53:00 INFO - Running pre-action listener: _resource_record_pre_action 08:53:00 INFO - Running main action method: create_virtualenv 08:53:00 INFO - Creating virtualenv /builds/slave/test/build/venv 08:53:00 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 08:53:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:53:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:53:00 INFO - Using real prefix '/tools/python27' 08:53:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:53:01 INFO - Installing distribute.............................................................................................................................................................................................done. 08:53:03 INFO - Installing pip.................done. 08:53:03 INFO - Return code: 0 08:53:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b38e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc0c994a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b25dd30>, '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 0x10afc2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b255b20>, '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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 08:53:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SeQCAsEMpx/Render', 08:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:03 INFO - 'HOME': '/Users/cltbld', 08:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:03 INFO - 'LOGNAME': 'cltbld', 08:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:03 INFO - 'MOZ_NO_REMOTE': '1', 08:53:03 INFO - 'NO_EM_RESTART': '1', 08:53:03 INFO - 'PAGER': '/bin/cat', 08:53:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:03 INFO - 'PWD': '/builds/slave/test', 08:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:03 INFO - 'SHELL': '/bin/bash', 08:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuP1wDxu1v/Listeners', 08:53:03 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 08:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:03 INFO - 'USER': 'cltbld', 08:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:03 INFO - 'XPC_FLAGS': '0x0', 08:53:03 INFO - 'XPC_SERVICE_NAME': '0', 08:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:04 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 08:53:04 INFO - Cleaning up... 08:53:04 INFO - Return code: 0 08:53:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:53:04 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')]} 08:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:04 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')]} 08:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:04 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 0x10b2f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b38e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc0c994a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b25dd30>, '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 0x10afc2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b255b20>, '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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 08:53:04 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 08:53:04 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 08:53:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SeQCAsEMpx/Render', 08:53:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:04 INFO - 'HOME': '/Users/cltbld', 08:53:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:04 INFO - 'LOGNAME': 'cltbld', 08:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:04 INFO - 'MOZ_NO_REMOTE': '1', 08:53:04 INFO - 'NO_EM_RESTART': '1', 08:53:04 INFO - 'PAGER': '/bin/cat', 08:53:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:04 INFO - 'PWD': '/builds/slave/test', 08:53:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:04 INFO - 'SHELL': '/bin/bash', 08:53:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuP1wDxu1v/Listeners', 08:53:04 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 08:53:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:04 INFO - 'USER': 'cltbld', 08:53:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:04 INFO - 'XPC_FLAGS': '0x0', 08:53:04 INFO - 'XPC_SERVICE_NAME': '0', 08:53:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:04 INFO - Downloading/unpacking psutil>=3.1.1 08:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:53:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:07 INFO - Installing collected packages: psutil 08:53:07 INFO - Running setup.py install for psutil 08:53:07 INFO - building 'psutil._psutil_osx' extension 08:53:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:53:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:53:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:53:07 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 08:53:08 INFO - building 'psutil._psutil_posix' extension 08:53:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:53:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:53:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:53:08 INFO - ^ 08:53:08 INFO - 1 warning generated. 08:53:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:53:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:08 INFO - Successfully installed psutil 08:53:08 INFO - Cleaning up... 08:53:08 INFO - Return code: 0 08:53:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b2f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b38e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc0c994a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b25dd30>, '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 0x10afc2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b255b20>, '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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 08:53:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SeQCAsEMpx/Render', 08:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:08 INFO - 'HOME': '/Users/cltbld', 08:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:08 INFO - 'LOGNAME': 'cltbld', 08:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:08 INFO - 'MOZ_NO_REMOTE': '1', 08:53:08 INFO - 'NO_EM_RESTART': '1', 08:53:08 INFO - 'PAGER': '/bin/cat', 08:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:08 INFO - 'PWD': '/builds/slave/test', 08:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:08 INFO - 'SHELL': '/bin/bash', 08:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuP1wDxu1v/Listeners', 08:53:08 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 08:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:08 INFO - 'USER': 'cltbld', 08:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:08 INFO - 'XPC_FLAGS': '0x0', 08:53:08 INFO - 'XPC_SERVICE_NAME': '0', 08:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:53: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 08:53: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 08:53: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 08:53: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 08:53: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 08:53: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 08:53:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:53:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:53:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:53:11 INFO - Installing collected packages: mozsystemmonitor 08:53:11 INFO - Running setup.py install for mozsystemmonitor 08:53:11 INFO - Successfully installed mozsystemmonitor 08:53:11 INFO - Cleaning up... 08:53:11 INFO - Return code: 0 08:53:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:53:11 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')]} 08:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:11 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')]} 08:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:11 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 0x10b2f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b38e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc0c994a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b25dd30>, '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 0x10afc2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b255b20>, '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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 08:53:11 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 08:53:11 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 08:53:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SeQCAsEMpx/Render', 08:53:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:11 INFO - 'HOME': '/Users/cltbld', 08:53:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:11 INFO - 'LOGNAME': 'cltbld', 08:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:11 INFO - 'MOZ_NO_REMOTE': '1', 08:53:11 INFO - 'NO_EM_RESTART': '1', 08:53:11 INFO - 'PAGER': '/bin/cat', 08:53:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:11 INFO - 'PWD': '/builds/slave/test', 08:53:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:11 INFO - 'SHELL': '/bin/bash', 08:53:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuP1wDxu1v/Listeners', 08:53:11 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 08:53:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:11 INFO - 'USER': 'cltbld', 08:53:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:11 INFO - 'XPC_FLAGS': '0x0', 08:53:11 INFO - 'XPC_SERVICE_NAME': '0', 08:53:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:11 INFO - Downloading/unpacking jsonschema==2.5.1 08:53:11 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 08:53:11 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 08:53:11 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 08:53:11 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 08:53:11 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 08:53:11 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 08:53:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:53:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:53:14 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 08:53:14 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 08:53:14 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 08:53:14 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 08:53:14 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 08:53:14 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 08:53:14 INFO - Downloading functools32-3.2.3-2.tar.gz 08:53:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:53:14 INFO - warning: no files found matching '*.txt' 08:53:14 INFO - no previously-included directories found matching 'build' 08:53:14 INFO - no previously-included directories found matching 'dist' 08:53:14 INFO - no previously-included directories found matching '.git*' 08:53:14 INFO - Installing collected packages: jsonschema, functools32 08:53:14 INFO - Running setup.py install for functools32 08:53:14 INFO - warning: no files found matching '*.txt' 08:53:14 INFO - no previously-included directories found matching 'build' 08:53:14 INFO - no previously-included directories found matching 'dist' 08:53:14 INFO - no previously-included directories found matching '.git*' 08:53:14 INFO - Successfully installed jsonschema functools32 08:53:14 INFO - Cleaning up... 08:53:15 INFO - Return code: 0 08:53:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b2f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b38e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc0c994a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b25dd30>, '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 0x10afc2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b255b20>, '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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 08:53:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:53:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SeQCAsEMpx/Render', 08:53:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:15 INFO - 'HOME': '/Users/cltbld', 08:53:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:15 INFO - 'LOGNAME': 'cltbld', 08:53:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:15 INFO - 'MOZ_NO_REMOTE': '1', 08:53:15 INFO - 'NO_EM_RESTART': '1', 08:53:15 INFO - 'PAGER': '/bin/cat', 08:53:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:15 INFO - 'PWD': '/builds/slave/test', 08:53:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:15 INFO - 'SHELL': '/bin/bash', 08:53:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuP1wDxu1v/Listeners', 08:53:15 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 08:53:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:15 INFO - 'USER': 'cltbld', 08:53:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:15 INFO - 'XPC_FLAGS': '0x0', 08:53:15 INFO - 'XPC_SERVICE_NAME': '0', 08:53:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:53:15 INFO - Cleaning up... 08:53:15 INFO - Return code: 0 08:53:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b2f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b38e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc0c994a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b25dd30>, '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 0x10afc2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b255b20>, '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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 08:53:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:53:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SeQCAsEMpx/Render', 08:53:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:15 INFO - 'HOME': '/Users/cltbld', 08:53:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:15 INFO - 'LOGNAME': 'cltbld', 08:53:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:15 INFO - 'MOZ_NO_REMOTE': '1', 08:53:15 INFO - 'NO_EM_RESTART': '1', 08:53:15 INFO - 'PAGER': '/bin/cat', 08:53:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:15 INFO - 'PWD': '/builds/slave/test', 08:53:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:15 INFO - 'SHELL': '/bin/bash', 08:53:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuP1wDxu1v/Listeners', 08:53:15 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 08:53:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:15 INFO - 'USER': 'cltbld', 08:53:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:15 INFO - 'XPC_FLAGS': '0x0', 08:53:15 INFO - 'XPC_SERVICE_NAME': '0', 08:53:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:15 INFO - Downloading/unpacking blobuploader==1.2.4 08:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:17 INFO - Downloading blobuploader-1.2.4.tar.gz 08:53:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:53:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:53:18 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 08:53:18 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 08:53:18 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 08:53:18 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 08:53:18 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 08:53:18 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 08:53:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:53:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:53:18 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 08:53:18 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 08:53:18 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 08:53:18 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 08:53:18 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 08:53:18 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 08:53:19 INFO - Downloading docopt-0.6.1.tar.gz 08:53:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:53:19 INFO - Installing collected packages: blobuploader, requests, docopt 08:53:19 INFO - Running setup.py install for blobuploader 08:53:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:53:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:53:19 INFO - Running setup.py install for requests 08:53:20 INFO - Running setup.py install for docopt 08:53:20 INFO - Successfully installed blobuploader requests docopt 08:53:20 INFO - Cleaning up... 08:53:20 INFO - Return code: 0 08:53:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:20 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 0x10b2f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b38e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc0c994a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b25dd30>, '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 0x10afc2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b255b20>, '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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 08:53:20 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 08:53:20 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 08:53:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SeQCAsEMpx/Render', 08:53:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:20 INFO - 'HOME': '/Users/cltbld', 08:53:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:20 INFO - 'LOGNAME': 'cltbld', 08:53:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:20 INFO - 'MOZ_NO_REMOTE': '1', 08:53:20 INFO - 'NO_EM_RESTART': '1', 08:53:20 INFO - 'PAGER': '/bin/cat', 08:53:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:20 INFO - 'PWD': '/builds/slave/test', 08:53:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:20 INFO - 'SHELL': '/bin/bash', 08:53:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuP1wDxu1v/Listeners', 08:53:20 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 08:53:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:20 INFO - 'USER': 'cltbld', 08:53:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:20 INFO - 'XPC_FLAGS': '0x0', 08:53:20 INFO - 'XPC_SERVICE_NAME': '0', 08:53:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:20 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-4ovx_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:20 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-fb4Vv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:20 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-aZW4JR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:20 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-5iUXey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:21 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-WktwBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:21 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-eGWbTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:21 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-hS8UZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:21 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-rzfECY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:21 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-xeKCmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:21 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-sXNi8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:21 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-SbQX92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:21 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-CCUi7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:21 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-3IRPaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:21 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-nyvTQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:22 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-RXS14B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:22 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-hyak_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:22 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-6KIQDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:53:22 INFO - Running setup.py install for manifestparser 08:53:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:53:22 INFO - Running setup.py install for mozcrash 08:53:22 INFO - Running setup.py install for mozdebug 08:53:22 INFO - Running setup.py install for mozdevice 08:53:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:53:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:53:23 INFO - Running setup.py install for mozfile 08:53:23 INFO - Running setup.py install for mozhttpd 08:53:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:53:23 INFO - Running setup.py install for mozinfo 08:53:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:53:23 INFO - Running setup.py install for mozInstall 08:53:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:53:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:53:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:53:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:53:23 INFO - Running setup.py install for mozleak 08:53:23 INFO - Running setup.py install for mozlog 08:53:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:53:23 INFO - Running setup.py install for moznetwork 08:53:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:53:24 INFO - Running setup.py install for mozprocess 08:53:24 INFO - Running setup.py install for mozprofile 08:53:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:53:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:53:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:53:24 INFO - Running setup.py install for mozrunner 08:53:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:53:24 INFO - Running setup.py install for mozscreenshot 08:53:24 INFO - Running setup.py install for moztest 08:53:24 INFO - Running setup.py install for mozversion 08:53:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:53:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:53:24 INFO - Cleaning up... 08:53:24 INFO - Return code: 0 08:53:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:24 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 0x10b2f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b38e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc0c994a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b25dd30>, '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 0x10afc2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b255b20>, '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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 08:53:24 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 08:53:24 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 08:53:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SeQCAsEMpx/Render', 08:53:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:24 INFO - 'HOME': '/Users/cltbld', 08:53:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:24 INFO - 'LOGNAME': 'cltbld', 08:53:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:24 INFO - 'MOZ_NO_REMOTE': '1', 08:53:24 INFO - 'NO_EM_RESTART': '1', 08:53:24 INFO - 'PAGER': '/bin/cat', 08:53:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:24 INFO - 'PWD': '/builds/slave/test', 08:53:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:24 INFO - 'SHELL': '/bin/bash', 08:53:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuP1wDxu1v/Listeners', 08:53:24 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 08:53:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:24 INFO - 'USER': 'cltbld', 08:53:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:24 INFO - 'XPC_FLAGS': '0x0', 08:53:24 INFO - 'XPC_SERVICE_NAME': '0', 08:53:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:25 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-TjCYC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:25 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)) 08:53:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:25 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-4e3ypS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:25 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)) 08:53:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:25 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-BsaWJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:25 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)) 08:53:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:25 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-STy8W7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:25 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)) 08:53:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:25 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-vzxlRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:25 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)) 08:53:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:25 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-FwF4Eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:25 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)) 08:53:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:25 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-fx6k3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:26 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)) 08:53:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:26 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-9HbYE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:26 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)) 08:53:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:26 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-s2uAkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:26 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)) 08:53:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:26 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-u6iqHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:26 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)) 08:53:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:26 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-rILbDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:26 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)) 08:53:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:26 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-IjlROS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:26 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)) 08:53:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:26 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-MZF5ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:26 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)) 08:53:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:26 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-WCFCDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:26 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)) 08:53:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:26 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-oRiF34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:26 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)) 08:53:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:26 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-BpYPs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:26 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)) 08:53:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:26 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-6IWo7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:27 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)) 08:53:27 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)) 08:53:27 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)) 08:53:27 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)) 08:53:27 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)) 08:53:27 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)) 08:53:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:29 INFO - Downloading blessings-1.6.tar.gz 08:53:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:53:29 INFO - Installing collected packages: blessings 08:53:29 INFO - Running setup.py install for blessings 08:53:29 INFO - Successfully installed blessings 08:53:29 INFO - Cleaning up... 08:53:29 INFO - Return code: 0 08:53:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:53:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:30 INFO - Reading from file tmpfile_stdout 08:53:30 INFO - Current package versions: 08:53:30 INFO - blessings == 1.6 08:53:30 INFO - blobuploader == 1.2.4 08:53:30 INFO - docopt == 0.6.1 08:53:30 INFO - functools32 == 3.2.3-2 08:53:30 INFO - jsonschema == 2.5.1 08:53:30 INFO - manifestparser == 1.1 08:53:30 INFO - mozInstall == 1.12 08:53:30 INFO - mozcrash == 0.17 08:53:30 INFO - mozdebug == 0.1 08:53:30 INFO - mozdevice == 0.48 08:53:30 INFO - mozfile == 1.2 08:53:30 INFO - mozhttpd == 0.7 08:53:30 INFO - mozinfo == 0.9 08:53:30 INFO - mozleak == 0.1 08:53:30 INFO - mozlog == 3.3 08:53:30 INFO - moznetwork == 0.27 08:53:30 INFO - mozprocess == 0.23 08:53:30 INFO - mozprofile == 0.28 08:53:30 INFO - mozrunner == 6.12 08:53:30 INFO - mozscreenshot == 0.1 08:53:30 INFO - mozsystemmonitor == 0.3 08:53:30 INFO - moztest == 0.7 08:53:30 INFO - mozversion == 1.4 08:53:30 INFO - psutil == 3.1.1 08:53:30 INFO - requests == 1.2.3 08:53:30 INFO - wsgiref == 0.1.2 08:53:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:30 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 0x10b2f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b38e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc0c994a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b25dd30>, '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 0x10afc2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b255b20>, '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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 08:53:30 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 08:53:30 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 08:53:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SeQCAsEMpx/Render', 08:53:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:30 INFO - 'HOME': '/Users/cltbld', 08:53:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:30 INFO - 'LOGNAME': 'cltbld', 08:53:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:30 INFO - 'MOZ_NO_REMOTE': '1', 08:53:30 INFO - 'NO_EM_RESTART': '1', 08:53:30 INFO - 'PAGER': '/bin/cat', 08:53:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:30 INFO - 'PWD': '/builds/slave/test', 08:53:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:30 INFO - 'SHELL': '/bin/bash', 08:53:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuP1wDxu1v/Listeners', 08:53:30 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 08:53:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:30 INFO - 'USER': 'cltbld', 08:53:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:30 INFO - 'XPC_FLAGS': '0x0', 08:53:30 INFO - 'XPC_SERVICE_NAME': '0', 08:53:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:30 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)) 08:53:30 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)) 08:53:30 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)) 08:53:30 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)) 08:53:30 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)) 08:53:30 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)) 08:53:30 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)) 08:53:30 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)) 08:53:30 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)) 08:53:30 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)) 08:53:30 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)) 08:53:30 INFO - Cleaning up... 08:53:30 INFO - Return code: 0 08:53:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:30 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 0x10b2f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b38e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc0c994a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b25dd30>, '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 0x10afc2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b255b20>, '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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 08:53:30 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 08:53:30 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 08:53:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SeQCAsEMpx/Render', 08:53:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:30 INFO - 'HOME': '/Users/cltbld', 08:53:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:30 INFO - 'LOGNAME': 'cltbld', 08:53:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:30 INFO - 'MOZ_NO_REMOTE': '1', 08:53:30 INFO - 'NO_EM_RESTART': '1', 08:53:30 INFO - 'PAGER': '/bin/cat', 08:53:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:30 INFO - 'PWD': '/builds/slave/test', 08:53:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:30 INFO - 'SHELL': '/bin/bash', 08:53:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuP1wDxu1v/Listeners', 08:53:30 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 08:53:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:30 INFO - 'USER': 'cltbld', 08:53:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:30 INFO - 'XPC_FLAGS': '0x0', 08:53:30 INFO - 'XPC_SERVICE_NAME': '0', 08:53:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:53:30 INFO - Cleaning up... 08:53:30 INFO - Return code: 0 08:53:30 INFO - Running post-action listener: _resource_record_post_action 08:53:30 INFO - Running post-action listener: _start_resource_monitoring 08:53:30 INFO - Starting resource monitoring. 08:53:30 INFO - [mozharness: 2016-08-11 15:53:30.690134Z] Finished create-virtualenv step (success) 08:53:30 INFO - [mozharness: 2016-08-11 15:53:30.691132Z] Running install step. 08:53:30 INFO - Running pre-action listener: _resource_record_pre_action 08:53:30 INFO - Running main action method: install 08:53:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:30 INFO - Reading from file tmpfile_stdout 08:53:30 INFO - Detecting whether we're running mozinstall >=1.0... 08:53:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:53:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:53:31 INFO - Reading from file tmpfile_stdout 08:53:31 INFO - Output received: 08:53:31 INFO - Usage: mozinstall [options] installer 08:53:31 INFO - Options: 08:53:31 INFO - -h, --help show this help message and exit 08:53:31 INFO - -d DEST, --destination=DEST 08:53:31 INFO - Directory to install application into. [default: 08:53:31 INFO - "/builds/slave/test"] 08:53:31 INFO - --app=APP Application being installed. [default: firefox] 08:53:31 INFO - mkdir: /builds/slave/test/build/application 08:53:31 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'] 08:53:31 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 08:54:04 INFO - Reading from file tmpfile_stdout 08:54:04 INFO - Output received: 08:54:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:54:04 INFO - Running post-action listener: _resource_record_post_action 08:54:04 INFO - [mozharness: 2016-08-11 15:54:04.961491Z] Finished install step (success) 08:54:04 INFO - [mozharness: 2016-08-11 15:54:04.961628Z] Running run-tests step. 08:54:04 INFO - Running pre-action listener: _resource_record_pre_action 08:54:04 INFO - Running main action method: run_tests 08:54:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:54:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:54:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:54:04 INFO - 2016-08-11 08:54:04.976 screenresolution[1981:11054] starting screenresolution argv=screenresolution get 08:54:04 INFO - 2016-08-11 08:54:04.978 screenresolution[1981:11054] Display 0: 1600x1200x32@60 08:54:04 INFO - 2016-08-11 08:54:04.985 screenresolution[1982:11057] starting screenresolution argv=screenresolution list 08:54:04 INFO - Available Modes on Display 0 08:54:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:54:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:54:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:54:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:54:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:54:05 INFO - Intel Iris: 08:54:05 INFO - Chipset Model: Intel Iris 08:54:05 INFO - Type: GPU 08:54:05 INFO - Bus: Built-In 08:54:05 INFO - VRAM (Dynamic, Max): 1536 MB 08:54:05 INFO - Vendor: Intel (0x8086) 08:54:05 INFO - Device ID: 0x0a2e 08:54:05 INFO - Revision ID: 0x0009 08:54:05 INFO - Displays: 08:54:05 INFO - Display: 08:54:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:54:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:54:05 INFO - Display Serial Number: 1600x1200 60 08:54:05 INFO - Main Display: Yes 08:54:05 INFO - Mirror: Off 08:54:05 INFO - Online: Yes 08:54:05 INFO - Rotation: Supported 08:54:05 INFO - Return code: 0 08:54:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:54:05 INFO - Python 2.7.3 08:54:05 INFO - Return code: 0 08:54:05 INFO - grabbing minidump binary from tooltool 08:54:05 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')]} 08:54:05 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 0x10b25dd30>, '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 0x10afc2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b255b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:54:05 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 08:54:05 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 08:54:05 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 08:54:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:54:05 INFO - Return code: 0 08:54:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:54:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:54:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:54:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:54:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:54:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0315', '--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 08:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0315 --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 08:54:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SeQCAsEMpx/Render', 08:54:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:05 INFO - 'HOME': '/Users/cltbld', 08:54:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:05 INFO - 'LOGNAME': 'cltbld', 08:54:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:54:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:05 INFO - 'MOZ_NO_REMOTE': '1', 08:54:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:54:05 INFO - 'NO_EM_RESTART': '1', 08:54:05 INFO - 'PAGER': '/bin/cat', 08:54:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:05 INFO - 'PWD': '/builds/slave/test', 08:54:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:54:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:05 INFO - 'SHELL': '/bin/bash', 08:54:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuP1wDxu1v/Listeners', 08:54:05 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 08:54:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:05 INFO - 'USER': 'cltbld', 08:54:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:05 INFO - 'XPC_FLAGS': '0x0', 08:54:05 INFO - 'XPC_SERVICE_NAME': '0', 08:54:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0315', '--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 08:54:05 INFO - mozversion application_buildid: 20160811071321 08:54:05 INFO - mozversion application_changeset: 039f759b2bc799a861fd32d0bba4558d2e9d1639 08:54:05 INFO - mozversion application_display_name: Nightly 08:54:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:54:05 INFO - mozversion application_name: Firefox 08:54:05 INFO - mozversion application_remotingname: firefox 08:54:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:54:05 INFO - mozversion application_vendor: Mozilla 08:54:05 INFO - mozversion application_version: 51.0a1 08:54:05 INFO - mozversion platform_buildid: 20160811071321 08:54:05 INFO - mozversion platform_changeset: 039f759b2bc799a861fd32d0bba4558d2e9d1639 08:54:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:54:05 INFO - mozversion platform_version: 51.0a1 08:54:05 INFO - using testdate: 1470930845 08:54:05 INFO - actual date: 1470930845 08:54:05 INFO - starting webserver on 'localhost:49393' 08:54:05 INFO - SUITE-START | Running 2 tests 08:54:05 INFO - TEST-START | dromaeo_css 08:54:05 INFO - operating with platform_type : mac_ 08:54:05 INFO - Initialising browser for dromaeo_css test... 08:54:05 INFO - TEST-INFO | started process 1992 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpVdPbHx/profile http://localhost:49393/getInfo.html) 08:54:08 INFO - PROCESS | 1992 | __metrics Screen width/height:1600/1200 08:54:08 INFO - PROCESS | 1992 | colorDepth:24 08:54:08 INFO - PROCESS | 1992 | Browser inner width/height: 1024/689 08:54:08 INFO - PROCESS | 1992 | __metrics 08:54:08 INFO - PROCESS | 1992 | 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" 08:54:08 INFO - PROCESS | 1992 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:54:09 INFO - TEST-INFO | 1992: exit 0 08:54:09 INFO - Browser initialized. 08:54:09 INFO - Running cycle 1/1 for dromaeo_css test... 08:54:09 INFO - TEST-INFO | started process 1994 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpVdPbHx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:54:21 INFO - PROCESS | 1994 | RSS: Main: 243388416 08:54:21 INFO - PROCESS | 1994 | 08:55:12 INFO - PROCESS | 1994 | 1470930912723 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] 08:57:24 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49393/tests/dromaeo/cssquery-ext.html) 08:57:26 INFO - PROCESS | 1994 | RSS: Main: 356556800 08:57:26 INFO - PROCESS | 1994 | 09:00:29 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-ext.html (next: http://localhost:49393/tests/dromaeo/cssquery-jquery.html) 09:00:29 INFO - PROCESS | 1994 | RSS: Main: 388280320 09:00:29 INFO - PROCESS | 1994 | 09:03:32 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49393/tests/dromaeo/cssquery-mootools.html) 09:03:32 INFO - PROCESS | 1994 | RSS: Main: 377540608 09:03:32 INFO - PROCESS | 1994 | 09:06:35 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49393/tests/dromaeo/cssquery-prototype.html) 09:06:35 INFO - PROCESS | 1994 | RSS: Main: 351862784 09:06:35 INFO - PROCESS | 1994 | 09:09:39 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49393/tests/dromaeo/cssquery-yui.html) 09:09:39 INFO - PROCESS | 1994 | RSS: Main: 340619264 09:09:39 INFO - PROCESS | 1994 | 09:12:43 INFO - PROCESS | 1994 | Cycle 1(1): loaded http://localhost:49393/tests/dromaeo/cssquery-yui.html (next: http://localhost:49393/tests/dromaeo/cssquery-dojo.html) 09:12:43 INFO - PROCESS | 1994 | RSS: Main: 555732992 09:12:43 INFO - PROCESS | 1994 | 09:12:43 INFO - PROCESS | 1994 | __start_tp_report 09:12:43 INFO - PROCESS | 1994 | _x_x_mozilla_page_load 09:12:43 INFO - PROCESS | 1994 | _x_x_mozilla_page_load_details 09:12:43 INFO - PROCESS | 1994 | |i|pagename|runs| 09:12:43 INFO - PROCESS | 1994 | |0;dojo.html;3217.4852023676217;3247.935041299174;3258.706716395524;3285.0965984354316;3309.32158907424;5252.002119597283;5334.946650533493;5367;5402.216678581603;5460.044492213861;5374.059539580578;5377.462253774621;5385.272988146599;5385.865353366168;5388.488093631108;4918.434380046296;4959.330490383806;4964.87587810304;5023.824166154185;5033.974830125844;4974.527419895104;5065.620078494109;5066.949330506703;5070.08738427083;5091;4979.502049795028;4986.35177426934;4986.476419975905;4992.32603598515;5006.148954677714;4189.895252618709;5062.417821950486;5065.721385323805;5077.314562534048;5100;4079.5920407959266;4882.096812089775;4901.289313049588;4976.253561965698;5023.146065168893;5329.973350133225;5346.465353464661;5401.918971215435;5404.027275090482;5422.91865622016;5797.884042319172;5861.443162899518;5871.060630299175;5874.882502349929;5886.175935369051;5694.9715251423895;5752.769889204426;5855.06318988964;5903.468687818117;5920.822375328747;5499.312585926759;5522.5858060644805;5543.1685247213245;5550.250716153268;5551.139573366135;6100.359462256488;6103.267607886994;6114.052321890114;6118.418750218722;6121.959266924633;5590.580706447033;5705.71471426427;5730.570207234474;5782.739776710058;5803.8549036274435;5923.555733320019;5931.4958228550195;5940.138679891392;5964.254468191476;5981.461668449861;5835.503982161479;5837;5852.473277405054;5853.707314634251;5856;5649.322081350182;5690.146478028329;5696.62971906833;5839.386864379179;5846.532277417797;4673.602743766818;4678.625709943196;4745.240761478147;4752.073345697556;4755.857324280277;4261.083866968616;4262.403263543107;4272.33778764292;4295.806688699016;4311.116221174651;5382.407935127133;5388.380336261302;5392.649477783932;5402.108652072443;5442.129259318569;5705.172749951317;5730.598858079978;5851.678157701367;5854.18041474194;5860.120981852756;5432.022235997559;5436.510714035756;5443.8094666686475;5452.809151679672;5509.5041446270025;5170.6122040847085;5190.6107041972;5201.245819356172;5202.3497062867145;5251;5933.792317269048;5955.047192449188;6211.503079753699;6216.875662486725;6235.438810507076;5827.76688932438;5882.352941176553;5884.32330282023;5918.526517878473;5952.077427998667;4972.676776009548;6113.694315284307;6162.414570615784;6177.598456100339;6193.287771906288;4844.878878028077;5559.6664200148125;5617.887642247214;5744.569157313134;5764.991126552753;5753.165790960371;5786.392428795084;5798.217240672455;5818.156367326798;5869.7945571904775;4967.82612608557;5050.813058931075;5632.127020311858;5646;5646.0683987141665;5549.916751248654;5588.720563971867;5617.43825617435;5629.887402252014;5686.857828554319;5587.44125587438;5595.692236926845;5639.746211420578;5664.971675141598;5686.147077938343;5671.489565939085;5677.630953987978;5693.28833895763;5700.458456446631;5703.572232082527;5428.348598168245;5483.259759932517;5500.202470641814;5592.161175823659;5606.85982850432 09:12:43 INFO - PROCESS | 1994 | |1;ext.html;466.82127627739976;475.99286010709824;517.1028265958562;520.9869753256171;524.1508755815579;30666.84666576667;40600.796996015015;41616.58383416161;41622.791886040526;41681.79159104208;42590.787046064805;43006.35490467645;43045.56954430455;43149.35275970855;43194.7041588752;8460.534670593115;9576.569054392552;9758.560864761068;9775.90224097757;9781.804363912717;9662.033796620351;9765;9784.07051330126;9795.412275263472;9796.412215267106;9781.706548803548;9784.07051330126;9842.557084931195;9848.965858584817;9850.556724947395;9727.756806079833;9779.657711980046;9785.07041831025;9802.558884850163;9824.115829575372;9697.612095516242;9770.560324785401;9782.972787857314;9795.167410770093;9800.215982721436;40473.1905361894;40769.592304077174;40812.591874081176;40817;40872.38691419631;3435.089701229176;3449.6722811332884;3453.2748122893972;3456.2569047654874;3523.453864650983;31358.37283254322;31588.842055789573;31714.682853171405;32176.6782332176;32756;3183.697548732867;3227.128675257667;3256.8860089896975;3264.069740124053;3383.915402114942;376310;376814.1159294213;377392.99999999726;378112;378151;59296.40703592995;59920.70039649774;59980.10029849468;60167.39832601618;60179.69910150509;34323.656763432045;35465.64534354623;35491.4676279856;36438.27123457569;63346.68326658337;16222.432214872422;16388.918055409886;16391.91804040996;16394.09832459226;16403.67192656164;12489.375531223403;12569.434375453127;12596.307203103917;12603.558875439316;12679;18080.82474639144;18996.57556057444;19027.61944761097;19459.604411757482;19581;6521;8102.230288122619;8117.431779775359;8190;8327.458715183615;881.8897637795276;896.1576118448637;949.3307218411004;988.2193067476613;1038.4184856480395;28714;29815.701842981292;30436.54345184824;30478.847605761828;32140.357192856012;880.55972013993;1150.4880328253846;1163.528770847808;1189.7501524679915;1221.9450124744408;13514.662133446742;15819.446319378996;15839;15844.44544440962;16197.51407457778;688452;694538;695100;696213;696658;59382;59685.70157149187;59788.402115978286;59838.401615984156;59927.40072599305;1248.4881198708485;1296.6693493159185;1371.7805151175764;1396.76255036644;1407.971840563183;27061.86469067721;29961.550576741804;31351.84324078365;31452.370952581277;32130.35739285248;50098.49901500938;50794.492055078976;50853.49146508487;50868.49131508637;50874.982500350525;521337;522993;523403;524509.7549024613;525944;23499.76500234976;24108.276751696776;26059.869700651376;26981.460370792865;27335.58996615092;43494;47351.05297894092;47502.287465687354;48095.75952120217;48439.5156048435;39205.01987450336;39395.80302098471;39768.2046359077;40542.79728601338;41007.58992410038;39127.413088804264;39832.80083599564;40521;40527.59472405356;40752.79623601863;30967.535486967263;37499.06252343713;38856.22287554177;39097.02257443587;39152.412713809885;32759.67240327566;33917.83041084779;34234.828825856704;34713.13217169591;34759.652403475644;22306.888465557568;23812.57124572531;25705.858828234053;25783.226503204933;25844.35389115212 09:12:43 INFO - PROCESS | 1994 | |2;jquery.html;351.9032266126813;369.8243334416153;370.82015222617025;374.8182131666142;397.6639739420191;14700.26498675066;15122.243887805607;20432.795672043274;20506.17975280987;21156.57686846262;19849.70225446616;20261.189552417924;20270;20636.587268254625;20736.89631551844;4970.527799859025;5258.6581872178285;5492.670439773607;5496.587755918302;5506.64206826557;5940.910886336708;6139.048447490646;6141.293751218623;6143.569950103494;6164.4143806338325;5858.384869588695;6077.209962704842;6085.574009819315;6090.299615544202;6166.6916654167335;4982.725950072744;6160.537959653044;6165.106059621375;6169.537284703621;6186.969065154668;5163.199704045879;5429.429909859447;5495.230667706524;5520;5957.910631340534;12746.745065098647;12991.480340786356;12997.675058123623;21372.572548548942;24913.252602421955;2081.198738485694;2092.2886218685567;2119.512512122205;2268.194790849258;2337.649352597107;9737.756556086084;10128.03783640553;10244.334118282288;12979.156354837;14957;1746.755454236408;1882.397632757518;1886.141805478504;1900.3633782682818;1903.0960293860444;115641;128368;129648;129781.35109324487;132074.99999999904;8043.718469853527;8113.391495637733;10111.848322275171;10243.795124097476;11384.658460246206;12317.50729970809;12382.26648269243;12411.317377544321;12411.75176496483;12417.31704756229;6596.96701516499;7905;8014.19858014194;8014.998125234346;8090.474119182234;8309;8324.958375208207;8328.792325112825;8329.583520823935;8496;3178.7933784303946;3863.469427524976;3867.7872717000837;3916.981584787976;3924.5682974872743;1886.9622607547772;1928.0937959159173;1932.3333449959741;1932.7294178814695;1933.2943475631293;868.4224990381325;884.3190743127884;888.1518150166576;889.3330002498126;955.894851566341;20268.98655067241;20428.795712042986;21184.94075296229;21320.14719411237;21433.249836255658;875;902.6795487601938;912.7124955638952;975.8194733974237;975.9219262459128;10548.525316360781;11140.665780026613;11289.887101128883;11294.491747871365;11330.546778128948;585991;588061.9100943828;597570;597878;600530;108226.91773082326;112202;121192;121662;129401;938.5494962417941;940.4921342474987;975.7804493988938;977.8142152990908;979.8187335342984;16028.599285017968;17107.716921231207;18402.954534111785;19064.809351906857;19175.712364314266;36834;52323.29090381884;54852;54933.951054886085;57147.714261428424;79359;79911.4039868219;80615.59692201501;81125.69868711608;82870;5615.606907516435;8065.717699880476;8196.713115040944;8216.383771217303;8296.714009328563;9235.1688348049;9577.138057574852;11183.608573700207;13219.967909787607;13283.80074298867;11188.944055279671;11236.269642473213;11382.715432114263;11416.657500275005;11418.771624567296;9229.953850230975;10899.945500272448;11045.005949464587;11056.723581910195;11360.943195283971;11321.377324247245;11506.252093613888;11532.94233528827;11624.360660163773;11664.416779161178;7596.506227095221;7871.33093687043;8183.672653094048;8186.672533098609;8237.752867413987;8323.334133269203;10249.795004100026;11324.037456816262;11359.829602556134;11529.308241505536 09:12:43 INFO - PROCESS | 1994 | |3;mootools.html;1350.993509835173;1492.917889516077;1537.3850459816072;1706.5051135170802;1879.605282890593;7669.079710434748;8561;8570.785730356736;8732.039475657688;8871;8636.56817159141;8725.912740872589;8730.126987301282;8734.344924130684;8743.038265790758;2289.9656505152434;2311.7919387255142;2313.0747700919596;2317.8493397929083;2323.488832456857;2263.20787724296;2298.8160947124275;2299.942501437469;2300.7584203658625;2304.320225533463;2261.8529795563236;2291.633338665814;2293.392251053472;2296.276672848056;2298.6092364298024;2251.594712951668;2258.06290389489;2267.263139479676;2274.5792028474702;2275.4538910661486;2065.39070974063;2151.0212853151934;2277.0436416705024;2277.920272790461;2280.315905228425;8655.35084868631;8747.30021598277;8748.34387420946;8750;8762.86855697165;5057.241413787924;5209.374875014984;5215.774293041132;5224.92162617561;5277.3139491866195;2776.818670336674;3080.5841211436395;3509.666581674737;3558.1282585766394;3604.9098772530897;919.6045700348848;1072.9892701072968;1084.5878566144893;1099.5601759296346;1104.5692180049787;11208.047315978152;11238.381888996102;11404.372759498225;11405;11411.714707132305;4479.932801007987;4482.21561226784;4676.69601475903;4682.859514214579;4708.858734237979;9952.552135153936;9954.352967057263;9988.300818942604;9992.450415227233;10011.649592264235;5165.354330708661;5210.869728256748;5235.319408476874;5245.396779370345;5247.081760691864;4218.451601291874;4295.634871035997;4428.778561071934;4675.719456832601;4797.616190704735;3865.7100717446306;3892.9099852041472;3978.204359128186;3978.781167035783;3979.7413168143976;1218.2629509147016;1256.7360854220717;1257.220523275575;1272.662744372742;1278.9808152877715;869.9821209185268;929.2380248196414;931.2689538712078;953.2135987810084;992.6376872441651;5827.5920685552455;5844.9415505845245;5918.704064796829;5979.730912108966;6164.7842325518395;355.65501463580284;385.5219527785526;410.46844336584195;423.36706623597917;442.7697597249477;3986.3422535281934;4015.8393664253686;4094.6724262058965;4135.234981528367;4136.544980052192;10395.94802026;10644.042036216622;10748.623798167027;10762.139028877671;10769.24615276923;10708.732281692864;10719.946400267949;10722.571097156026;10723.195760318007;10789.19081068923;7983.5209887406945;8137.918620813716;8167.918320816716;8297.419180657416;8299.128591497927;5752.395998420106;5891.440313170242;5923.170756094237;5923.940760592339;5987.850303742442;9940.204783617148;9950.154236889946;9997.900020999698;10024.649137280161;10027.749306267402;8994.82010359776;9219.861702074608;10172.237082219011;10175.847362289423;10186.898131018595;7553.886691699739;8028.197180282159;8137.349012078901;8189.549574773207;8344.7913802153;7996.920415744033;8230.176982301962;8291.58542072882;8317.001959764866;8317.41778075529;7413.406927445899;7532.585707786125;7535.020447341811;7561.3194812467145;7673.808154796175;7346.302101300368;7391.852162956819;7416.629168541659;7423.962880185564;7517.924820751722;6853.4859885507785;7036.331548502884;7076.080109585927;7169.820754481179;7176.569405835667;4810.254410566367;4999.750012499288;5032.220005899091;5041.168207245763;5259.605529585373;4958.380202474691;5008.824691135793;5124.128898087409;5140.871478213075;5309.548688361378 09:12:43 INFO - PROCESS | 1994 | |4;prototype.html;262.3048920361043;294.9542820862767;299.72724820413424;311.6602902835908;313.4264296337702;5238.423773384925;5287.656302340345;5318.787248510055;5360.731963401824;5388.299521062267;4951.207806750921;4977.975110124454;5115.232715092738;5136.101182293102;5251.107311757001;2920.3721199942006;3395.2530443302435;4026.9663819770976;4038.818253178599;4045.5954404559457;3471.4098603237385;4013.5986401359924;4021.9597804022023;4037.394390841368;4041.6564592009713;3252.333271679312;3369.9216250799755;3754.8122593870335;4001.8799436016966;4013.177298653769;3015.381846721417;3218.6942240487115;4001.5598284188864;4005.83976640934;4022.9597704022876;3295.719863811555;3465.705415039725;3475.304939012208;3494.5107684924133;3495.8077305748175;4475.820967161309;4828.044641920028;5119.923201151985;5141.922871156936;5154.639175257734;4224.957750422487;4379.737215767064;4554.157480866051;4572.9314060289125;4605.539446055401;5473.589480788917;5523.972380138114;5614.978607701224;5636.505366672689;5645;3795.2599243147597;3803.4675145479655;3830.4637350770913;3844.2311537692294;3848.8652897148745;4693.55411235932;5532.502074813246;5739.397363276879;5755.913661295084;5808.854778630568;3433.8454769535433;3457.6369481204615;3591.3715099857423;3812.4281357796553;3844.2695887781233;3459.187091033605;3923.489946306962;3935.2916475034767;3937.8031098445535;3946.2502124596235;4167.5832416758085;4557.339185818038;4557.749323787158;4585.197590421662;4587.839425620087;5273.736313184402;5328.813491527778;5505.724713764296;5520.972395137998;5532.6680399175375;1271.8728127187392;1300.466808608466;1324.6622111361542;1327.8672132786837;1329.7274058817918;721.3904250907974;776.9006855299688;789.9960500197462;877.8727084572829;897.8877640294963;4170.270202714512;4409.713368631029;4930.13722598551;5047;5117.974410128;5565.415631358649;5674.177244299636;5680.403557626472;5701.258836351248;5750.281214848144;4508.4174585056835;4898.853034408973;4941.8633714245925;5033.647644664911;5086.16078347076;2904.8547572621706;2936.588877557144;2950.963834953875;2973.910782676523;2975.2710585906157;5012.47369026247;5252.606054545924;5419.783208671609;5868.67722275279;5874.530037596982;5110.437851836295;5449.591280653967;5805;5845.457226106657;5917.704114794243;4902.803887844589;5705.657660540381;5912;5973.49225315853;6006.369331220247;4708.905821883612;5336.359636843604;5693.800716974886;5710.602835816315;5738.68437235956;4910.3125562422;5112.74436278192;5189.636725429184;5658;5665.433456654301;5408.810691625774;5909.099994003936;5916.408359164049;5943.346231914399;5954.915779477148;3251.837408129537;3652.872149474862;3817.102980799454;3873;3876.1860009398342;5603.898538048232;5704.229928959538;5723.198752174782;5726.911886741505;5727.8854422912145;4684.859454216379;5355.544778693698;5643.125315576092;5651.660900345993;5722.828315150552;4649.628029757679;5075.492450754895;5222.634415590872;5546.36216835053;5627.692646015278;4632.4904260531985;5200.557952574073;5357.892842143213;5502.119660854404;5533.5849811265125;5133.281340612392;5439.129739241703;5442.673439593479;5609.242752228397;5683.175939488834;5038.2190760432195;5472.753726082256;5499.807506737245;5525.502704756591;5548 09:12:43 INFO - PROCESS | 1994 | |5;yui.html;716.8566286742652;726.7492715013321;776.8290975985285;828.2214718164925;832.3008672714926;14939.25303734813;16432;16433;16464.753028704577;16483.42308019218;14701.779473307883;14862.405503779835;14869.55391338259;14873.958822882403;14895.92552037241;679.0696745458729;703.2686006553179;826.0500424511829;833.1418638802044;852.7569642651846;851.7274472168908;856.5717141429285;862.6937437209795;864.1660797330575;865.9350548708841;911.3711539038076;915.0940568836837;915.6566287642135;916.1754421021068;916.8120535290283;709.684190535212;864.2049314630555;906.315731622624;908.9681861134894;909.3452714045909;669.0967194287673;676.9559978601375;679.4326737174465;682.0281099433337;683.6376720338179;14665.193414362206;14684.559463216121;14698.70602587953;14705.55883323502;14735.484258050918;13472.046553249724;13677.794833077513;13863.37614807326;13937;14076.2961851907;10867.728306792314;11176;12058;13091.14907531017;13369.799453008212;4575.748333841638;4617.37665415168;4675.4155730533685;4782.234842425195;4824.517548245182;100815;114005;115059.99999999916;115173;115264.42367788107;10439.321444106108;10633.043026127685;11001.449927503752;11046.502907369188;11089.889101109047;21302.680459793115;21641.78358216398;21658.783412165678;21692.56614867694;21742;7823.178566250575;8030.678772849021;8114.756557303291;8209.507429554245;8231;3780;4354.477462704495;4459.175052615276;4476.1495315890525;4530.6602004849765;3338.315645292758;3387.1193489692364;3448.7756846319194;3450.4996775467416;3454.516367708523;573.7131434282859;590.4626789621424;597.6115524908844;642.1555654314561;652.1782553982017;455.6641725395654;572.4189947203591;598.2491972574478;599.07307598691;626;8648.956755216184;8819.691310804092;8972.775680608038;9113.134252246025;9259;537.7069497124077;560.9607327487118;581.3111462916457;585.748128304833;627.5826575327357;3876.9418458723144;4356.607905288539;4358.782060896942;4368.475782906008;4446.666500012448;92217;124488.1326780099;146037.07925841637;161201;161481.19259403629;77935;145204;146576;147268.263658681;149137.2543137299;124279.13581296288;126387.3680631591;127880;128598;128788.35605822099;8281.461704989157;8330;8419;8468.957655211685;8611.224989750952;46173;61014.69492652508;66520.66739666271;66914.66170676656;67215.3278467209;65808.67095664491;77377.22622773926;95490;97543;97867;13068;13288.86711132915;13926.955478339367;14073.507427239998;14127.858721412655;15150.772738409154;15358.15530145808;15403.998740081859;15758.448454304504;15868.60328491797;13844.86155138476;14076.859231407554;14118.647033824236;14343.067700599428;14554.41782328695;12066.155369124077;12305.56930507428;12422.937885310515;13766.105203161762;14582.781258281346;13418.127821691558;13735;13747.725045498833;13864.445422183;14133.010689251654;11419.828702569303;11475.827862582235;11597.362145082101;11611.303321800719;12134.211276267015;10064.547095380873;10487.52806123711;11359.659210223375;11550.653480395602;11573.652790416301 09:12:43 INFO - PROCESS | 1994 | __end_tp_report 09:12:43 INFO - PROCESS | 1994 | __start_cc_report 09:12:43 INFO - PROCESS | 1994 | _x_x_mozilla_cycle_collect,1272 09:12:43 INFO - PROCESS | 1994 | __end_cc_report 09:12:43 INFO - PROCESS | 1994 | __startTimestamp1470931963659__endTimestamp 09:12:43 INFO - PROCESS | 1994 | 09:12:43 INFO - PROCESS | 1994 | ------- Summary: start ------- 09:12:43 INFO - PROCESS | 1994 | Number of tests: 6 09:12:43 INFO - PROCESS | 1994 | 09:12:43 INFO - PROCESS | 1994 | [#0] dojo.html Cycles:165 Average:5412.92 Median:5551.14 stddev:589.02 (10.6%) stddev-sans-first:565.08 09:12:43 INFO - PROCESS | 1994 | Values: 3217.5 3247.9 3258.7 3285.1 3309.3 5252.0 5334.9 5367.0 5402.2 5460.0 5374.1 5377.5 5385.3 5385.9 5388.5 4918.4 4959.3 4964.9 5023.8 5034.0 4974.5 5065.6 5066.9 5070.1 5091.0 4979.5 4986.4 4986.5 4992.3 5006.1 4189.9 5062.4 5065.7 5077.3 5100.0 4079.6 4882.1 4901.3 4976.3 5023.1 5330.0 5346.5 5401.9 5404.0 5422.9 5797.9 5861.4 5871.1 5874.9 5886.2 5695.0 5752.8 5855.1 5903.5 5920.8 5499.3 5522.6 5543.2 5550.3 5551.1 6100.4 6103.3 6114.1 6118.4 6122.0 5590.6 5705.7 5730.6 5782.7 5803.9 5923.6 5931.5 5940.1 5964.3 5981.5 5835.5 5837.0 5852.5 5853.7 5856.0 5649.3 5690.1 5696.6 5839.4 5846.5 4673.6 4678.6 4745.2 4752.1 4755.9 4261.1 4262.4 4272.3 4295.8 4311.1 5382.4 5388.4 5392.6 5402.1 5442.1 5705.2 5730.6 5851.7 5854.2 5860.1 5432.0 5436.5 5443.8 5452.8 5509.5 5170.6 5190.6 5201.2 5202.3 5251.0 5933.8 5955.0 6211.5 6216.9 6235.4 5827.8 5882.4 5884.3 5918.5 5952.1 4972.7 6113.7 6162.4 6177.6 6193.3 4844.9 5559.7 5617.9 5744.6 5765.0 5753.2 5786.4 5798.2 5818.2 5869.8 4967.8 5050.8 5632.1 5646.0 5646.1 5549.9 5588.7 5617.4 5629.9 5686.9 5587.4 5595.7 5639.7 5665.0 5686.1 5671.5 5677.6 5693.3 5700.5 5703.6 5428.3 5483.3 5500.2 5592.2 5606.9 09:12:43 INFO - PROCESS | 1994 | 09:12:43 INFO - PROCESS | 1994 | [#1] ext.html Cycles:180 Average:66701.53 Median:40497.10 stddev:146527.61 (361.8%) stddev-sans-first:146854.27 09:12:43 INFO - PROCESS | 1994 | Values: 466.8 476.0 517.1 521.0 524.2 30666.8 40600.8 41616.6 41622.8 41681.8 42590.8 43006.4 43045.6 43149.4 43194.7 8460.5 9576.6 9758.6 9775.9 9781.8 9662.0 9765.0 9784.1 9795.4 9796.4 9781.7 9784.1 9842.6 9849.0 9850.6 9727.8 9779.7 9785.1 9802.6 9824.1 9697.6 9770.6 9783.0 9795.2 9800.2 40473.2 40769.6 40812.6 40817.0 40872.4 3435.1 3449.7 3453.3 3456.3 3523.5 31358.4 31588.8 31714.7 32176.7 32756.0 3183.7 3227.1 3256.9 3264.1 3383.9 376310.0 376814.1 377393.0 378112.0 378151.0 59296.4 59920.7 59980.1 60167.4 60179.7 34323.7 35465.6 35491.5 36438.3 63346.7 16222.4 16388.9 16391.9 16394.1 16403.7 12489.4 12569.4 12596.3 12603.6 12679.0 18080.8 18996.6 19027.6 19459.6 19581.0 6521.0 8102.2 8117.4 8190.0 8327.5 881.9 896.2 949.3 988.2 1038.4 28714.0 29815.7 30436.5 30478.8 32140.4 880.6 1150.5 1163.5 1189.8 1221.9 13514.7 15819.4 15839.0 15844.4 16197.5 688452.0 694538.0 695100.0 696213.0 696658.0 59382.0 59685.7 59788.4 59838.4 59927.4 1248.5 1296.7 1371.8 1396.8 1408.0 27061.9 29961.6 31351.8 31452.4 32130.4 50098.5 50794.5 50853.5 50868.5 50875.0 521337.0 522993.0 523403.0 524509.8 525944.0 23499.8 24108.3 26059.9 26981.5 27335.6 43494.0 47351.1 47502.3 48095.8 48439.5 39205.0 39395.8 39768.2 40542.8 41007.6 39127.4 39832.8 40521.0 40527.6 40752.8 30967.5 37499.1 38856.2 39097.0 39152.4 32759.7 33917.8 34234.8 34713.1 34759.7 22306.9 23812.6 25705.9 25783.2 25844.4 09:12:43 INFO - PROCESS | 1994 | 09:12:43 INFO - PROCESS | 1994 | [#2] jquery.html Cycles:180 Average:34444.44 Median:18601.91 stddev:99311.28 (533.9%) stddev-sans-first:99556.88 09:12:43 INFO - PROCESS | 1994 | Values: 351.9 369.8 370.8 374.8 397.7 14700.3 15122.2 20432.8 20506.2 21156.6 19849.7 20261.2 20270.0 20636.6 20736.9 4970.5 5258.7 5492.7 5496.6 5506.6 5940.9 6139.0 6141.3 6143.6 6164.4 5858.4 6077.2 6085.6 6090.3 6166.7 4982.7 6160.5 6165.1 6169.5 6187.0 5163.2 5429.4 5495.2 5520.0 5957.9 12746.7 12991.5 12997.7 21372.6 24913.3 2081.2 2092.3 2119.5 2268.2 2337.6 9737.8 10128.0 10244.3 12979.2 14957.0 1746.8 1882.4 1886.1 1900.4 1903.1 115641.0 128368.0 129648.0 129781.4 132075.0 8043.7 8113.4 10111.8 10243.8 11384.7 12317.5 12382.3 12411.3 12411.8 12417.3 6597.0 7905.0 8014.2 8015.0 8090.5 8309.0 8325.0 8328.8 8329.6 8496.0 3178.8 3863.5 3867.8 3917.0 3924.6 1887.0 1928.1 1932.3 1932.7 1933.3 868.4 884.3 888.2 889.3 955.9 20269.0 20428.8 21184.9 21320.1 21433.2 875.0 902.7 912.7 975.8 975.9 10548.5 11140.7 11289.9 11294.5 11330.5 585991.0 588061.9 597570.0 597878.0 600530.0 108226.9 112202.0 121192.0 121662.0 129401.0 938.5 940.5 975.8 977.8 979.8 16028.6 17107.7 18403.0 19064.8 19175.7 36834.0 52323.3 54852.0 54934.0 57147.7 79359.0 79911.4 80615.6 81125.7 82870.0 5615.6 8065.7 8196.7 8216.4 8296.7 9235.2 9577.1 11183.6 13220.0 13283.8 11188.9 11236.3 11382.7 11416.7 11418.8 9230.0 10899.9 11045.0 11056.7 11360.9 11321.4 11506.3 11532.9 11624.4 11664.4 7596.5 7871.3 8183.7 8186.7 8237.8 8323.3 10249.8 11324.0 11359.8 11529.3 09:12:43 INFO - PROCESS | 1994 | 09:12:43 INFO - PROCESS | 1994 | [#3] mootools.html Cycles:180 Average:5674.42 Median:4884.32 stddev:3219.81 (65.9%) stddev-sans-first:3212.44 09:12:43 INFO - PROCESS | 1994 | Values: 1351.0 1492.9 1537.4 1706.5 1879.6 7669.1 8561.0 8570.8 8732.0 8871.0 8636.6 8725.9 8730.1 8734.3 8743.0 2290.0 2311.8 2313.1 2317.8 2323.5 2263.2 2298.8 2299.9 2300.8 2304.3 2261.9 2291.6 2293.4 2296.3 2298.6 2251.6 2258.1 2267.3 2274.6 2275.5 2065.4 2151.0 2277.0 2277.9 2280.3 8655.4 8747.3 8748.3 8750.0 8762.9 5057.2 5209.4 5215.8 5224.9 5277.3 2776.8 3080.6 3509.7 3558.1 3604.9 919.6 1073.0 1084.6 1099.6 1104.6 11208.0 11238.4 11404.4 11405.0 11411.7 4479.9 4482.2 4676.7 4682.9 4708.9 9952.6 9954.4 9988.3 9992.5 10011.6 5165.4 5210.9 5235.3 5245.4 5247.1 4218.5 4295.6 4428.8 4675.7 4797.6 3865.7 3892.9 3978.2 3978.8 3979.7 1218.3 1256.7 1257.2 1272.7 1279.0 870.0 929.2 931.3 953.2 992.6 5827.6 5844.9 5918.7 5979.7 6164.8 355.7 385.5 410.5 423.4 442.8 3986.3 4015.8 4094.7 4135.2 4136.5 10395.9 10644.0 10748.6 10762.1 10769.2 10708.7 10719.9 10722.6 10723.2 10789.2 7983.5 8137.9 8167.9 8297.4 8299.1 5752.4 5891.4 5923.2 5923.9 5987.9 9940.2 9950.2 9997.9 10024.6 10027.7 8994.8 9219.9 10172.2 10175.8 10186.9 7553.9 8028.2 8137.3 8189.5 8344.8 7996.9 8230.2 8291.6 8317.0 8317.4 7413.4 7532.6 7535.0 7561.3 7673.8 7346.3 7391.9 7416.6 7424.0 7517.9 6853.5 7036.3 7076.1 7169.8 7176.6 4810.3 4999.8 5032.2 5041.2 5259.6 4958.4 5008.8 5124.1 5140.9 5309.5 09:12:43 INFO - PROCESS | 1994 | 09:12:43 INFO - PROCESS | 1994 | [#4] prototype.html Cycles:180 Average:4463.96 Median:5061.25 stddev:1396.83 (27.6%) stddev-sans-first:1364.68 09:12:43 INFO - PROCESS | 1994 | Values: 262.3 295.0 299.7 311.7 313.4 5238.4 5287.7 5318.8 5360.7 5388.3 4951.2 4978.0 5115.2 5136.1 5251.1 2920.4 3395.3 4027.0 4038.8 4045.6 3471.4 4013.6 4022.0 4037.4 4041.7 3252.3 3369.9 3754.8 4001.9 4013.2 3015.4 3218.7 4001.6 4005.8 4023.0 3295.7 3465.7 3475.3 3494.5 3495.8 4475.8 4828.0 5119.9 5141.9 5154.6 4225.0 4379.7 4554.2 4572.9 4605.5 5473.6 5524.0 5615.0 5636.5 5645.0 3795.3 3803.5 3830.5 3844.2 3848.9 4693.6 5532.5 5739.4 5755.9 5808.9 3433.8 3457.6 3591.4 3812.4 3844.3 3459.2 3923.5 3935.3 3937.8 3946.3 4167.6 4557.3 4557.7 4585.2 4587.8 5273.7 5328.8 5505.7 5521.0 5532.7 1271.9 1300.5 1324.7 1327.9 1329.7 721.4 776.9 790.0 877.9 897.9 4170.3 4409.7 4930.1 5047.0 5118.0 5565.4 5674.2 5680.4 5701.3 5750.3 4508.4 4898.9 4941.9 5033.6 5086.2 2904.9 2936.6 2951.0 2973.9 2975.3 5012.5 5252.6 5419.8 5868.7 5874.5 5110.4 5449.6 5805.0 5845.5 5917.7 4902.8 5705.7 5912.0 5973.5 6006.4 4708.9 5336.4 5693.8 5710.6 5738.7 4910.3 5112.7 5189.6 5658.0 5665.4 5408.8 5909.1 5916.4 5943.3 5954.9 3251.8 3652.9 3817.1 3873.0 3876.2 5603.9 5704.2 5723.2 5726.9 5727.9 4684.9 5355.5 5643.1 5651.7 5722.8 4649.6 5075.5 5222.6 5546.4 5627.7 4632.5 5200.6 5357.9 5502.1 5533.6 5133.3 5439.1 5442.7 5609.2 5683.2 5038.2 5472.8 5499.8 5525.5 5548.0 09:12:43 INFO - PROCESS | 1994 | 09:12:43 INFO - PROCESS | 1994 | [#5] yui.html Cycles:180 Average:25101.77 Median:3449.64 stddev:40527.11 (1174.8%) stddev-sans-first:40599.44 09:12:43 INFO - PROCESS | 1994 | Values: 716.9 726.7 776.8 828.2 832.3 14939.3 16432.0 16433.0 16464.8 16483.4 14701.8 14862.4 14869.6 14874.0 14895.9 679.1 703.3 826.1 833.1 852.8 851.7 856.6 862.7 864.2 865.9 911.4 915.1 915.7 916.2 916.8 709.7 864.2 906.3 909.0 909.3 669.1 677.0 679.4 682.0 683.6 14665.2 14684.6 14698.7 14705.6 14735.5 13472.0 13677.8 13863.4 13937.0 14076.3 10867.7 11176.0 12058.0 13091.1 13369.8 4575.7 4617.4 4675.4 4782.2 4824.5 100815.0 114005.0 115060.0 115173.0 115264.4 10439.3 10633.0 11001.4 11046.5 11089.9 21302.7 21641.8 21658.8 21692.6 21742.0 7823.2 8030.7 8114.8 8209.5 8231.0 3780.0 4354.5 4459.2 4476.1 4530.7 3338.3 3387.1 3448.8 3450.5 3454.5 573.7 590.5 597.6 642.2 652.2 455.7 572.4 598.2 599.1 626.0 8649.0 8819.7 8972.8 9113.1 9259.0 537.7 561.0 581.3 585.7 627.6 3876.9 4356.6 4358.8 4368.5 4446.7 92217.0 124488.1 146037.1 161201.0 161481.2 77935.0 145204.0 146576.0 147268.3 149137.3 124279.1 126387.4 127880.0 128598.0 128788.4 8281.5 8330.0 8419.0 8469.0 8611.2 46173.0 61014.7 66520.7 66914.7 67215.3 65808.7 77377.2 95490.0 97543.0 97867.0 13068.0 13288.9 13927.0 14073.5 14127.9 15150.8 15358.2 15404.0 15758.4 15868.6 13844.9 14076.9 14118.6 14343.1 14554.4 12066.2 12305.6 12422.9 13766.1 14582.8 13418.1 13735.0 13747.7 13864.4 14133.0 11419.8 11475.8 11597.4 11611.3 12134.2 10064.5 10487.5 11359.7 11550.7 11573.7 09:12:43 INFO - PROCESS | 1994 | -------- Summary: end -------- 09:12:43 INFO - PROCESS | 1994 | 09:12:44 INFO - TEST-INFO | 1994: exit 0 09:12:44 INFO - TEST-OK | dromaeo_css | took 1118758ms 09:12:44 INFO - TEST-START | kraken 09:12:44 INFO - operating with platform_type : mac_ 09:12:44 INFO - Initialising browser for kraken test... 09:12:44 INFO - TEST-INFO | started process 1998 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpFnJ_kY/profile http://localhost:49393/getInfo.html) 09:12:46 INFO - PROCESS | 1998 | __metrics Screen width/height:1600/1200 09:12:46 INFO - PROCESS | 1998 | colorDepth:24 09:12:46 INFO - PROCESS | 1998 | Browser inner width/height: 1024/689 09:12:46 INFO - PROCESS | 1998 | __metrics 09:12:46 INFO - PROCESS | 1998 | 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" 09:12:46 INFO - PROCESS | 1998 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:12:47 INFO - TEST-INFO | 1998: exit 0 09:12:47 INFO - Browser initialized. 09:12:47 INFO - Running cycle 1/1 for kraken test... 09:12:47 INFO - TEST-INFO | started process 1999 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpFnJ_kY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:59 INFO - PROCESS | 1999 | RSS: Main: 243597312 09:12:59 INFO - PROCESS | 1999 | 09:13:02 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49393/tests/kraken/driver.html?testName=audio-beat-detection) 09:13:03 INFO - PROCESS | 1999 | RSS: Main: 293298176 09:13:03 INFO - PROCESS | 1999 | 09:13:05 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49393/tests/kraken/driver.html?testName=audio-dft) 09:13:06 INFO - PROCESS | 1999 | RSS: Main: 301244416 09:13:06 INFO - PROCESS | 1999 | 09:13:09 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49393/tests/kraken/driver.html?testName=audio-fft) 09:13:10 INFO - PROCESS | 1999 | RSS: Main: 394682368 09:13:10 INFO - PROCESS | 1999 | 09:13:12 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49393/tests/kraken/driver.html?testName=audio-oscillator) 09:13:12 INFO - PROCESS | 1999 | RSS: Main: 297127936 09:13:12 INFO - PROCESS | 1999 | 09:13:15 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49393/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:13:15 INFO - PROCESS | 1999 | RSS: Main: 300904448 09:13:15 INFO - PROCESS | 1999 | 09:13:18 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49393/tests/kraken/driver.html?testName=imaging-darkroom) 09:13:19 INFO - PROCESS | 1999 | RSS: Main: 336449536 09:13:19 INFO - PROCESS | 1999 | 09:13:22 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49393/tests/kraken/driver.html?testName=imaging-desaturate) 09:13:22 INFO - PROCESS | 1999 | RSS: Main: 341057536 09:13:22 INFO - PROCESS | 1999 | 09:13:25 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49393/tests/kraken/driver.html?testName=json-parse-financial) 09:13:26 INFO - PROCESS | 1999 | RSS: Main: 339959808 09:13:26 INFO - PROCESS | 1999 | 09:13:27 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49393/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:13:28 INFO - PROCESS | 1999 | RSS: Main: 316395520 09:13:28 INFO - PROCESS | 1999 | 09:13:30 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:13:31 INFO - PROCESS | 1999 | RSS: Main: 320204800 09:13:31 INFO - PROCESS | 1999 | 09:13:33 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:13:33 INFO - PROCESS | 1999 | RSS: Main: 327749632 09:13:33 INFO - PROCESS | 1999 | 09:13:36 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:13:37 INFO - PROCESS | 1999 | RSS: Main: 331456512 09:13:37 INFO - PROCESS | 1999 | 09:13:40 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:13:41 INFO - PROCESS | 1999 | RSS: Main: 332795904 09:13:41 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | Cycle 1(1): loaded http://localhost:49393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49393/tests/kraken/driver.html?testName=ai-astar) 09:13:42 INFO - PROCESS | 1999 | RSS: Main: 376446976 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | __start_tp_report 09:13:42 INFO - PROCESS | 1999 | _x_x_mozilla_page_load 09:13:42 INFO - PROCESS | 1999 | _x_x_mozilla_page_load_details 09:13:42 INFO - PROCESS | 1999 | |i|pagename|runs| 09:13:42 INFO - PROCESS | 1999 | |0;ai-astar;103;113;113;112;112;112;111;113;113;112 09:13:42 INFO - PROCESS | 1999 | |1;audio-beat-detection;143;110;103;166;104;107;130;105;146;107 09:13:42 INFO - PROCESS | 1999 | |2;audio-dft;184;185;185;184;185;184;184;180;184;182 09:13:42 INFO - PROCESS | 1999 | |3;audio-fft;79;58;66;86;60;57;60;67;83;57 09:13:42 INFO - PROCESS | 1999 | |4;audio-oscillator;83;68;102;71;71;68;97;70;69;129 09:13:42 INFO - PROCESS | 1999 | |5;imaging-gaussian-blur;89;87;95;91;89;85;88;90;88;84 09:13:42 INFO - PROCESS | 1999 | |6;imaging-darkroom;93;96;113;151;100;93;94;94;94;93 09:13:42 INFO - PROCESS | 1999 | |7;imaging-desaturate;85;87;86;87;89;136;73;135;136;108 09:13:42 INFO - PROCESS | 1999 | |8;json-parse-financial;57;55;54;57;55;55;53;53;53;54 09:13:42 INFO - PROCESS | 1999 | |9;json-stringify-tinderbox;68;67;81;84;79;74;74;73;73;73 09:13:42 INFO - PROCESS | 1999 | |10;stanford-crypto-aes;83;86;83;85;85;85;85;87;83;115 09:13:42 INFO - PROCESS | 1999 | |11;stanford-crypto-ccm;139;144;154;150;157;140;130;146;167;153 09:13:42 INFO - PROCESS | 1999 | |12;stanford-crypto-pbkdf2;164;164;166;163;164;175;191;164;164;165 09:13:42 INFO - PROCESS | 1999 | |13;stanford-crypto-sha256-iterative;67;68;68;67;67;67;65;66;67;65 09:13:42 INFO - PROCESS | 1999 | __end_tp_report 09:13:42 INFO - PROCESS | 1999 | __start_cc_report 09:13:42 INFO - PROCESS | 1999 | _x_x_mozilla_cycle_collect,2041 09:13:42 INFO - PROCESS | 1999 | __end_cc_report 09:13:42 INFO - PROCESS | 1999 | __startTimestamp1470932022964__endTimestamp 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | ------- Summary: start ------- 09:13:42 INFO - PROCESS | 1999 | Number of tests: 14 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.50 stddev:3.03 (2.7%) stddev-sans-first:0.71 09:13:42 INFO - PROCESS | 1999 | Values: 103.0 113.0 113.0 112.0 112.0 112.0 111.0 113.0 113.0 112.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:120.00 stddev:22.57 (18.8%) stddev-sans-first:22.64 09:13:42 INFO - PROCESS | 1999 | Values: 143.0 110.0 103.0 166.0 104.0 107.0 130.0 105.0 146.0 107.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.57 (0.9%) stddev-sans-first:1.66 09:13:42 INFO - PROCESS | 1999 | Values: 184.0 185.0 185.0 184.0 185.0 184.0 184.0 180.0 184.0 182.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#3] audio-fft Cycles:10 Average:67.30 Median:66.50 stddev:11.26 (16.9%) stddev-sans-first:11.11 09:13:42 INFO - PROCESS | 1999 | Values: 79.0 58.0 66.0 86.0 60.0 57.0 60.0 67.0 83.0 57.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:70.50 stddev:20.48 (29.1%) stddev-sans-first:21.72 09:13:42 INFO - PROCESS | 1999 | Values: 83.0 68.0 102.0 71.0 71.0 68.0 97.0 70.0 69.0 129.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.00 stddev:3.10 (3.5%) stddev-sans-first:3.28 09:13:42 INFO - PROCESS | 1999 | Values: 89.0 87.0 95.0 91.0 89.0 85.0 88.0 90.0 88.0 84.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:93.50 stddev:18.25 (19.5%) stddev-sans-first:19.06 09:13:42 INFO - PROCESS | 1999 | Values: 93.0 96.0 113.0 151.0 100.0 93.0 94.0 94.0 94.0 93.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#7] imaging-desaturate Cycles:10 Average:102.20 Median:85.50 stddev:24.59 (28.8%) stddev-sans-first:25.28 09:13:42 INFO - PROCESS | 1999 | Values: 85.0 87.0 86.0 87.0 89.0 136.0 73.0 135.0 136.0 108.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.51 (2.7%) stddev-sans-first:1.32 09:13:42 INFO - PROCESS | 1999 | Values: 57.0 55.0 54.0 57.0 55.0 55.0 53.0 53.0 53.0 54.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#9] json-stringify-tinderbox Cycles:10 Average:74.60 Median:74.00 stddev:5.36 (7.2%) stddev-sans-first:5.12 09:13:42 INFO - PROCESS | 1999 | Values: 68.0 67.0 81.0 84.0 79.0 74.0 74.0 73.0 73.0 73.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:85.00 stddev:9.68 (11.4%) stddev-sans-first:10.12 09:13:42 INFO - PROCESS | 1999 | Values: 83.0 86.0 83.0 85.0 85.0 85.0 85.0 87.0 83.0 115.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:151.50 stddev:10.52 (6.9%) stddev-sans-first:10.64 09:13:42 INFO - PROCESS | 1999 | Values: 139.0 144.0 154.0 150.0 157.0 140.0 130.0 146.0 167.0 153.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:164.50 stddev:8.79 (5.3%) stddev-sans-first:9.21 09:13:42 INFO - PROCESS | 1999 | Values: 164.0 164.0 166.0 163.0 164.0 175.0 191.0 164.0 164.0 165.0 09:13:42 INFO - PROCESS | 1999 | 09:13:42 INFO - PROCESS | 1999 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.06 (1.6%) stddev-sans-first:1.12 09:13:42 INFO - PROCESS | 1999 | Values: 67.0 68.0 68.0 67.0 67.0 67.0 65.0 66.0 67.0 65.0 09:13:42 INFO - PROCESS | 1999 | -------- Summary: end -------- 09:13:42 INFO - PROCESS | 1999 | 09:13:43 INFO - TEST-INFO | 1999: exit 0 09:13:43 INFO - TEST-OK | kraken | took 59494ms 09:13:43 INFO - SUITE-END | took 1178s 09:13:44 INFO - Completed test suite (00:19:38) 09:13:44 INFO - javascript benchmark 09:13:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3217.4852023676217, 3247.935041299174, 3258.706716395524, 3285.0965984354316, 3309.32158907424, 5252.002119597283, 5334.946650533493, 5367.0, 5402.216678581603, 5460.044492213861, 5374.059539580578, 5377.462253774621, 5385.272988146599, 5385.865353366168, 5388.488093631108, 4918.434380046296, 4959.330490383806, 4964.87587810304, 5023.824166154185, 5033.974830125844, 4974.527419895104, 5065.620078494109, 5066.949330506703, 5070.08738427083, 5091.0, 4979.502049795028, 4986.35177426934, 4986.476419975905, 4992.32603598515, 5006.148954677714, 4189.895252618709, 5062.417821950486, 5065.721385323805, 5077.314562534048, 5100.0, 4079.5920407959266, 4882.096812089775, 4901.289313049588, 4976.253561965698, 5023.146065168893, 5329.973350133225, 5346.465353464661, 5401.918971215435, 5404.027275090482, 5422.91865622016, 5797.884042319172, 5861.443162899518, 5871.060630299175, 5874.882502349929, 5886.175935369051, 5694.9715251423895, 5752.769889204426, 5855.06318988964, 5903.468687818117, 5920.822375328747, 5499.312585926759, 5522.5858060644805, 5543.1685247213245, 5550.250716153268, 5551.139573366135, 6100.359462256488, 6103.267607886994, 6114.052321890114, 6118.418750218722, 6121.959266924633, 5590.580706447033, 5705.71471426427, 5730.570207234474, 5782.739776710058, 5803.8549036274435, 5923.555733320019, 5931.4958228550195, 5940.138679891392, 5964.254468191476, 5981.461668449861, 5835.503982161479, 5837.0, 5852.473277405054, 5853.707314634251, 5856.0, 5649.322081350182, 5690.146478028329, 5696.62971906833, 5839.386864379179, 5846.532277417797, 4673.602743766818, 4678.625709943196, 4745.240761478147, 4752.073345697556, 4755.857324280277, 4261.083866968616, 4262.403263543107, 4272.33778764292, 4295.806688699016, 4311.116221174651, 5382.407935127133, 5388.380336261302, 5392.649477783932, 5402.108652072443, 5442.129259318569, 5705.172749951317, 5730.598858079978, 5851.678157701367, 5854.18041474194, 5860.120981852756, 5432.022235997559, 5436.510714035756, 5443.8094666686475, 5452.809151679672, 5509.5041446270025, 5170.6122040847085, 5190.6107041972, 5201.245819356172, 5202.3497062867145, 5251.0, 5933.792317269048, 5955.047192449188, 6211.503079753699, 6216.875662486725, 6235.438810507076, 5827.76688932438, 5882.352941176553, 5884.32330282023, 5918.526517878473, 5952.077427998667, 4972.676776009548, 6113.694315284307, 6162.414570615784, 6177.598456100339, 6193.287771906288, 4844.878878028077, 5559.6664200148125, 5617.887642247214, 5744.569157313134, 5764.991126552753, 5753.165790960371, 5786.392428795084, 5798.217240672455, 5818.156367326798, 5869.7945571904775, 4967.82612608557, 5050.813058931075, 5632.127020311858, 5646.0, 5646.0683987141665, 5549.916751248654, 5588.720563971867, 5617.43825617435, 5629.887402252014, 5686.857828554319, 5587.44125587438, 5595.692236926845, 5639.746211420578, 5664.971675141598, 5686.147077938343, 5671.489565939085, 5677.630953987978, 5693.28833895763, 5700.458456446631, 5703.572232082527, 5428.348598168245, 5483.259759932517, 5500.202470641814, 5592.161175823659, 5606.85982850432], "value": 5377.93360318943, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.82127627739976, 475.99286010709824, 517.1028265958562, 520.9869753256171, 524.1508755815579, 30666.84666576667, 40600.796996015015, 41616.58383416161, 41622.791886040526, 41681.79159104208, 42590.787046064805, 43006.35490467645, 43045.56954430455, 43149.35275970855, 43194.7041588752, 8460.534670593115, 9576.569054392552, 9758.560864761068, 9775.90224097757, 9781.804363912717, 9662.033796620351, 9765.0, 9784.07051330126, 9795.412275263472, 9796.412215267106, 9781.706548803548, 9784.07051330126, 9842.557084931195, 9848.965858584817, 9850.556724947395, 9727.756806079833, 9779.657711980046, 9785.07041831025, 9802.558884850163, 9824.115829575372, 9697.612095516242, 9770.560324785401, 9782.972787857314, 9795.167410770093, 9800.215982721436, 40473.1905361894, 40769.592304077174, 40812.591874081176, 40817.0, 40872.38691419631, 3435.089701229176, 3449.6722811332884, 3453.2748122893972, 3456.2569047654874, 3523.453864650983, 31358.37283254322, 31588.842055789573, 31714.682853171405, 32176.6782332176, 32756.0, 3183.697548732867, 3227.128675257667, 3256.8860089896975, 3264.069740124053, 3383.915402114942, 376310.0, 376814.1159294213, 377392.99999999726, 378112.0, 378151.0, 59296.40703592995, 59920.70039649774, 59980.10029849468, 60167.39832601618, 60179.69910150509, 34323.656763432045, 35465.64534354623, 35491.4676279856, 36438.27123457569, 63346.68326658337, 16222.432214872422, 16388.918055409886, 16391.91804040996, 16394.09832459226, 16403.67192656164, 12489.375531223403, 12569.434375453127, 12596.307203103917, 12603.558875439316, 12679.0, 18080.82474639144, 18996.57556057444, 19027.61944761097, 19459.604411757482, 19581.0, 6521.0, 8102.230288122619, 8117.431779775359, 8190.0, 8327.458715183615, 881.8897637795276, 896.1576118448637, 949.3307218411004, 988.2193067476613, 1038.4184856480395, 28714.0, 29815.701842981292, 30436.54345184824, 30478.847605761828, 32140.357192856012, 880.55972013993, 1150.4880328253846, 1163.528770847808, 1189.7501524679915, 1221.9450124744408, 13514.662133446742, 15819.446319378996, 15839.0, 15844.44544440962, 16197.51407457778, 688452.0, 694538.0, 695100.0, 696213.0, 696658.0, 59382.0, 59685.70157149187, 59788.402115978286, 59838.401615984156, 59927.40072599305, 1248.4881198708485, 1296.6693493159185, 1371.7805151175764, 1396.76255036644, 1407.971840563183, 27061.86469067721, 29961.550576741804, 31351.84324078365, 31452.370952581277, 32130.35739285248, 50098.49901500938, 50794.492055078976, 50853.49146508487, 50868.49131508637, 50874.982500350525, 521337.0, 522993.0, 523403.0, 524509.7549024613, 525944.0, 23499.76500234976, 24108.276751696776, 26059.869700651376, 26981.460370792865, 27335.58996615092, 43494.0, 47351.05297894092, 47502.287465687354, 48095.75952120217, 48439.5156048435, 39205.01987450336, 39395.80302098471, 39768.2046359077, 40542.79728601338, 41007.58992410038, 39127.413088804264, 39832.80083599564, 40521.0, 40527.59472405356, 40752.79623601863, 30967.535486967263, 37499.06252343713, 38856.22287554177, 39097.02257443587, 39152.412713809885, 32759.67240327566, 33917.83041084779, 34234.828825856704, 34713.13217169591, 34759.652403475644, 22306.888465557568, 23812.57124572531, 25705.858828234053, 25783.226503204933, 25844.35389115212], "value": 19726.015427804225, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.9032266126813, 369.8243334416153, 370.82015222617025, 374.8182131666142, 397.6639739420191, 14700.26498675066, 15122.243887805607, 20432.795672043274, 20506.17975280987, 21156.57686846262, 19849.70225446616, 20261.189552417924, 20270.0, 20636.587268254625, 20736.89631551844, 4970.527799859025, 5258.6581872178285, 5492.670439773607, 5496.587755918302, 5506.64206826557, 5940.910886336708, 6139.048447490646, 6141.293751218623, 6143.569950103494, 6164.4143806338325, 5858.384869588695, 6077.209962704842, 6085.574009819315, 6090.299615544202, 6166.6916654167335, 4982.725950072744, 6160.537959653044, 6165.106059621375, 6169.537284703621, 6186.969065154668, 5163.199704045879, 5429.429909859447, 5495.230667706524, 5520.0, 5957.910631340534, 12746.745065098647, 12991.480340786356, 12997.675058123623, 21372.572548548942, 24913.252602421955, 2081.198738485694, 2092.2886218685567, 2119.512512122205, 2268.194790849258, 2337.649352597107, 9737.756556086084, 10128.03783640553, 10244.334118282288, 12979.156354837, 14957.0, 1746.755454236408, 1882.397632757518, 1886.141805478504, 1900.3633782682818, 1903.0960293860444, 115641.0, 128368.0, 129648.0, 129781.35109324487, 132074.99999999904, 8043.718469853527, 8113.391495637733, 10111.848322275171, 10243.795124097476, 11384.658460246206, 12317.50729970809, 12382.26648269243, 12411.317377544321, 12411.75176496483, 12417.31704756229, 6596.96701516499, 7905.0, 8014.19858014194, 8014.998125234346, 8090.474119182234, 8309.0, 8324.958375208207, 8328.792325112825, 8329.583520823935, 8496.0, 3178.7933784303946, 3863.469427524976, 3867.7872717000837, 3916.981584787976, 3924.5682974872743, 1886.9622607547772, 1928.0937959159173, 1932.3333449959741, 1932.7294178814695, 1933.2943475631293, 868.4224990381325, 884.3190743127884, 888.1518150166576, 889.3330002498126, 955.894851566341, 20268.98655067241, 20428.795712042986, 21184.94075296229, 21320.14719411237, 21433.249836255658, 875.0, 902.6795487601938, 912.7124955638952, 975.8194733974237, 975.9219262459128, 10548.525316360781, 11140.665780026613, 11289.887101128883, 11294.491747871365, 11330.546778128948, 585991.0, 588061.9100943828, 597570.0, 597878.0, 600530.0, 108226.91773082326, 112202.0, 121192.0, 121662.0, 129401.0, 938.5494962417941, 940.4921342474987, 975.7804493988938, 977.8142152990908, 979.8187335342984, 16028.599285017968, 17107.716921231207, 18402.954534111785, 19064.809351906857, 19175.712364314266, 36834.0, 52323.29090381884, 54852.0, 54933.951054886085, 57147.714261428424, 79359.0, 79911.4039868219, 80615.59692201501, 81125.69868711608, 82870.0, 5615.606907516435, 8065.717699880476, 8196.713115040944, 8216.383771217303, 8296.714009328563, 9235.1688348049, 9577.138057574852, 11183.608573700207, 13219.967909787607, 13283.80074298867, 11188.944055279671, 11236.269642473213, 11382.715432114263, 11416.657500275005, 11418.771624567296, 9229.953850230975, 10899.945500272448, 11045.005949464587, 11056.723581910195, 11360.943195283971, 11321.377324247245, 11506.252093613888, 11532.94233528827, 11624.360660163773, 11664.416779161178, 7596.506227095221, 7871.33093687043, 8183.672653094048, 8186.672533098609, 8237.752867413987, 8323.334133269203, 10249.795004100026, 11324.037456816262, 11359.829602556134, 11529.308241505536], "value": 9208.005820310127, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.993509835173, 1492.917889516077, 1537.3850459816072, 1706.5051135170802, 1879.605282890593, 7669.079710434748, 8561.0, 8570.785730356736, 8732.039475657688, 8871.0, 8636.56817159141, 8725.912740872589, 8730.126987301282, 8734.344924130684, 8743.038265790758, 2289.9656505152434, 2311.7919387255142, 2313.0747700919596, 2317.8493397929083, 2323.488832456857, 2263.20787724296, 2298.8160947124275, 2299.942501437469, 2300.7584203658625, 2304.320225533463, 2261.8529795563236, 2291.633338665814, 2293.392251053472, 2296.276672848056, 2298.6092364298024, 2251.594712951668, 2258.06290389489, 2267.263139479676, 2274.5792028474702, 2275.4538910661486, 2065.39070974063, 2151.0212853151934, 2277.0436416705024, 2277.920272790461, 2280.315905228425, 8655.35084868631, 8747.30021598277, 8748.34387420946, 8750.0, 8762.86855697165, 5057.241413787924, 5209.374875014984, 5215.774293041132, 5224.92162617561, 5277.3139491866195, 2776.818670336674, 3080.5841211436395, 3509.666581674737, 3558.1282585766394, 3604.9098772530897, 919.6045700348848, 1072.9892701072968, 1084.5878566144893, 1099.5601759296346, 1104.5692180049787, 11208.047315978152, 11238.381888996102, 11404.372759498225, 11405.0, 11411.714707132305, 4479.932801007987, 4482.21561226784, 4676.69601475903, 4682.859514214579, 4708.858734237979, 9952.552135153936, 9954.352967057263, 9988.300818942604, 9992.450415227233, 10011.649592264235, 5165.354330708661, 5210.869728256748, 5235.319408476874, 5245.396779370345, 5247.081760691864, 4218.451601291874, 4295.634871035997, 4428.778561071934, 4675.719456832601, 4797.616190704735, 3865.7100717446306, 3892.9099852041472, 3978.204359128186, 3978.781167035783, 3979.7413168143976, 1218.2629509147016, 1256.7360854220717, 1257.220523275575, 1272.662744372742, 1278.9808152877715, 869.9821209185268, 929.2380248196414, 931.2689538712078, 953.2135987810084, 992.6376872441651, 5827.5920685552455, 5844.9415505845245, 5918.704064796829, 5979.730912108966, 6164.7842325518395, 355.65501463580284, 385.5219527785526, 410.46844336584195, 423.36706623597917, 442.7697597249477, 3986.3422535281934, 4015.8393664253686, 4094.6724262058965, 4135.234981528367, 4136.544980052192, 10395.94802026, 10644.042036216622, 10748.623798167027, 10762.139028877671, 10769.24615276923, 10708.732281692864, 10719.946400267949, 10722.571097156026, 10723.195760318007, 10789.19081068923, 7983.5209887406945, 8137.918620813716, 8167.918320816716, 8297.419180657416, 8299.128591497927, 5752.395998420106, 5891.440313170242, 5923.170756094237, 5923.940760592339, 5987.850303742442, 9940.204783617148, 9950.154236889946, 9997.900020999698, 10024.649137280161, 10027.749306267402, 8994.82010359776, 9219.861702074608, 10172.237082219011, 10175.847362289423, 10186.898131018595, 7553.886691699739, 8028.197180282159, 8137.349012078901, 8189.549574773207, 8344.7913802153, 7996.920415744033, 8230.176982301962, 8291.58542072882, 8317.001959764866, 8317.41778075529, 7413.406927445899, 7532.585707786125, 7535.020447341811, 7561.3194812467145, 7673.808154796175, 7346.302101300368, 7391.852162956819, 7416.629168541659, 7423.962880185564, 7517.924820751722, 6853.4859885507785, 7036.331548502884, 7076.080109585927, 7169.820754481179, 7176.569405835667, 4810.254410566367, 4999.750012499288, 5032.220005899091, 5041.168207245763, 5259.605529585373, 4958.380202474691, 5008.824691135793, 5124.128898087409, 5140.871478213075, 5309.548688361378], "value": 4454.3630384237895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [262.3048920361043, 294.9542820862767, 299.72724820413424, 311.6602902835908, 313.4264296337702, 5238.423773384925, 5287.656302340345, 5318.787248510055, 5360.731963401824, 5388.299521062267, 4951.207806750921, 4977.975110124454, 5115.232715092738, 5136.101182293102, 5251.107311757001, 2920.3721199942006, 3395.2530443302435, 4026.9663819770976, 4038.818253178599, 4045.5954404559457, 3471.4098603237385, 4013.5986401359924, 4021.9597804022023, 4037.394390841368, 4041.6564592009713, 3252.333271679312, 3369.9216250799755, 3754.8122593870335, 4001.8799436016966, 4013.177298653769, 3015.381846721417, 3218.6942240487115, 4001.5598284188864, 4005.83976640934, 4022.9597704022876, 3295.719863811555, 3465.705415039725, 3475.304939012208, 3494.5107684924133, 3495.8077305748175, 4475.820967161309, 4828.044641920028, 5119.923201151985, 5141.922871156936, 5154.639175257734, 4224.957750422487, 4379.737215767064, 4554.157480866051, 4572.9314060289125, 4605.539446055401, 5473.589480788917, 5523.972380138114, 5614.978607701224, 5636.505366672689, 5645.0, 3795.2599243147597, 3803.4675145479655, 3830.4637350770913, 3844.2311537692294, 3848.8652897148745, 4693.55411235932, 5532.502074813246, 5739.397363276879, 5755.913661295084, 5808.854778630568, 3433.8454769535433, 3457.6369481204615, 3591.3715099857423, 3812.4281357796553, 3844.2695887781233, 3459.187091033605, 3923.489946306962, 3935.2916475034767, 3937.8031098445535, 3946.2502124596235, 4167.5832416758085, 4557.339185818038, 4557.749323787158, 4585.197590421662, 4587.839425620087, 5273.736313184402, 5328.813491527778, 5505.724713764296, 5520.972395137998, 5532.6680399175375, 1271.8728127187392, 1300.466808608466, 1324.6622111361542, 1327.8672132786837, 1329.7274058817918, 721.3904250907974, 776.9006855299688, 789.9960500197462, 877.8727084572829, 897.8877640294963, 4170.270202714512, 4409.713368631029, 4930.13722598551, 5047.0, 5117.974410128, 5565.415631358649, 5674.177244299636, 5680.403557626472, 5701.258836351248, 5750.281214848144, 4508.4174585056835, 4898.853034408973, 4941.8633714245925, 5033.647644664911, 5086.16078347076, 2904.8547572621706, 2936.588877557144, 2950.963834953875, 2973.910782676523, 2975.2710585906157, 5012.47369026247, 5252.606054545924, 5419.783208671609, 5868.67722275279, 5874.530037596982, 5110.437851836295, 5449.591280653967, 5805.0, 5845.457226106657, 5917.704114794243, 4902.803887844589, 5705.657660540381, 5912.0, 5973.49225315853, 6006.369331220247, 4708.905821883612, 5336.359636843604, 5693.800716974886, 5710.602835816315, 5738.68437235956, 4910.3125562422, 5112.74436278192, 5189.636725429184, 5658.0, 5665.433456654301, 5408.810691625774, 5909.099994003936, 5916.408359164049, 5943.346231914399, 5954.915779477148, 3251.837408129537, 3652.872149474862, 3817.102980799454, 3873.0, 3876.1860009398342, 5603.898538048232, 5704.229928959538, 5723.198752174782, 5726.911886741505, 5727.8854422912145, 4684.859454216379, 5355.544778693698, 5643.125315576092, 5651.660900345993, 5722.828315150552, 4649.628029757679, 5075.492450754895, 5222.634415590872, 5546.36216835053, 5627.692646015278, 4632.4904260531985, 5200.557952574073, 5357.892842143213, 5502.119660854404, 5533.5849811265125, 5133.281340612392, 5439.129739241703, 5442.673439593479, 5609.242752228397, 5683.175939488834, 5038.2190760432195, 5472.753726082256, 5499.807506737245, 5525.502704756591, 5548.0], "value": 4013.9222443185445, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.8566286742652, 726.7492715013321, 776.8290975985285, 828.2214718164925, 832.3008672714926, 14939.25303734813, 16432.0, 16433.0, 16464.753028704577, 16483.42308019218, 14701.779473307883, 14862.405503779835, 14869.55391338259, 14873.958822882403, 14895.92552037241, 679.0696745458729, 703.2686006553179, 826.0500424511829, 833.1418638802044, 852.7569642651846, 851.7274472168908, 856.5717141429285, 862.6937437209795, 864.1660797330575, 865.9350548708841, 911.3711539038076, 915.0940568836837, 915.6566287642135, 916.1754421021068, 916.8120535290283, 709.684190535212, 864.2049314630555, 906.315731622624, 908.9681861134894, 909.3452714045909, 669.0967194287673, 676.9559978601375, 679.4326737174465, 682.0281099433337, 683.6376720338179, 14665.193414362206, 14684.559463216121, 14698.70602587953, 14705.55883323502, 14735.484258050918, 13472.046553249724, 13677.794833077513, 13863.37614807326, 13937.0, 14076.2961851907, 10867.728306792314, 11176.0, 12058.0, 13091.14907531017, 13369.799453008212, 4575.748333841638, 4617.37665415168, 4675.4155730533685, 4782.234842425195, 4824.517548245182, 100815.0, 114005.0, 115059.99999999916, 115173.0, 115264.42367788107, 10439.321444106108, 10633.043026127685, 11001.449927503752, 11046.502907369188, 11089.889101109047, 21302.680459793115, 21641.78358216398, 21658.783412165678, 21692.56614867694, 21742.0, 7823.178566250575, 8030.678772849021, 8114.756557303291, 8209.507429554245, 8231.0, 3780.0, 4354.477462704495, 4459.175052615276, 4476.1495315890525, 4530.6602004849765, 3338.315645292758, 3387.1193489692364, 3448.7756846319194, 3450.4996775467416, 3454.516367708523, 573.7131434282859, 590.4626789621424, 597.6115524908844, 642.1555654314561, 652.1782553982017, 455.6641725395654, 572.4189947203591, 598.2491972574478, 599.07307598691, 626.0, 8648.956755216184, 8819.691310804092, 8972.775680608038, 9113.134252246025, 9259.0, 537.7069497124077, 560.9607327487118, 581.3111462916457, 585.748128304833, 627.5826575327357, 3876.9418458723144, 4356.607905288539, 4358.782060896942, 4368.475782906008, 4446.666500012448, 92217.0, 124488.1326780099, 146037.07925841637, 161201.0, 161481.19259403629, 77935.0, 145204.0, 146576.0, 147268.263658681, 149137.2543137299, 124279.13581296288, 126387.3680631591, 127880.0, 128598.0, 128788.35605822099, 8281.461704989157, 8330.0, 8419.0, 8468.957655211685, 8611.224989750952, 46173.0, 61014.69492652508, 66520.66739666271, 66914.66170676656, 67215.3278467209, 65808.67095664491, 77377.22622773926, 95490.0, 97543.0, 97867.0, 13068.0, 13288.86711132915, 13926.955478339367, 14073.507427239998, 14127.858721412655, 15150.772738409154, 15358.15530145808, 15403.998740081859, 15758.448454304504, 15868.60328491797, 13844.86155138476, 14076.859231407554, 14118.647033824236, 14343.067700599428, 14554.41782328695, 12066.155369124077, 12305.56930507428, 12422.937885310515, 13766.105203161762, 14582.781258281346, 13418.127821691558, 13735.0, 13747.725045498833, 13864.445422183, 14133.010689251654, 11419.828702569303, 11475.827862582235, 11597.362145082101, 11611.303321800719, 12134.211276267015, 10064.547095380873, 10487.52806123711, 11359.659210223375, 11550.653480395602, 11573.652790416301], "value": 7809.526330075833, "unit": "score"}], "name": "dromaeo_css", "value": 7174.769426357686, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 112.0, 112.0, 112.0, 111.0, 113.0, 113.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 103.0, 166.0, 104.0, 107.0, 130.0, 105.0, 146.0, 107.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 185.0, 184.0, 185.0, 184.0, 184.0, 180.0, 184.0, 182.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 66.0, 86.0, 60.0, 57.0, 60.0, 67.0, 83.0, 57.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 68.0, 102.0, 71.0, 71.0, 68.0, 97.0, 70.0, 69.0, 129.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 95.0, 91.0, 89.0, 85.0, 88.0, 90.0, 88.0, 84.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 113.0, 151.0, 100.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 87.0, 89.0, 136.0, 73.0, 135.0, 136.0, 108.0], "value": 102.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 54.0, 57.0, 55.0, 55.0, 53.0, 53.0, 53.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 81.0, 84.0, 79.0, 74.0, 74.0, 73.0, 73.0, 73.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 83.0, 85.0, 85.0, 85.0, 85.0, 87.0, 83.0, 115.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 144.0, 154.0, 150.0, 157.0, 140.0, 130.0, 146.0, 167.0, 153.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 166.0, 163.0, 164.0, 175.0, 191.0, 164.0, 164.0, 165.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 67.0, 67.0, 65.0, 66.0, 67.0, 65.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1459.8000000000002, "alertThreshold": 2.0}]} 09:13:44 INFO - Return code: 0 09:13:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:13:44 INFO - # TBPL SUCCESS # 09:13:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:13:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:13:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:13:44 INFO - 2016-08-11 09:13:44.280 screenresolution[2003:13178] starting screenresolution argv=screenresolution get 09:13:44 INFO - 2016-08-11 09:13:44.284 screenresolution[2003:13178] Display 0: 1600x1200x32@60 09:13:44 INFO - 2016-08-11 09:13:44.290 screenresolution[2004:13181] starting screenresolution argv=screenresolution list 09:13:44 INFO - Available Modes on Display 0 09:13:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:13:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:13:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:13:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:13:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:13:44 INFO - Intel Iris: 09:13:44 INFO - Chipset Model: Intel Iris 09:13:44 INFO - Type: GPU 09:13:44 INFO - Bus: Built-In 09:13:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:13:44 INFO - Vendor: Intel (0x8086) 09:13:44 INFO - Device ID: 0x0a2e 09:13:44 INFO - Revision ID: 0x0009 09:13:44 INFO - Displays: 09:13:44 INFO - Display: 09:13:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:13:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:13:44 INFO - Display Serial Number: 1600x1200 60 09:13:44 INFO - Main Display: Yes 09:13:44 INFO - Mirror: Off 09:13:44 INFO - Online: Yes 09:13:44 INFO - Rotation: Supported 09:13:44 INFO - Return code: 0 09:13:44 INFO - Running post-action listener: _resource_record_post_action 09:13:44 INFO - [mozharness: 2016-08-11 16:13:44.525157Z] Finished run-tests step (success) 09:13:44 INFO - Running post-run listener: _resource_record_post_run 09:13:44 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 249856; Write bytes: 701500928; Read time: 8; Write time: 1960 09:13:44 INFO - TinderboxPrint: CPU usage
53.4% 09:13:44 INFO - TinderboxPrint: I/O read bytes / time
249,856 / 8 09:13:44 INFO - TinderboxPrint: I/O write bytes / time
701,500,928 / 1,960 09:13:44 INFO - TinderboxPrint: CPU idle
2,262.6 (46.6%) 09:13:44 INFO - TinderboxPrint: CPU system
971.8 (20.0%) 09:13:44 INFO - TinderboxPrint: CPU user
1,619.3 (33.4%) 09:13:44 INFO - TinderboxPrint: Swap in / out
404,176,896 / 0 09:13:44 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401463808; Write bytes: 395834880; Read time: 29701; Write time: 830 09:13:44 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 204800; Write bytes: 293804032; Read time: 7; Write time: 1092 09:13:45 INFO - Running post-run listener: _upload_blobber_files 09:13:45 INFO - Blob upload gear active. 09:13:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:13:45 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 09:13:45 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:13:45 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:13:45 INFO - (blobuploader) - INFO - Open directory for files ... 09:13:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:13:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:13:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:46 INFO - (blobuploader) - INFO - Done attempting. 09:13:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:13:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:13:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:47 INFO - (blobuploader) - INFO - Done attempting. 09:13:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:13:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:13:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:49 INFO - (blobuploader) - INFO - Done attempting. 09:13:49 INFO - (blobuploader) - INFO - Iteration through files over. 09:13:49 INFO - Return code: 0 09:13:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:13:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:13:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fdf325a9bec8848627c1abf882e50fa323c2c5f031eb02047170212e5d794f0ddcf396d2e600599912d8a42ce2c02fbc7854d718ab180875662879a1440b3bf5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9be160ebda13d3c119b6a01050209c40d689f704a1a0aed9e993e6cf7cbe748f9f459f0068d49794584113d801250fecd32769a109f2c57a7d0c968f0babf946", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c87893424cc89957c032862aabc12de3e72e673d4578e12c633464ebe7fff5f7d87239ee96480d66945e7dc3e357fbf4609ef2e901ab241cfd04fac0ed702ec2"} 09:13:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:13:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:13:49 INFO - Contents: 09:13:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fdf325a9bec8848627c1abf882e50fa323c2c5f031eb02047170212e5d794f0ddcf396d2e600599912d8a42ce2c02fbc7854d718ab180875662879a1440b3bf5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9be160ebda13d3c119b6a01050209c40d689f704a1a0aed9e993e6cf7cbe748f9f459f0068d49794584113d801250fecd32769a109f2c57a7d0c968f0babf946", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c87893424cc89957c032862aabc12de3e72e673d4578e12c633464ebe7fff5f7d87239ee96480d66945e7dc3e357fbf4609ef2e901ab241cfd04fac0ed702ec2"} 09:13:49 INFO - Running post-run listener: copy_logs_to_upload_dir 09:13:49 INFO - Copying logs to upload dir... 09:13:49 INFO - mkdir: /builds/slave/test/build/upload/logs 09:13:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.490582 ========= master_lag: 1.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-11 09:13:50.432974) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 09:13:50.439984) ========= 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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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/Autoland/sha512/fdf325a9bec8848627c1abf882e50fa323c2c5f031eb02047170212e5d794f0ddcf396d2e600599912d8a42ce2c02fbc7854d718ab180875662879a1440b3bf5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9be160ebda13d3c119b6a01050209c40d689f704a1a0aed9e993e6cf7cbe748f9f459f0068d49794584113d801250fecd32769a109f2c57a7d0c968f0babf946", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c87893424cc89957c032862aabc12de3e72e673d4578e12c633464ebe7fff5f7d87239ee96480d66945e7dc3e357fbf4609ef2e901ab241cfd04fac0ed702ec2"} build_url:https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010180 build_url: 'https://queue.taskcluster.net/v1/task/fSkofMknQUi8oc9sUxacRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fdf325a9bec8848627c1abf882e50fa323c2c5f031eb02047170212e5d794f0ddcf396d2e600599912d8a42ce2c02fbc7854d718ab180875662879a1440b3bf5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9be160ebda13d3c119b6a01050209c40d689f704a1a0aed9e993e6cf7cbe748f9f459f0068d49794584113d801250fecd32769a109f2c57a7d0c968f0babf946", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c87893424cc89957c032862aabc12de3e72e673d4578e12c633464ebe7fff5f7d87239ee96480d66945e7dc3e357fbf4609ef2e901ab241cfd04fac0ed702ec2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 09:13:50.474386) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 09:13:50.474715) ========= 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.SeQCAsEMpx/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.YuP1wDxu1v/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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.004918 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 09:13:50.523183) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 09:13:50.523481) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 09:13:50.526834) ========= ========= Total master_lag: 1.35 =========