builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0104 starttime: 1472538157.99 results: success (0) buildid: 20160829213322 builduid: 67ac40d5f45946db9386e28ed2442b29 revision: 49253ea90409c02b5a6fb1d3687ab3de73d32bea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:37.995347) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:37.995760) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:37.996050) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:38.183530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:38.183919) ========= 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.BAxiv8nwji/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.Ur5msYu6e9/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-29 23:22:38-- 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% 37.4M=0s 2016-08-29 23:22:38 (37.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.628774 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:38.837813) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:38.838515) ========= 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.BAxiv8nwji/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.Ur5msYu6e9/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.020487 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:38.891800) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 23:22:38.892378) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 49253ea90409c02b5a6fb1d3687ab3de73d32bea --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 49253ea90409c02b5a6fb1d3687ab3de73d32bea --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BAxiv8nwji/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.Ur5msYu6e9/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-29 23:22:38,964 truncating revision to first 12 chars 2016-08-29 23:22:38,964 Setting DEBUG logging. 2016-08-29 23:22:38,964 attempt 1/10 2016-08-29 23:22:38,964 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/49253ea90409?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 23:22:40,117 unpacking tar archive at: autoland-49253ea90409/testing/mozharness/ program finished with exit code 0 elapsedTime=1.400434 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 23:22:40.315973) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:40.316277) ========= script_repo_revision: 49253ea90409c02b5a6fb1d3687ab3de73d32bea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:40.316668) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:40.316935) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 23:22:40.335411) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-29 23:22:40.335756) ========= /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.BAxiv8nwji/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.Ur5msYu6e9/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 23:22:40 INFO - MultiFileLogger online at 20160829 23:22:40 in /builds/slave/test 23:22:40 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 23:22:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:22:40 INFO - {'append_to_log': False, 23:22:40 INFO - 'base_work_dir': '/builds/slave/test', 23:22:40 INFO - 'blob_upload_branch': 'Autoland', 23:22:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:22:40 INFO - 'branch': 'Autoland', 23:22:40 INFO - 'buildbot_json_path': 'buildprops.json', 23:22:40 INFO - 'config_files': ('talos/mac_config.py',), 23:22:40 INFO - 'default_actions': ('clobber', 23:22:40 INFO - 'read-buildbot-config', 23:22:40 INFO - 'download-and-extract', 23:22:40 INFO - 'populate-webroot', 23:22:40 INFO - 'create-virtualenv', 23:22:40 INFO - 'install', 23:22:40 INFO - 'run-tests'), 23:22:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:22:40 INFO - 'download_minidump_stackwalk': True, 23:22:40 INFO - 'download_symbols': 'ondemand', 23:22:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:22:40 INFO - 'tooltool.py': '/tools/tooltool.py', 23:22:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:22:40 INFO - '/tools/misc-python/virtualenv.py')}, 23:22:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:22:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:22:40 INFO - 'installer_path': 'installer.exe', 23:22:40 INFO - 'log_level': 'info', 23:22:40 INFO - 'log_name': 'talos', 23:22:40 INFO - 'log_to_console': True, 23:22:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:22:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:22:40 INFO - 'opt_config_files': (), 23:22:40 INFO - 'pip_index': False, 23:22:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:22:40 INFO - 'cmd': ('bash', 23:22:40 INFO - '-c', 23:22:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:22:40 INFO - 'enabled': True, 23:22:40 INFO - 'halt_on_failure': False, 23:22:40 INFO - 'name': 'check_screen_resolution'},), 23:22:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:22:40 INFO - 'cmd': ('bash', 23:22:40 INFO - '-c', 23:22:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:22:40 INFO - 'enabled': True, 23:22:40 INFO - 'halt_on_failure': False, 23:22:40 INFO - 'name': 'check_screen_resolution'},), 23:22:40 INFO - 'run_cmd_checks_enabled': True, 23:22:40 INFO - 'sps_profile': False, 23:22:40 INFO - 'sps_profile_interval': 0, 23:22:40 INFO - 'suite': 'dromaeojs', 23:22:40 INFO - 'system_bits': '32', 23:22:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:22:40 INFO - 'title': 't-yosemite-r7-0104', 23:22:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:22:40 INFO - 'use_talos_json': True, 23:22:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:22:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:22:40 INFO - 'work_dir': 'build'} 23:22:40 INFO - [mozharness: 2016-08-30 06:22:40.502227Z] Running clobber step. 23:22:40 INFO - Running pre-action listener: _resource_record_pre_action 23:22:40 INFO - Running main action method: clobber 23:22:40 INFO - rmtree: /builds/slave/test/build 23:22:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:22:40 INFO - Running post-action listener: _resource_record_post_action 23:22:40 INFO - [mozharness: 2016-08-30 06:22:40.735076Z] Finished clobber step (success) 23:22:40 INFO - [mozharness: 2016-08-30 06:22:40.735206Z] Running read-buildbot-config step. 23:22:40 INFO - Running pre-action listener: _resource_record_pre_action 23:22:40 INFO - Running main action method: read_buildbot_config 23:22:40 INFO - Using buildbot properties: 23:22:40 INFO - { 23:22:40 INFO - "project": "", 23:22:40 INFO - "product": "firefox", 23:22:40 INFO - "script_repo_revision": "production", 23:22:40 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 23:22:40 INFO - "repository": "", 23:22:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 23:22:40 INFO - "buildid": "20160829213322", 23:22:40 INFO - "pgo_build": "False", 23:22:40 INFO - "basedir": "/builds/slave/test", 23:22:40 INFO - "buildnumber": 163, 23:22:40 INFO - "slavename": "t-yosemite-r7-0104", 23:22:40 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 23:22:40 INFO - "platform": "yosemite_r7", 23:22:40 INFO - "scheduler": "tests-autoland-macosx64-talos", 23:22:40 INFO - "branch": "autoland", 23:22:40 INFO - "repo_path": "integration/autoland", 23:22:40 INFO - "revision": "49253ea90409c02b5a6fb1d3687ab3de73d32bea", 23:22:40 INFO - "stage_platform": "macosx64", 23:22:40 INFO - "builduid": "67ac40d5f45946db9386e28ed2442b29", 23:22:40 INFO - "slavebuilddir": "test" 23:22:40 INFO - } 23:22:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 23:22:40 INFO - Running post-action listener: _resource_record_post_action 23:22:40 INFO - [mozharness: 2016-08-30 06:22:40.738523Z] Finished read-buildbot-config step (success) 23:22:40 INFO - [mozharness: 2016-08-30 06:22:40.738633Z] Running download-and-extract step. 23:22:40 INFO - Running pre-action listener: _resource_record_pre_action 23:22:40 INFO - Running main action method: download_and_extract 23:22:40 INFO - mkdir: /builds/slave/test/build/tests 23:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:22:40 INFO - trying https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 23:22:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 23:22:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 23:22:42 INFO - Downloaded 1199 bytes. 23:22:42 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 23:22:42 INFO - Using the following test package requirements: 23:22:42 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:22:42 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:22:42 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 23:22:42 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:22:42 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 23:22:42 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:22:42 INFO - u'jsshell-mac.zip'], 23:22:42 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:22:42 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 23:22:42 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:22:42 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:22:42 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 23:22:42 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:22:42 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 23:22:42 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:22:42 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 23:22:42 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:22:42 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 23:22:42 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:22:42 INFO - trying https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:22:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:22:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:22:43 INFO - Downloaded 16380457 bytes. 23:22:43 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 23:22:50 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:22:50 INFO - trying https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:22:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:22:52 INFO - Downloaded 16380457 bytes. 23:22:52 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 23:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:22:59 INFO - trying https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 23:22:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 23:22:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:23:00 INFO - Downloaded 14413998 bytes. 23:23:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 23:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:01 INFO - trying https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:23:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 23:23:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 23:23:04 INFO - Downloaded 111538889 bytes. 23:23:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:23:04 INFO - mkdir: /builds/slave/test/properties 23:23:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:23:04 INFO - Writing to file /builds/slave/test/properties/build_url 23:23:04 INFO - Contents: 23:23:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:23:05 INFO - Running post-action listener: _resource_record_post_action 23:23:05 INFO - Running post-action listener: set_extra_try_arguments 23:23:05 INFO - [mozharness: 2016-08-30 06:23:05.043413Z] Finished download-and-extract step (success) 23:23:05 INFO - [mozharness: 2016-08-30 06:23:05.043556Z] Running populate-webroot step. 23:23:05 INFO - Running pre-action listener: _resource_record_pre_action 23:23:05 INFO - Running main action method: populate_webroot 23:23:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:23:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:23:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:23:05 INFO - '--tppagecycles', 23:23:05 INFO - '7'], 23:23:05 INFO - 'tests': ['tsvgm']}}, 23:23:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:23:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:23:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:23:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:23:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:23:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:23:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:05 INFO - 'talos_options': ['--disable-e10s'], 23:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:23:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:23:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:05 INFO - 'talos_options': ['--disable-e10s'], 23:23:05 INFO - 'tests': ['damp', 'tps']}, 23:23:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:05 INFO - 'tests': ['damp', 'tps']}, 23:23:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:23:05 INFO - 'tests': ['dromaeo_dom']}, 23:23:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:23:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:23:05 INFO - 'tests': ['basic_compositor_video']}, 23:23:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:23:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:23:05 INFO - 'tests': ['a11yr', 23:23:05 INFO - 'ts_paint', 23:23:05 INFO - 'tpaint', 23:23:05 INFO - 'sessionrestore', 23:23:05 INFO - 'sessionrestore_no_auto_restore', 23:23:05 INFO - 'tabpaint']}, 23:23:05 INFO - 'other-e10s': {'tests': ['a11yr', 23:23:05 INFO - 'ts_paint', 23:23:05 INFO - 'tpaint', 23:23:05 INFO - 'sessionrestore', 23:23:05 INFO - 'sessionrestore_no_auto_restore', 23:23:05 INFO - 'tabpaint']}, 23:23:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:23:05 INFO - 'ts_paint', 23:23:05 INFO - 'tpaint', 23:23:05 INFO - 'sessionrestore', 23:23:05 INFO - 'sessionrestore_no_auto_restore', 23:23:05 INFO - 'tabpaint']}, 23:23:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:23:05 INFO - 'ts_paint', 23:23:05 INFO - 'tpaint', 23:23:05 INFO - 'sessionrestore', 23:23:05 INFO - 'sessionrestore_no_auto_restore', 23:23:05 INFO - 'tabpaint']}, 23:23:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:23:05 INFO - 'tests': ['a11yr', 23:23:05 INFO - 'ts_paint', 23:23:05 INFO - 'tpaint', 23:23:05 INFO - 'sessionrestore', 23:23:05 INFO - 'sessionrestore_no_auto_restore', 23:23:05 INFO - 'tabpaint']}, 23:23:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:23:05 INFO - 'tests': ['a11yr', 23:23:05 INFO - 'ts_paint', 23:23:05 INFO - 'tpaint', 23:23:05 INFO - 'sessionrestore', 23:23:05 INFO - 'sessionrestore_no_auto_restore', 23:23:05 INFO - 'tabpaint']}, 23:23:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:23:05 INFO - 'tests': ['tsvgx', 23:23:05 INFO - 'tsvgr_opacity', 23:23:05 INFO - 'tart', 23:23:05 INFO - 'tscrollx', 23:23:05 INFO - 'cart']}, 23:23:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:23:05 INFO - 'tsvgr_opacity', 23:23:05 INFO - 'tart', 23:23:05 INFO - 'tscrollx', 23:23:05 INFO - 'cart']}, 23:23:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:05 INFO - 'talos_options': ['--disable-e10s'], 23:23:05 INFO - 'tests': ['tp5o']}, 23:23:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:05 INFO - 'tests': ['tp5o']}, 23:23:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:05 INFO - 'talos_options': ['--disable-e10s', 23:23:05 INFO - '--xperf_path', 23:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:23:05 INFO - 'tests': ['tp5n']}, 23:23:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:05 INFO - 'talos_options': ['--xperf_path', 23:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:23:05 INFO - 'tests': ['tp5n']}}, 23:23:05 INFO - 'talos.zip': {'path': '', 23:23:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:23:05 INFO - Running post-action listener: _resource_record_post_action 23:23:05 INFO - [mozharness: 2016-08-30 06:23:05.062429Z] Finished populate-webroot step (success) 23:23:05 INFO - [mozharness: 2016-08-30 06:23:05.062545Z] Running create-virtualenv step. 23:23:05 INFO - Running pre-action listener: _resource_record_pre_action 23:23:05 INFO - Running main action method: create_virtualenv 23:23:05 INFO - Creating virtualenv /builds/slave/test/build/venv 23:23:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:23:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:23:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:23:05 INFO - Using real prefix '/tools/python27' 23:23:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:23:05 INFO - Installing distribute.............................................................................................................................................................................................done. 23:23:08 INFO - Installing pip.................done. 23:23:08 INFO - Return code: 0 23:23:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105590a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105625618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa178cdaa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054f4d30>, '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 0x105259db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054ecb20>, '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.BAxiv8nwji/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.Ur5msYu6e9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:23: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', 'pip>=1.5'] in /builds/slave/test/build 23:23: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 pip>=1.5 23:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BAxiv8nwji/Render', 23:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:08 INFO - 'HOME': '/Users/cltbld', 23:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:08 INFO - 'LOGNAME': 'cltbld', 23:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:08 INFO - 'MOZ_NO_REMOTE': '1', 23:23:08 INFO - 'NO_EM_RESTART': '1', 23:23:08 INFO - 'PAGER': '/bin/cat', 23:23:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:08 INFO - 'PWD': '/builds/slave/test', 23:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:08 INFO - 'SHELL': '/bin/bash', 23:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ur5msYu6e9/Listeners', 23:23:08 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 23:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:08 INFO - 'USER': 'cltbld', 23:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:08 INFO - 'XPC_FLAGS': '0x0', 23:23:08 INFO - 'XPC_SERVICE_NAME': '0', 23:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:23:09 INFO - Cleaning up... 23:23:09 INFO - Return code: 0 23:23:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:09 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 0x105590a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105625618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa178cdaa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054f4d30>, '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 0x105259db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054ecb20>, '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.BAxiv8nwji/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.Ur5msYu6e9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:23:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BAxiv8nwji/Render', 23:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:09 INFO - 'HOME': '/Users/cltbld', 23:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:09 INFO - 'LOGNAME': 'cltbld', 23:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:09 INFO - 'MOZ_NO_REMOTE': '1', 23:23:09 INFO - 'NO_EM_RESTART': '1', 23:23:09 INFO - 'PAGER': '/bin/cat', 23:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:09 INFO - 'PWD': '/builds/slave/test', 23:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:09 INFO - 'SHELL': '/bin/bash', 23:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ur5msYu6e9/Listeners', 23:23:09 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 23:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:09 INFO - 'USER': 'cltbld', 23:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:09 INFO - 'XPC_FLAGS': '0x0', 23:23:09 INFO - 'XPC_SERVICE_NAME': '0', 23:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:09 INFO - Downloading/unpacking psutil>=3.1.1 23:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:23:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:23:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:23:13 INFO - Installing collected packages: psutil 23:23:13 INFO - Running setup.py install for psutil 23:23:13 INFO - building 'psutil._psutil_osx' extension 23:23:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 23:23:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 23:23:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 23:23:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 23:23:13 INFO - building 'psutil._psutil_posix' extension 23:23:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 23:23:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:23:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:23:13 INFO - ^ 23:23:13 INFO - 1 warning generated. 23:23:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 23:23:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:23:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:23:13 INFO - Successfully installed psutil 23:23:13 INFO - Cleaning up... 23:23:13 INFO - Return code: 0 23:23:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105590a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105625618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa178cdaa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054f4d30>, '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 0x105259db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054ecb20>, '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.BAxiv8nwji/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.Ur5msYu6e9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:23:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:23:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:23:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BAxiv8nwji/Render', 23:23:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:13 INFO - 'HOME': '/Users/cltbld', 23:23:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:13 INFO - 'LOGNAME': 'cltbld', 23:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:13 INFO - 'MOZ_NO_REMOTE': '1', 23:23:13 INFO - 'NO_EM_RESTART': '1', 23:23:13 INFO - 'PAGER': '/bin/cat', 23:23:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:13 INFO - 'PWD': '/builds/slave/test', 23:23:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:13 INFO - 'SHELL': '/bin/bash', 23:23:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ur5msYu6e9/Listeners', 23:23:13 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 23:23:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:13 INFO - 'USER': 'cltbld', 23:23:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:13 INFO - 'XPC_FLAGS': '0x0', 23:23:13 INFO - 'XPC_SERVICE_NAME': '0', 23:23:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:23: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 23:23: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 23:23: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 23:23: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 23:23: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 23:23: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 23:23:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:23:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:23:16 INFO - Installing collected packages: mozsystemmonitor 23:23:16 INFO - Running setup.py install for mozsystemmonitor 23:23:16 INFO - Successfully installed mozsystemmonitor 23:23:16 INFO - Cleaning up... 23:23:16 INFO - Return code: 0 23:23:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105590a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105625618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa178cdaa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054f4d30>, '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 0x105259db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054ecb20>, '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.BAxiv8nwji/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.Ur5msYu6e9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:23:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:23:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:23:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BAxiv8nwji/Render', 23:23:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:16 INFO - 'HOME': '/Users/cltbld', 23:23:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:16 INFO - 'LOGNAME': 'cltbld', 23:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:16 INFO - 'MOZ_NO_REMOTE': '1', 23:23:16 INFO - 'NO_EM_RESTART': '1', 23:23:16 INFO - 'PAGER': '/bin/cat', 23:23:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:16 INFO - 'PWD': '/builds/slave/test', 23:23:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:16 INFO - 'SHELL': '/bin/bash', 23:23:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ur5msYu6e9/Listeners', 23:23:16 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 23:23:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:16 INFO - 'USER': 'cltbld', 23:23:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:16 INFO - 'XPC_FLAGS': '0x0', 23:23:16 INFO - 'XPC_SERVICE_NAME': '0', 23:23:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:17 INFO - Downloading/unpacking jsonschema==2.5.1 23:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:23:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:20 INFO - Downloading functools32-3.2.3-2.tar.gz 23:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:23:20 INFO - warning: no files found matching '*.txt' 23:23:20 INFO - no previously-included directories found matching 'build' 23:23:20 INFO - no previously-included directories found matching 'dist' 23:23:20 INFO - no previously-included directories found matching '.git*' 23:23:20 INFO - Installing collected packages: jsonschema, functools32 23:23:20 INFO - Running setup.py install for functools32 23:23:20 INFO - warning: no files found matching '*.txt' 23:23:20 INFO - no previously-included directories found matching 'build' 23:23:20 INFO - no previously-included directories found matching 'dist' 23:23:20 INFO - no previously-included directories found matching '.git*' 23:23:20 INFO - Successfully installed jsonschema functools32 23:23:20 INFO - Cleaning up... 23:23:20 INFO - Return code: 0 23:23:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105590a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105625618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa178cdaa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054f4d30>, '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 0x105259db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054ecb20>, '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.BAxiv8nwji/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.Ur5msYu6e9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BAxiv8nwji/Render', 23:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:20 INFO - 'HOME': '/Users/cltbld', 23:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:20 INFO - 'LOGNAME': 'cltbld', 23:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:20 INFO - 'MOZ_NO_REMOTE': '1', 23:23:20 INFO - 'NO_EM_RESTART': '1', 23:23:20 INFO - 'PAGER': '/bin/cat', 23:23:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:20 INFO - 'PWD': '/builds/slave/test', 23:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:20 INFO - 'SHELL': '/bin/bash', 23:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ur5msYu6e9/Listeners', 23:23:20 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 23:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:20 INFO - 'USER': 'cltbld', 23:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:20 INFO - 'XPC_FLAGS': '0x0', 23:23:20 INFO - 'XPC_SERVICE_NAME': '0', 23:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:23:20 INFO - Cleaning up... 23:23:20 INFO - Return code: 0 23:23:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105590a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105625618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa178cdaa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054f4d30>, '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 0x105259db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054ecb20>, '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.BAxiv8nwji/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.Ur5msYu6e9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BAxiv8nwji/Render', 23:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:20 INFO - 'HOME': '/Users/cltbld', 23:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:20 INFO - 'LOGNAME': 'cltbld', 23:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:20 INFO - 'MOZ_NO_REMOTE': '1', 23:23:20 INFO - 'NO_EM_RESTART': '1', 23:23:20 INFO - 'PAGER': '/bin/cat', 23:23:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:20 INFO - 'PWD': '/builds/slave/test', 23:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:20 INFO - 'SHELL': '/bin/bash', 23:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ur5msYu6e9/Listeners', 23:23:20 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 23:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:20 INFO - 'USER': 'cltbld', 23:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:20 INFO - 'XPC_FLAGS': '0x0', 23:23:20 INFO - 'XPC_SERVICE_NAME': '0', 23:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:20 INFO - Downloading/unpacking blobuploader==1.2.4 23:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:23 INFO - Downloading blobuploader-1.2.4.tar.gz 23:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:23:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:23:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:24 INFO - Downloading docopt-0.6.1.tar.gz 23:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:23:24 INFO - Installing collected packages: blobuploader, requests, docopt 23:23:24 INFO - Running setup.py install for blobuploader 23:23:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:23:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:23:24 INFO - Running setup.py install for requests 23:23:25 INFO - Running setup.py install for docopt 23:23:25 INFO - Successfully installed blobuploader requests docopt 23:23:25 INFO - Cleaning up... 23:23:25 INFO - Return code: 0 23:23:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105590a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105625618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa178cdaa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054f4d30>, '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 0x105259db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054ecb20>, '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.BAxiv8nwji/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.Ur5msYu6e9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:23:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BAxiv8nwji/Render', 23:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:25 INFO - 'HOME': '/Users/cltbld', 23:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:25 INFO - 'LOGNAME': 'cltbld', 23:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:25 INFO - 'MOZ_NO_REMOTE': '1', 23:23:25 INFO - 'NO_EM_RESTART': '1', 23:23:25 INFO - 'PAGER': '/bin/cat', 23:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:25 INFO - 'PWD': '/builds/slave/test', 23:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:25 INFO - 'SHELL': '/bin/bash', 23:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ur5msYu6e9/Listeners', 23:23:25 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 23:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:25 INFO - 'USER': 'cltbld', 23:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:25 INFO - 'XPC_FLAGS': '0x0', 23:23:25 INFO - 'XPC_SERVICE_NAME': '0', 23:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:23:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-XIOYL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:23:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-3QHNup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:23:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-6zgYQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:23:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-vCvj0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:23:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-cc19r_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:23:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-5nbKJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:23:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-PE4dFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:23:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-FCdya5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:23:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1RMA0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:23:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-sxUMtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:23:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-G0elQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:23:27 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-KSZ51q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:23:27 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-oOUGpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:23:27 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-z5Pbhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:23:27 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-qKXKnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:23:27 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-v8PwUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:23:27 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-BTdDay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:23:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:23:27 INFO - Running setup.py install for manifestparser 23:23:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:23:27 INFO - Running setup.py install for mozcrash 23:23:27 INFO - Running setup.py install for mozdebug 23:23:28 INFO - Running setup.py install for mozdevice 23:23:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:23:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:23:28 INFO - Running setup.py install for mozfile 23:23:28 INFO - Running setup.py install for mozhttpd 23:23:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:23:28 INFO - Running setup.py install for mozinfo 23:23:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:23:28 INFO - Running setup.py install for mozInstall 23:23:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:23:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:23:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:23:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:23:28 INFO - Running setup.py install for mozleak 23:23:29 INFO - Running setup.py install for mozlog 23:23:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:23:29 INFO - Running setup.py install for moznetwork 23:23:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:23:29 INFO - Running setup.py install for mozprocess 23:23:29 INFO - Running setup.py install for mozprofile 23:23:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:23:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:23:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:23:29 INFO - Running setup.py install for mozrunner 23:23:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:23:29 INFO - Running setup.py install for mozscreenshot 23:23:30 INFO - Running setup.py install for moztest 23:23:30 INFO - Running setup.py install for mozversion 23:23:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:23:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:23:30 INFO - Cleaning up... 23:23:30 INFO - Return code: 0 23:23:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:23: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')]} 23:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23: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')]} 23:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105590a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105625618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa178cdaa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054f4d30>, '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 0x105259db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054ecb20>, '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.BAxiv8nwji/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.Ur5msYu6e9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:23:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BAxiv8nwji/Render', 23:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:30 INFO - 'HOME': '/Users/cltbld', 23:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:30 INFO - 'LOGNAME': 'cltbld', 23:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:30 INFO - 'MOZ_NO_REMOTE': '1', 23:23:30 INFO - 'NO_EM_RESTART': '1', 23:23:30 INFO - 'PAGER': '/bin/cat', 23:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:30 INFO - 'PWD': '/builds/slave/test', 23:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:30 INFO - 'SHELL': '/bin/bash', 23:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ur5msYu6e9/Listeners', 23:23:30 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 23:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:30 INFO - 'USER': 'cltbld', 23:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:30 INFO - 'XPC_FLAGS': '0x0', 23:23:30 INFO - 'XPC_SERVICE_NAME': '0', 23:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:23:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-TZyLbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:23:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-XmKi2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:23:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-t6Cmwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:23:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-s0T9XG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:23:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-4lCN0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:23:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-zE_X3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:23:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-lBYeaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:23:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-dgNVGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:23:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-IJuNMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:23:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-SSohU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:23:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-n4CzAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:23:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ROLPZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:23:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1x1tpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:23:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-MgoFYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:23:32 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-kgasFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:23:32 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-xAptSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:23:32 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Gzwe8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:23:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:34 INFO - Downloading blessings-1.6.tar.gz 23:23:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:23:34 INFO - Installing collected packages: blessings 23:23:34 INFO - Running setup.py install for blessings 23:23:35 INFO - Successfully installed blessings 23:23:35 INFO - Cleaning up... 23:23:35 INFO - Return code: 0 23:23:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:23:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:23:35 INFO - Reading from file tmpfile_stdout 23:23:35 INFO - Current package versions: 23:23:35 INFO - blessings == 1.6 23:23:35 INFO - blobuploader == 1.2.4 23:23:35 INFO - docopt == 0.6.1 23:23:35 INFO - functools32 == 3.2.3-2 23:23:35 INFO - jsonschema == 2.5.1 23:23:35 INFO - manifestparser == 1.1 23:23:35 INFO - mozInstall == 1.12 23:23:35 INFO - mozcrash == 0.17 23:23:35 INFO - mozdebug == 0.1 23:23:35 INFO - mozdevice == 0.48 23:23:35 INFO - mozfile == 1.2 23:23:35 INFO - mozhttpd == 0.7 23:23:35 INFO - mozinfo == 0.9 23:23:35 INFO - mozleak == 0.1 23:23:35 INFO - mozlog == 3.3 23:23:35 INFO - moznetwork == 0.27 23:23:35 INFO - mozprocess == 0.23 23:23:35 INFO - mozprofile == 0.28 23:23:35 INFO - mozrunner == 6.12 23:23:35 INFO - mozscreenshot == 0.1 23:23:35 INFO - mozsystemmonitor == 0.3 23:23:35 INFO - moztest == 0.7 23:23:35 INFO - mozversion == 1.4 23:23:35 INFO - psutil == 3.1.1 23:23:35 INFO - requests == 1.2.3 23:23:35 INFO - wsgiref == 0.1.2 23:23:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105590a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105625618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa178cdaa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054f4d30>, '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 0x105259db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054ecb20>, '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.BAxiv8nwji/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.Ur5msYu6e9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:23:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:23:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BAxiv8nwji/Render', 23:23:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:35 INFO - 'HOME': '/Users/cltbld', 23:23:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:35 INFO - 'LOGNAME': 'cltbld', 23:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:35 INFO - 'MOZ_NO_REMOTE': '1', 23:23:35 INFO - 'NO_EM_RESTART': '1', 23:23:35 INFO - 'PAGER': '/bin/cat', 23:23:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:35 INFO - 'PWD': '/builds/slave/test', 23:23:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:35 INFO - 'SHELL': '/bin/bash', 23:23:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ur5msYu6e9/Listeners', 23:23:35 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 23:23:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:35 INFO - 'USER': 'cltbld', 23:23:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:35 INFO - 'XPC_FLAGS': '0x0', 23:23:35 INFO - 'XPC_SERVICE_NAME': '0', 23:23:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:23:35 INFO - Cleaning up... 23:23:35 INFO - Return code: 0 23:23:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105590a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105625618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa178cdaa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054f4d30>, '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 0x105259db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054ecb20>, '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.BAxiv8nwji/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.Ur5msYu6e9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:23:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:23:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BAxiv8nwji/Render', 23:23:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:35 INFO - 'HOME': '/Users/cltbld', 23:23:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:35 INFO - 'LOGNAME': 'cltbld', 23:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:35 INFO - 'MOZ_NO_REMOTE': '1', 23:23:35 INFO - 'NO_EM_RESTART': '1', 23:23:35 INFO - 'PAGER': '/bin/cat', 23:23:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:35 INFO - 'PWD': '/builds/slave/test', 23:23:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:35 INFO - 'SHELL': '/bin/bash', 23:23:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ur5msYu6e9/Listeners', 23:23:35 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 23:23:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:35 INFO - 'USER': 'cltbld', 23:23:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:35 INFO - 'XPC_FLAGS': '0x0', 23:23:35 INFO - 'XPC_SERVICE_NAME': '0', 23:23:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:23:35 INFO - Cleaning up... 23:23:36 INFO - Return code: 0 23:23:36 INFO - Running post-action listener: _resource_record_post_action 23:23:36 INFO - Running post-action listener: _start_resource_monitoring 23:23:36 INFO - Starting resource monitoring. 23:23:36 INFO - [mozharness: 2016-08-30 06:23:36.033594Z] Finished create-virtualenv step (success) 23:23:36 INFO - [mozharness: 2016-08-30 06:23:36.034600Z] Running install step. 23:23:36 INFO - Running pre-action listener: _resource_record_pre_action 23:23:36 INFO - Running main action method: install 23:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:23:36 INFO - Reading from file tmpfile_stdout 23:23:36 INFO - Detecting whether we're running mozinstall >=1.0... 23:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:23:36 INFO - Reading from file tmpfile_stdout 23:23:36 INFO - Output received: 23:23:36 INFO - Usage: mozinstall [options] installer 23:23:36 INFO - Options: 23:23:36 INFO - -h, --help show this help message and exit 23:23:36 INFO - -d DEST, --destination=DEST 23:23:36 INFO - Directory to install application into. [default: 23:23:36 INFO - "/builds/slave/test"] 23:23:36 INFO - --app=APP Application being installed. [default: firefox] 23:23:36 INFO - mkdir: /builds/slave/test/build/application 23:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:24:10 INFO - Reading from file tmpfile_stdout 23:24:10 INFO - Output received: 23:24:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:24:10 INFO - Running post-action listener: _resource_record_post_action 23:24:10 INFO - [mozharness: 2016-08-30 06:24:10.819713Z] Finished install step (success) 23:24:10 INFO - [mozharness: 2016-08-30 06:24:10.819857Z] Running run-tests step. 23:24:10 INFO - Running pre-action listener: _resource_record_pre_action 23:24:10 INFO - Running main action method: run_tests 23:24:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:24:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:24:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:24:10 INFO - 2016-08-29 23:24:10.834 screenresolution[1897:9872] starting screenresolution argv=screenresolution get 23:24:10 INFO - 2016-08-29 23:24:10.837 screenresolution[1897:9872] Display 0: 1600x1200x32@60 23:24:10 INFO - 2016-08-29 23:24:10.842 screenresolution[1898:9874] starting screenresolution argv=screenresolution list 23:24:10 INFO - Available Modes on Display 0 23:24:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:24:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:24:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:24:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:24:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:24:11 INFO - Intel Iris: 23:24:11 INFO - Chipset Model: Intel Iris 23:24:11 INFO - Type: GPU 23:24:11 INFO - Bus: Built-In 23:24:11 INFO - VRAM (Dynamic, Max): 1536 MB 23:24:11 INFO - Vendor: Intel (0x8086) 23:24:11 INFO - Device ID: 0x0a2e 23:24:11 INFO - Revision ID: 0x0009 23:24:11 INFO - Displays: 23:24:11 INFO - Display: 23:24:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:24:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:24:11 INFO - Display Serial Number: 1600x1200 60 23:24:11 INFO - Main Display: Yes 23:24:11 INFO - Mirror: Off 23:24:11 INFO - Online: Yes 23:24:11 INFO - Rotation: Supported 23:24:11 INFO - Return code: 0 23:24:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:24:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:24:11 INFO - Python 2.7.3 23:24:11 INFO - Return code: 0 23:24:11 INFO - grabbing minidump binary from tooltool 23:24: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')]} 23:24:11 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 0x1054f4d30>, '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 0x105259db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1054ecb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:24:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:24:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 23:24:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:24:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:24:11 INFO - Return code: 0 23:24:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:24:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:24:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:24:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:24:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:24:11 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/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:24:11 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/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0104 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:24:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BAxiv8nwji/Render', 23:24:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:24:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:24:11 INFO - 'HOME': '/Users/cltbld', 23:24:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:24:11 INFO - 'LOGNAME': 'cltbld', 23:24:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:24:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:24:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:11 INFO - 'MOZ_NO_REMOTE': '1', 23:24:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:24:11 INFO - 'NO_EM_RESTART': '1', 23:24:11 INFO - 'PAGER': '/bin/cat', 23:24:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:24:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:11 INFO - 'PWD': '/builds/slave/test', 23:24:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:24:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:24:11 INFO - 'SHELL': '/bin/bash', 23:24:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ur5msYu6e9/Listeners', 23:24:11 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 23:24:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:24:11 INFO - 'USER': 'cltbld', 23:24:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:24:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:24:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:11 INFO - 'XPC_FLAGS': '0x0', 23:24:11 INFO - 'XPC_SERVICE_NAME': '0', 23:24:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:24:11 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/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:24:11 INFO - mozversion application_buildid: 20160829213322 23:24:11 INFO - mozversion application_changeset: 49253ea90409c02b5a6fb1d3687ab3de73d32bea 23:24:11 INFO - mozversion application_display_name: Nightly 23:24:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:24:11 INFO - mozversion application_name: Firefox 23:24:11 INFO - mozversion application_remotingname: firefox 23:24:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 23:24:11 INFO - mozversion application_vendor: Mozilla 23:24:11 INFO - mozversion application_version: 51.0a1 23:24:11 INFO - mozversion platform_buildid: 20160829213322 23:24:11 INFO - mozversion platform_changeset: 49253ea90409c02b5a6fb1d3687ab3de73d32bea 23:24:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 23:24:11 INFO - mozversion platform_version: 51.0a1 23:24:11 INFO - using testdate: 1472538251 23:24:11 INFO - actual date: 1472538251 23:24:11 INFO - starting webserver on 'localhost:49470' 23:24:11 INFO - SUITE-START | Running 2 tests 23:24:11 INFO - TEST-START | dromaeo_css 23:24:11 INFO - operating with platform_type : mac_ 23:24:11 INFO - Initialising browser for dromaeo_css test... 23:24:11 INFO - TEST-INFO | started process 1908 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp2YxRZ8/profile http://localhost:49470/getInfo.html) 23:24:13 INFO - PROCESS | 1908 | __metrics Screen width/height:1600/1200 23:24:13 INFO - PROCESS | 1908 | colorDepth:24 23:24:13 INFO - PROCESS | 1908 | Browser inner width/height: 1024/689 23:24:13 INFO - PROCESS | 1908 | __metrics 23:24:13 INFO - PROCESS | 1908 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:24:13 INFO - PROCESS | 1908 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:24:14 INFO - TEST-INFO | 1908: exit 0 23:24:14 INFO - Browser initialized. 23:24:14 INFO - Running cycle 1/1 for dromaeo_css test... 23:24:14 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp2YxRZ8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:24:26 INFO - PROCESS | 1910 | RSS: Main: 234831872 23:24:26 INFO - PROCESS | 1910 | 23:25:17 INFO - PROCESS | 1910 | 1472538317540 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] 23:27:29 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49470/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49470/tests/dromaeo/cssquery-ext.html) 23:27:31 INFO - PROCESS | 1910 | RSS: Main: 385843200 23:27:31 INFO - PROCESS | 1910 | 23:30:34 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49470/tests/dromaeo/cssquery-ext.html (next: http://localhost:49470/tests/dromaeo/cssquery-jquery.html) 23:30:34 INFO - PROCESS | 1910 | RSS: Main: 372092928 23:30:34 INFO - PROCESS | 1910 | 23:33:37 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49470/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49470/tests/dromaeo/cssquery-mootools.html) 23:33:38 INFO - PROCESS | 1910 | RSS: Main: 337096704 23:33:38 INFO - PROCESS | 1910 | 23:36:40 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49470/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49470/tests/dromaeo/cssquery-prototype.html) 23:36:40 INFO - PROCESS | 1910 | RSS: Main: 335933440 23:36:40 INFO - PROCESS | 1910 | 23:39:44 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49470/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49470/tests/dromaeo/cssquery-yui.html) 23:39:45 INFO - PROCESS | 1910 | RSS: Main: 329564160 23:39:45 INFO - PROCESS | 1910 | 23:42:48 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:49470/tests/dromaeo/cssquery-yui.html (next: http://localhost:49470/tests/dromaeo/cssquery-dojo.html) 23:42:48 INFO - PROCESS | 1910 | RSS: Main: 387076096 23:42:48 INFO - PROCESS | 1910 | 23:42:48 INFO - PROCESS | 1910 | __start_tp_report 23:42:48 INFO - PROCESS | 1910 | _x_x_mozilla_page_load 23:42:48 INFO - PROCESS | 1910 | _x_x_mozilla_page_load_details 23:42:48 INFO - PROCESS | 1910 | |i|pagename|runs| 23:42:48 INFO - PROCESS | 1910 | |0;dojo.html;3278.885239016634;3314.320564284322;3320.754716981132;3359.395308844407;3397.7451691123156;5553.777848886045;5593.720313984305;5618.550515958724;5630.352509461408;5674.3190817102;5640.971795141028;5718.399568045358;5732.2548068751;5791.536677065835;5799;5180.896382072357;5226.372835259775;5245.842624721246;5262.421133675302;5280.762365693534;5149.674086828189;5277.947220527803;5278.392984806739;5292.523672869441;5303.946960530384;5275.340582427197;5281.5774738021055;5324.76038578263;5337.306150200468;5338;4314.82740690372;5110.207917772714;5245.108331583602;5252.959957761129;5275.050490911634;5089.694618322913;5148.665336753136;5149.716765577892;5181.5077567631015;5188.92216616751;5568.638038527482;5596.888062238732;5599.972000140014;5670.319561652628;5700.971495142498;6227.875442491125;6228.0346546284945;6231.3145553989025;6234.750609975596;6239.032949892774;4782.545658162469;6129.509639228851;6145.262568491765;6156.6613836238985;6158.784442544489;5724.2558467399385;5788.18965344852;5828.737706803204;5829.766809327664;5856.853578660568;6251.6561589112125;6252.249730032334;6261.436470717566;6263.78076767311;6264.185655864709;5991.460768530853;6028.698565071729;6060.818175454743;6099.015434670893;6144.047672610753;6204.410580994799;6248.562600618004;6252;6253.937460625427;6275;6122.938770612326;6143.754249830046;6202.596831205957;6215.502759779207;6218;5991.011483105239;6073.726682299307;6074.210352654127;6230.906536401958;6251.406116419024;4899.240617704261;4907.386576677915;4921.114199444063;4923.359963204832;4939.90120197594;4391.560843915583;4422.09347083847;4483.14820184164;4485.730856148576;4498.347739577743;4284.764337961442;5717.285339332583;5720.799772007959;5735.827925162252;5776;6198.194234749454;6302.653354065479;6308.179936608211;6317.936820631827;6325.462335701517;4944.8763780905765;5536.280283563192;5597.216389705444;5668.971655141698;5680.091185410314;5295.285136506523;5359.678419294854;5369.919451208235;5407;5440.727963601883;6262.780802671793;6306.211723534559;6329.556931014877;6332.39842214998;6334.081558174131;6114.144019837137;6136.641621527207;6262.185915830913;6272.466840318624;6296.307406185408;6209.875802483834;6348.492120630431;6357.110004599267;6359.236891572856;6364.809055728336;6138.3861613840045;6158.568900176944;6193.690315484298;6194.504439644727;6197;6170.290416601969;6227.377262273918;6237.8752424950335;6252.062190671436;6252.687365631609;5915.526757859447;5984.401559843981;6096.993995990611;6097.5426842987845;6104.084387341934;5954.46409823118;6034.125051867542;6054.8183554493435;6122.602030867979;6128.080787881853;4985.900281994413;5760.769569217185;5901.616394934315;5912.497437717842;5919.496842768241;6033.3061697905305;6072.696365181811;6082.695865206811;6087.786927457518;6102.7253773579405;5636.549076073986;5694.345150307602;5822.446867547576;5876.853078673067;5877.35349111606 23:42:48 INFO - PROCESS | 1910 | |1;ext.html;490.93126962225296;524.2320001198246;525.7949399734104;532.7283085626332;552.2489414396417;30304.242393940163;30469.84765076174;31257.84371078147;39155.60844391559;40141.78865690745;41468.37797433041;41800.58199418005;41822.95442613929;41930.58069419305;41985.7900710496;9588.856167157499;9771.462569558671;9785.706428807112;9790.314677972545;9813.852792208123;8192.467489613156;9692.369995950276;9717.46553939533;9722.90277097227;9777.31558790885;8185.181481851797;9540.379875308083;9704.417734935927;9760.755981100494;9764.414135151914;9670;9690.46702431366;9746.463944483086;9747.610095596168;9753.609855605768;9629.759256018586;9718.222542196678;9723;9723.416595004323;9731.854022189673;39076.804615977024;39285;39292.21415571701;39309.60690393088;39337.803310983545;3373.190434295776;3379.104537297618;3408.3694516514524;3571.5535558055244;3726.6832319252894;26279.08023219178;29427.705722942927;29727.554086688717;30202.69797302043;30297.697023029927;3176.6664500227375;3218.50113232447;3221.597300337458;3237.093613788143;3314.850831712579;466091;466521;470056;470901;471043.2895671034;53950.460495394545;54244.72877635666;54266.45733542693;54320;55315.72342138264;33046.50430243548;33542.664573353955;33674.326513470085;34680.30639387198;56486;13670.52153174634;16077.196140192944;16245.756313655305;16257.430989915296;16310.918445407697;12377.133600648049;12482.43829027696;12492;12501.06242031833;12508.31204283755;18233.544161396072;18239;18248.178831952595;18255.817441825413;18304.542386440444;7426.5492387511995;7566.659500322498;7584.317411432998;7643.7324693636565;7650.502717323357;865.4028720183054;968.3995922528078;984.8276551603441;986.2750878104588;1055.36677993205;28182.43635127286;28782.85608571944;29165.56251656227;29766.40467190644;29966;999.5302207962246;1054.8575942247699;1064.1114669251108;1130.2596799096605;1188.2217147768226;14596.124232545868;14973.176475293963;15089.773653395208;15184.861135414889;15455.072695638297;670344.6482767555;675418;675446;679334;680351;54977;55541;55606.72196638991;55661;55902;1224.9877501225053;1264.7344057747976;1349.190485708567;1355.7695191817415;1361.8978576606596;28830.84676612912;28860.855695721388;30043.69956300409;30245.89221532894;30896.073117806503;44847.77576112099;45413.31880021736;45477.77261113673;45497;45581.54418455773;584288;594378;596643;597691;598357;34598.65401346055;41773.79113104415;43213.56786432221;44327.55672443234;44334.66995990126;45501;45618.54381456143;46233.76883115563;46284.53715462802;47037.76481117709;38015.809920951324;38040.619593803705;38333.041673958374;38895.41656875092;39340.2131957365;34455.483167753;37326.25347492981;37631.62368376281;38010.61989380071;38921;36869.743712384;37100.81449592735;37144.81427592953;37470.250594988494;38133;32106.03681889547;33490.49764253587;33670;33770.49344259887;33850.153746156546;22887.08451661915;23970.88014559916;24358.63462048106;24608.138715144883;24961.625575616745 23:42:48 INFO - PROCESS | 1910 | |2;jquery.html;319.173341046689;340.9846556904939;364.1061194766846;368.9907752306192;395.4286056648145;15153.54539363818;15179.16514591697;20250.89874550629;20442.08010639521;21109.366718998415;16273.51179464615;19014;19573.92343421115;20456.29505213802;20775.896120519414;5709.343425506068;5948.464638182562;5950.613210141327;5957.314908785502;5964;5496.532794712434;5807.535397168216;5865.120231965197;5868.119782032708;5876.764929402819;5542.279503664538;5791.391903850098;5807.535397168216;5808.8838223235725;5817.563682723791;5645.915311270334;5778.682172480512;5779.971100144515;5783.36382997872;5785.074388097884;4833.830815921461;5045;5051.823186188466;5054.241863720435;5064.822731204427;24384.878075609686;24404.145854894992;24499.632505512433;24745.13392031288;24751.25746227616;2214.2139540463063;2250.493638931244;2275.4425165834477;2394.162043284854;2995.996341225692;14675.853241467556;15164.317605707769;15466.226688665522;15569.455069072641;15842.28709708066;1792.09499202903;1838.9780154486116;1841.3095089341498;1847.7228415737613;1875.9437216883516;92313;115990;116855.4157229217;117300;132133.6786632131;10938.991777261317;10971.945140274407;11012.119030477703;11102.874229450656;11268.943655281671;13844.238566878917;13848.86151138496;13850.445982160802;13868.029237953448;13882.583522494342;8189.426740128134;8419.284360829399;8461.788455288544;8572.3999320047;8609.354298427643;7912.0505539335645;8328.375371847136;8353.665853365797;8485.745427637181;8501.319894408432;3607.639236076424;3733.383991641403;3754.6433088856515;4336.503906152436;4367.192069467159;1856.3595559531939;1892.8296453319267;1896.7818700849302;1896.8482521398255;1908.5133291010704;817.2603793566832;851.936104792143;867.1415298854089;879.2174964281794;944.6363150187227;17649.558761031076;18749.437516874517;21192.15231390758;21402.25092121768;21592.136314547242;888.3026823943173;907.8502047162144;937.1659223291276;950.3680052764834;958.9424634521812;9497.145256926771;10612.681619551426;10787.78424431507;11098.167637427065;11135.665930022113;500422;573497;574233;579349;584328;99918;105099.94900051055;125908.37045814712;133288;139058.30470847784;967.3857100740939;1005.9597616095274;1023.3706270643501;1025.6410256410197;1028.9125424338865;18277.54306142357;19261.614767704847;19437.51406214856;19548.21807127699;19875.602487950448;55040;55269.1709624364;55810.744603201325;56747;57198.7140064297;69001.30998689949;76795;78899.60550197212;83693.58153209195;83836.16163838284;7809.648565814438;7838.921610783819;8036.156203598772;8077;8138.796530086795;12621.28642341901;12631.557895473614;12720.173188742701;13083.476660933837;13084.803727943898;11050.226484146033;11056.060234880126;11190.720231994264;11288.887111128784;11297.152713546353;4445.555444455528;9724.173445256949;10752.462376881282;10835.695112990614;10849.837252441062;9038.265242612199;11204.383758893338;11355.489002995051;11404.885951140383;11547.364894930859;7685.154632990479;7700.961495192488;8106.635201416068;8124.67501299965;8173.509589424653;9720.708378748648;10636.41499717523;11151.330920144817;11158.88841111611;11226.382548959558 23:42:48 INFO - PROCESS | 1910 | |3;mootools.html;1446.869781719645;1492.1047371577056;1623.3912282893914;1801.3064969986551;1828.040278853601;8232.094469608346;8756.036835948049;8769.210771030606;8770.342224333168;8784.077671844452;7602.467827252094;7854.685812567491;7868.2918537331625;7971.0434747830195;8564.100769419214;2072.0572139676447;2274.044901141516;2360.103160798894;2365.4914193448403;2369.07606033647;2345.3433038749176;2347.6713260143592;2349.7767712067325;2352.8588284702973;2361.787439130478;1747.4582879307454;2356.846804957681;2362.29131260622;2364.125273648752;2369.0050178924807;2319.223060274818;2332.8950197241165;2358.2571489980774;2363.480034392448;2364.337985364101;2317.9884100579557;2326.6393708974965;2331.219041621059;2332.93001209964;2352.364861487406;8606.698765543177;8614.138586141398;8674.349423793177;8685;8705.608247628872;5034.295198672189;5186.611004174702;5193.610479214074;5196.844094677166;5197.662151960149;3398.6771256730817;3545.982270088659;3586.7668601541077;3592.838322275478;3595.946060809064;960.9135177834029;1034.6999370182639;1090.6782499162766;1109.112709832131;1111.3387534417009;11116.221864469491;11185.832212516818;11199.328040317525;11203.71990700239;11217.158713096469;4305.612494875476;4465.084657645175;4638.652101092432;4661.114388266218;4661.463931647904;9536.761580960532;9545.331826772199;9552.092551207625;9576.281778866613;9584.185344245678;5184.14461613837;5186.533212010937;5187.481251874782;5189.962007598496;5193.2989046479;4572.7713614319155;4686.882827929329;4733.621310295168;4763.54746299108;4766.4995175506765;3491.8428670709877;3618.8371523281517;3830.9808450958126;3890.105275786585;3897.70767192456;1264.5574049082747;1272.9363531823371;1275.3177050278057;1286.8777466140702;1290.7676618208625;885.172363839799;920.2086205862953;920.9769755756159;939.6899023322424;944.8346539355585;5723.8569035774435;5837.374669007245;5874.9118763218585;6056.3338032816355;6159.045347971071;827.1397746343855;886.082904194156;945.333539854401;951.5432592355708;966.3235734985539;3362.9831850840587;3944.3294639911283;4024.71209213049;4027.2146931348107;4101.5488296287385;9772.462514561625;10426.843597346045;10567.94716026415;10572;10594.364338139736;9056.283115065455;10166;10573.101286390589;10594.205434592437;10647.84028239577;7871.48835325702;8059.314958228616;8076.798080048045;8086.231807978233;8086.353091752646;4708.505606911362;5751.166080918159;5763.8559036024435;5766.192733017465;5906.911396329145;9087.636494540144;9362.251019918254;9543.950165481652;9755.951220243853;9833.360831545926;9757.51212439361;9868.753281168028;9935.354201976848;9942.950285248528;10000.399976001463;7201.171865235356;7294.708211671686;7326.157491888501;7357.632118393952;7367.742129025459;7261.782146535613;7542.962285188539;7711.11322197932;7899.486533375312;7978.002749656293;7140.6072666003865;7286.781396558112;7319.707211711685;7321.853562928818;7437.962810185914;6315.842103947438;7038.718451262097;7190.1731300901065;7211.567305961659;7348.265173482609;6836.829079273058;6894.931050689429;6896.241413444417;6900.723971041102;6904.206016308189;4767.523247675204;4900.14247506692;4932.482089380564;4941.579965702956;5152.355955505562;4929.088118698052;5069.467705890976;5086.313347698014;5135.204043373283;5253.632245742761 23:42:48 INFO - PROCESS | 1910 | |4;prototype.html;233.541091754702;294.6125844514463;303.77672410778086;304.9737632865408;306.1138005474154;4622.930656040162;5049.2678561608545;5192.143296356101;5262.210668399737;5268.762905669245;4725.621950243981;5154.175331946889;5177.223416487519;5203.791848326063;5224.712640804754;3791.9620803792022;3980.6417422431973;3983.948266642681;4023.436718859362;4076.8165432555534;3900.980495097535;3935.822887970048;3970.7419017763896;4005.699572532057;4007.238624661305;3456.8271586420706;3973.9205215895663;3979.3235150024566;4003.299422601048;4006.318925782624;3525.94711079334;3835.9424608630893;3982.1438390745984;4027.577104404025;4052.9189416211807;3355.4799006154085;3467.133216695826;3485.3203625293077;3497.2655742293896;3552.520409744677;3883.1457079442475;5015.699058056528;5068.3917929848285;5091.847244582668;5092.618053645954;3815.389537673959;4533.909321813545;4568.680192386534;4581.541845815425;4583.358329833826;5011.273365362039;5204.75085979366;5503.174523821419;5525.254090697745;5534.557235421156;3448.896533103986;3766.0396598867114;3891.1633998690418;3894.4547763313158;3910.276598829226;5504.146857237095;5504.614676972654;5729.1119876419225;5754.251947246831;5764.250647415851;3662.597114317423;3929.410588411704;3949.644531992077;3956.940645890314;4011.8997025074605;3372.9325413491592;4023.7325242548504;4032.334664780337;4048.716589838742;4048.8582899598373;4035.7174997750462;4577;4581.908361832745;4644.535546445329;4648.558386953234;4437.134758722018;4875.684050076256;5242.606804489678;5549.944500555023;5590.496855283044;1165.5104855960346;1353.966150846217;1373.203541945669;1374.587623712882;1375.4154484344115;741.4068745004083;797.1271457753867;801.935845132388;831.4886344897965;848.1942154952819;4245.8513952011535;4312.54718254585;4320.308750599889;4330.4193969201015;4337.544557821447;4234.428352172417;4662.953370466252;4708.88227794301;4727.716337019721;4786.611882554021;3646.4530320452145;4290.29210180323;4311.116221174714;4324.362139095149;4324.394584758136;2320.6772139880445;2700.3789128500557;2823.858807059639;2991.745701615387;3009.1042643135124;5199.922001169985;5844.12338149281;5845.707714614337;5860.033094539439;5885.941140588539;5542.307211598551;5800.709964501758;5807.593468457168;5830.329512106077;5878.735456904449;5573.714776941434;5848.64908105515;5884.911291411103;5886.440788125121;5890.469857712741;5664.762418844616;5679.9148012779015;5688.63023903445;5710.9428905712075;5729.914051289151;4606.723596584216;4878.146324393288;5474.206240095244;5482;5641.492265696107;5496.367917689357;5858.355580886185;5859.5019423349495;5880.79443714029;5949.583529152917;3180.775401470404;3368.612609549933;3462.8918746002046;3647.215848592565;3699.3341198584512;5401.217598192666;5408.3509978802795;5617.859553511195;5643.915341269966;5693.9430605693415;4806.7836947336755;5176.948230517647;5430.212619170277;5443.292371991616;5489.423610520837;4847.200211965127;5515.393306736336;5559.861003474945;5570.888582228414;5583.302087239095;4586.8165273388695;4608.262677971508;5465.180222966586;5466.835994920159;5476.495043172294;4881.585065269492;5044.537084245528;5523.392426833126;5573.7459071708545;5583.274174357308;5171.741412929414;5454.972725136349;5486.451354864482;5490.670559766427;5492.725363731877 23:42:48 INFO - PROCESS | 1910 | |5;yui.html;701.9543729657569;705.5202462325619;754.8565772503224;762.1996903251586;812.082346947949;15232.695346093085;15726.764098538531;15732.921335393321;15754.212289385514;15760.527184184493;13911.791323130135;13945.233012184326;13983.93008034961;13987.090839095452;13987.440502379917;670.8859493886026;692.9965350173242;820.3519219816338;834.8288600836844;865.4417900454204;823.4976664234812;838.1409055717908;838.4214891724721;839.3914412051275;841.1378337204353;901.1619194149439;901.8286525560122;902.9909700902973;903.2818908967387;906.1618003346911;718.3821913154683;881.6297155194834;896.3456676626067;896.6996056321108;907.1518130547893;670.1187937861745;670.3732010570084;670.8993650952348;670.9731610735565;671.633959492078;14552.781708274384;14574.271286435636;14595.927020364936;14602;14665.486707965276;13502.257375844325;13732.382042808098;13743.312834358341;13864.098833575785;13975.650608734863;11170.329780213213;12538.373081345897;12670.746585068247;12834.294113823737;12849.229046257255;4476.328550717386;4484.865454036384;4552.88617784558;4674.135284972291;4688.859334219979;105890.47054764676;109008.90991090066;112491.43754281258;117140;119093;10790.56837726482;10854.45727713611;11044.282121662067;11094.278871873303;11167.720806979729;21533.815640139943;21534.56930861374;21564.78435215628;21571.460713482287;21602.891985539973;7716.228377162239;7932;8018.198180181935;8081.191880811989;8119.512829230265;4330.2638551446325;4444.3333499974615;4447.688661793708;4449.933251001237;4519.163954668397;3455.51622772812;3856.1902000580194;4079.4288799568085;4079.8776036718946;4087.2438598859308;712.8895021271711;716.8351279205708;724.2721065329415;729.806601250669;739.2607392607392;572.7995201679379;654.7773756922669;668.6872795638604;674.8414122681166;680.2279412866334;9103.954480227689;9447.716568502956;9456.810863782823;9571.33000689945;9722.215331491045;561.8511094559944;638.6615094000188;642.325558163926;659.3044338223125;701.3022043067181;4305.784710764449;4435.445569303837;4754.976225118852;4758.214894542361;4848.854534363974;152909;159242;159638;160903;161537.19231403767;130590;132436;133560.9795118444;133586;141540;110819;116955;117926.99999999828;120770.3961480187;124128.37935810444;8812.779680508038;9002.504862232377;9033.59348829291;9199.586018629312;9202.53987300619;53942;65466.34533654793;65844.67077664581;67663;68333.50675605361;93205.60191596996;97606;99359;101871;102210;10210.642627508001;11908.04735621131;12042.518299267931;12264.877351226374;12503.124781265224;11454.942725286654;13049.217046977212;13069.542566010145;13167.539136130572;13215.339233038118;12236.388180590757;12239.510419583117;12258.080643951562;12355.135140540435;12362.44369003415;12113.357094788336;12247.75504489923;12337.568185113838;12516.499340026297;12546.811797822857;11992.520299187934;12003.099767517297;12040.578579749666;12201.145919785884;12259.693507662023;9835.655752048644;10046.799064018825;10247.692569222936;10409.68770936873;10452.477376131315;10235.232357573062;10456.215783816397;10519.842202367123;10540.894591054299;10546.630867919586 23:42:48 INFO - PROCESS | 1910 | __end_tp_report 23:42:48 INFO - PROCESS | 1910 | __start_cc_report 23:42:48 INFO - PROCESS | 1910 | _x_x_mozilla_cycle_collect,1374 23:42:48 INFO - PROCESS | 1910 | __end_cc_report 23:42:48 INFO - PROCESS | 1910 | __startTimestamp1472539368473__endTimestamp 23:42:48 INFO - PROCESS | 1910 | 23:42:48 INFO - PROCESS | 1910 | ------- Summary: start ------- 23:42:48 INFO - PROCESS | 1910 | Number of tests: 6 23:42:48 INFO - PROCESS | 1910 | 23:42:48 INFO - PROCESS | 1910 | [#0] dojo.html Cycles:165 Average:5696.59 Median:5877.35 stddev:655.37 (11.2%) stddev-sans-first:629.34 23:42:48 INFO - PROCESS | 1910 | Values: 3278.9 3314.3 3320.8 3359.4 3397.7 5553.8 5593.7 5618.6 5630.4 5674.3 5641.0 5718.4 5732.3 5791.5 5799.0 5180.9 5226.4 5245.8 5262.4 5280.8 5149.7 5277.9 5278.4 5292.5 5303.9 5275.3 5281.6 5324.8 5337.3 5338.0 4314.8 5110.2 5245.1 5253.0 5275.1 5089.7 5148.7 5149.7 5181.5 5188.9 5568.6 5596.9 5600.0 5670.3 5701.0 6227.9 6228.0 6231.3 6234.8 6239.0 4782.5 6129.5 6145.3 6156.7 6158.8 5724.3 5788.2 5828.7 5829.8 5856.9 6251.7 6252.2 6261.4 6263.8 6264.2 5991.5 6028.7 6060.8 6099.0 6144.0 6204.4 6248.6 6252.0 6253.9 6275.0 6122.9 6143.8 6202.6 6215.5 6218.0 5991.0 6073.7 6074.2 6230.9 6251.4 4899.2 4907.4 4921.1 4923.4 4939.9 4391.6 4422.1 4483.1 4485.7 4498.3 4284.8 5717.3 5720.8 5735.8 5776.0 6198.2 6302.7 6308.2 6317.9 6325.5 4944.9 5536.3 5597.2 5669.0 5680.1 5295.3 5359.7 5369.9 5407.0 5440.7 6262.8 6306.2 6329.6 6332.4 6334.1 6114.1 6136.6 6262.2 6272.5 6296.3 6209.9 6348.5 6357.1 6359.2 6364.8 6138.4 6158.6 6193.7 6194.5 6197.0 6170.3 6227.4 6237.9 6252.1 6252.7 5915.5 5984.4 6097.0 6097.5 6104.1 5954.5 6034.1 6054.8 6122.6 6128.1 4985.9 5760.8 5901.6 5912.5 5919.5 6033.3 6072.7 6082.7 6087.8 6102.7 5636.5 5694.3 5822.4 5876.9 5877.4 23:42:48 INFO - PROCESS | 1910 | 23:42:48 INFO - PROCESS | 1910 | [#1] ext.html Cycles:180 Average:70071.78 Median:39300.91 stddev:156928.42 (399.3%) stddev-sans-first:157281.69 23:42:48 INFO - PROCESS | 1910 | Values: 490.9 524.2 525.8 532.7 552.2 30304.2 30469.8 31257.8 39155.6 40141.8 41468.4 41800.6 41823.0 41930.6 41985.8 9588.9 9771.5 9785.7 9790.3 9813.9 8192.5 9692.4 9717.5 9722.9 9777.3 8185.2 9540.4 9704.4 9760.8 9764.4 9670.0 9690.5 9746.5 9747.6 9753.6 9629.8 9718.2 9723.0 9723.4 9731.9 39076.8 39285.0 39292.2 39309.6 39337.8 3373.2 3379.1 3408.4 3571.6 3726.7 26279.1 29427.7 29727.6 30202.7 30297.7 3176.7 3218.5 3221.6 3237.1 3314.9 466091.0 466521.0 470056.0 470901.0 471043.3 53950.5 54244.7 54266.5 54320.0 55315.7 33046.5 33542.7 33674.3 34680.3 56486.0 13670.5 16077.2 16245.8 16257.4 16310.9 12377.1 12482.4 12492.0 12501.1 12508.3 18233.5 18239.0 18248.2 18255.8 18304.5 7426.5 7566.7 7584.3 7643.7 7650.5 865.4 968.4 984.8 986.3 1055.4 28182.4 28782.9 29165.6 29766.4 29966.0 999.5 1054.9 1064.1 1130.3 1188.2 14596.1 14973.2 15089.8 15184.9 15455.1 670344.6 675418.0 675446.0 679334.0 680351.0 54977.0 55541.0 55606.7 55661.0 55902.0 1225.0 1264.7 1349.2 1355.8 1361.9 28830.8 28860.9 30043.7 30245.9 30896.1 44847.8 45413.3 45477.8 45497.0 45581.5 584288.0 594378.0 596643.0 597691.0 598357.0 34598.7 41773.8 43213.6 44327.6 44334.7 45501.0 45618.5 46233.8 46284.5 47037.8 38015.8 38040.6 38333.0 38895.4 39340.2 34455.5 37326.3 37631.6 38010.6 38921.0 36869.7 37100.8 37144.8 37470.3 38133.0 32106.0 33490.5 33670.0 33770.5 33850.2 22887.1 23970.9 24358.6 24608.1 24961.6 23:42:48 INFO - PROCESS | 1910 | 23:42:48 INFO - PROCESS | 1910 | [#2] jquery.html Cycles:180 Average:33724.88 Median:13873.63 stddev:94190.93 (678.9%) stddev-sans-first:94421.77 23:42:48 INFO - PROCESS | 1910 | Values: 319.2 341.0 364.1 369.0 395.4 15153.5 15179.2 20250.9 20442.1 21109.4 16273.5 19014.0 19573.9 20456.3 20775.9 5709.3 5948.5 5950.6 5957.3 5964.0 5496.5 5807.5 5865.1 5868.1 5876.8 5542.3 5791.4 5807.5 5808.9 5817.6 5645.9 5778.7 5780.0 5783.4 5785.1 4833.8 5045.0 5051.8 5054.2 5064.8 24384.9 24404.1 24499.6 24745.1 24751.3 2214.2 2250.5 2275.4 2394.2 2996.0 14675.9 15164.3 15466.2 15569.5 15842.3 1792.1 1839.0 1841.3 1847.7 1875.9 92313.0 115990.0 116855.4 117300.0 132133.7 10939.0 10971.9 11012.1 11102.9 11268.9 13844.2 13848.9 13850.4 13868.0 13882.6 8189.4 8419.3 8461.8 8572.4 8609.4 7912.1 8328.4 8353.7 8485.7 8501.3 3607.6 3733.4 3754.6 4336.5 4367.2 1856.4 1892.8 1896.8 1896.8 1908.5 817.3 851.9 867.1 879.2 944.6 17649.6 18749.4 21192.2 21402.3 21592.1 888.3 907.9 937.2 950.4 958.9 9497.1 10612.7 10787.8 11098.2 11135.7 500422.0 573497.0 574233.0 579349.0 584328.0 99918.0 105099.9 125908.4 133288.0 139058.3 967.4 1006.0 1023.4 1025.6 1028.9 18277.5 19261.6 19437.5 19548.2 19875.6 55040.0 55269.2 55810.7 56747.0 57198.7 69001.3 76795.0 78899.6 83693.6 83836.2 7809.6 7838.9 8036.2 8077.0 8138.8 12621.3 12631.6 12720.2 13083.5 13084.8 11050.2 11056.1 11190.7 11288.9 11297.2 4445.6 9724.2 10752.5 10835.7 10849.8 9038.3 11204.4 11355.5 11404.9 11547.4 7685.2 7701.0 8106.6 8124.7 8173.5 9720.7 10636.4 11151.3 11158.9 11226.4 23:42:48 INFO - PROCESS | 1910 | 23:42:48 INFO - PROCESS | 1910 | [#3] mootools.html Cycles:180 Average:5555.03 Median:5186.57 stddev:3067.14 (59.1%) stddev-sans-first:3060.20 23:42:48 INFO - PROCESS | 1910 | Values: 1446.9 1492.1 1623.4 1801.3 1828.0 8232.1 8756.0 8769.2 8770.3 8784.1 7602.5 7854.7 7868.3 7971.0 8564.1 2072.1 2274.0 2360.1 2365.5 2369.1 2345.3 2347.7 2349.8 2352.9 2361.8 1747.5 2356.8 2362.3 2364.1 2369.0 2319.2 2332.9 2358.3 2363.5 2364.3 2318.0 2326.6 2331.2 2332.9 2352.4 8606.7 8614.1 8674.3 8685.0 8705.6 5034.3 5186.6 5193.6 5196.8 5197.7 3398.7 3546.0 3586.8 3592.8 3595.9 960.9 1034.7 1090.7 1109.1 1111.3 11116.2 11185.8 11199.3 11203.7 11217.2 4305.6 4465.1 4638.7 4661.1 4661.5 9536.8 9545.3 9552.1 9576.3 9584.2 5184.1 5186.5 5187.5 5190.0 5193.3 4572.8 4686.9 4733.6 4763.5 4766.5 3491.8 3618.8 3831.0 3890.1 3897.7 1264.6 1272.9 1275.3 1286.9 1290.8 885.2 920.2 921.0 939.7 944.8 5723.9 5837.4 5874.9 6056.3 6159.0 827.1 886.1 945.3 951.5 966.3 3363.0 3944.3 4024.7 4027.2 4101.5 9772.5 10426.8 10567.9 10572.0 10594.4 9056.3 10166.0 10573.1 10594.2 10647.8 7871.5 8059.3 8076.8 8086.2 8086.4 4708.5 5751.2 5763.9 5766.2 5906.9 9087.6 9362.3 9544.0 9756.0 9833.4 9757.5 9868.8 9935.4 9943.0 10000.4 7201.2 7294.7 7326.2 7357.6 7367.7 7261.8 7543.0 7711.1 7899.5 7978.0 7140.6 7286.8 7319.7 7321.9 7438.0 6315.8 7038.7 7190.2 7211.6 7348.3 6836.8 6894.9 6896.2 6900.7 6904.2 4767.5 4900.1 4932.5 4941.6 5152.4 4929.1 5069.5 5086.3 5135.2 5253.6 23:42:48 INFO - PROCESS | 1910 | 23:42:48 INFO - PROCESS | 1910 | [#4] prototype.html Cycles:180 Average:4409.51 Median:4757.16 stddev:1368.91 (28.8%) stddev-sans-first:1336.38 23:42:48 INFO - PROCESS | 1910 | Values: 233.5 294.6 303.8 305.0 306.1 4622.9 5049.3 5192.1 5262.2 5268.8 4725.6 5154.2 5177.2 5203.8 5224.7 3792.0 3980.6 3983.9 4023.4 4076.8 3901.0 3935.8 3970.7 4005.7 4007.2 3456.8 3973.9 3979.3 4003.3 4006.3 3525.9 3835.9 3982.1 4027.6 4052.9 3355.5 3467.1 3485.3 3497.3 3552.5 3883.1 5015.7 5068.4 5091.8 5092.6 3815.4 4533.9 4568.7 4581.5 4583.4 5011.3 5204.8 5503.2 5525.3 5534.6 3448.9 3766.0 3891.2 3894.5 3910.3 5504.1 5504.6 5729.1 5754.3 5764.3 3662.6 3929.4 3949.6 3956.9 4011.9 3372.9 4023.7 4032.3 4048.7 4048.9 4035.7 4577.0 4581.9 4644.5 4648.6 4437.1 4875.7 5242.6 5549.9 5590.5 1165.5 1354.0 1373.2 1374.6 1375.4 741.4 797.1 801.9 831.5 848.2 4245.9 4312.5 4320.3 4330.4 4337.5 4234.4 4663.0 4708.9 4727.7 4786.6 3646.5 4290.3 4311.1 4324.4 4324.4 2320.7 2700.4 2823.9 2991.7 3009.1 5199.9 5844.1 5845.7 5860.0 5885.9 5542.3 5800.7 5807.6 5830.3 5878.7 5573.7 5848.6 5884.9 5886.4 5890.5 5664.8 5679.9 5688.6 5710.9 5729.9 4606.7 4878.1 5474.2 5482.0 5641.5 5496.4 5858.4 5859.5 5880.8 5949.6 3180.8 3368.6 3462.9 3647.2 3699.3 5401.2 5408.4 5617.9 5643.9 5693.9 4806.8 5176.9 5430.2 5443.3 5489.4 4847.2 5515.4 5559.9 5570.9 5583.3 4586.8 4608.3 5465.2 5466.8 5476.5 4881.6 5044.5 5523.4 5573.7 5583.3 5171.7 5455.0 5486.5 5490.7 5492.7 23:42:48 INFO - PROCESS | 1910 | 23:42:48 INFO - PROCESS | 1910 | [#5] yui.html Cycles:180 Average:25635.59 Median:12529.20 stddev:42000.64 (335.2%) stddev-sans-first:42076.74 23:42:48 INFO - PROCESS | 1910 | Values: 702.0 705.5 754.9 762.2 812.1 15232.7 15726.8 15732.9 15754.2 15760.5 13911.8 13945.2 13983.9 13987.1 13987.4 670.9 693.0 820.4 834.8 865.4 823.5 838.1 838.4 839.4 841.1 901.2 901.8 903.0 903.3 906.2 718.4 881.6 896.3 896.7 907.2 670.1 670.4 670.9 671.0 671.6 14552.8 14574.3 14595.9 14602.0 14665.5 13502.3 13732.4 13743.3 13864.1 13975.7 11170.3 12538.4 12670.7 12834.3 12849.2 4476.3 4484.9 4552.9 4674.1 4688.9 105890.5 109008.9 112491.4 117140.0 119093.0 10790.6 10854.5 11044.3 11094.3 11167.7 21533.8 21534.6 21564.8 21571.5 21602.9 7716.2 7932.0 8018.2 8081.2 8119.5 4330.3 4444.3 4447.7 4449.9 4519.2 3455.5 3856.2 4079.4 4079.9 4087.2 712.9 716.8 724.3 729.8 739.3 572.8 654.8 668.7 674.8 680.2 9104.0 9447.7 9456.8 9571.3 9722.2 561.9 638.7 642.3 659.3 701.3 4305.8 4435.4 4755.0 4758.2 4848.9 152909.0 159242.0 159638.0 160903.0 161537.2 130590.0 132436.0 133561.0 133586.0 141540.0 110819.0 116955.0 117927.0 120770.4 124128.4 8812.8 9002.5 9033.6 9199.6 9202.5 53942.0 65466.3 65844.7 67663.0 68333.5 93205.6 97606.0 99359.0 101871.0 102210.0 10210.6 11908.0 12042.5 12264.9 12503.1 11454.9 13049.2 13069.5 13167.5 13215.3 12236.4 12239.5 12258.1 12355.1 12362.4 12113.4 12247.8 12337.6 12516.5 12546.8 11992.5 12003.1 12040.6 12201.1 12259.7 9835.7 10046.8 10247.7 10409.7 10452.5 10235.2 10456.2 10519.8 10540.9 10546.6 23:42:48 INFO - PROCESS | 1910 | -------- Summary: end -------- 23:42:48 INFO - PROCESS | 1910 | 23:42:49 INFO - TEST-INFO | 1910: exit 0 23:42:49 INFO - TEST-OK | dromaeo_css | took 1117677ms 23:42:49 INFO - TEST-START | kraken 23:42:49 INFO - operating with platform_type : mac_ 23:42:49 INFO - Initialising browser for kraken test... 23:42:49 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp8jxvW3/profile http://localhost:49470/getInfo.html) 23:42:51 INFO - PROCESS | 1917 | __metrics Screen width/height:1600/1200 23:42:51 INFO - PROCESS | 1917 | colorDepth:24 23:42:51 INFO - PROCESS | 1917 | Browser inner width/height: 1024/689 23:42:51 INFO - PROCESS | 1917 | __metrics 23:42:51 INFO - PROCESS | 1917 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:42:51 INFO - PROCESS | 1917 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:42:52 INFO - TEST-INFO | 1917: exit 0 23:42:52 INFO - Browser initialized. 23:42:52 INFO - Running cycle 1/1 for kraken test... 23:42:52 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp8jxvW3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:43:04 INFO - PROCESS | 1918 | RSS: Main: 234913792 23:43:04 INFO - PROCESS | 1918 | 23:43:06 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49470/tests/kraken/driver.html?testName=audio-beat-detection) 23:43:07 INFO - PROCESS | 1918 | RSS: Main: 292233216 23:43:07 INFO - PROCESS | 1918 | 23:43:10 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49470/tests/kraken/driver.html?testName=audio-dft) 23:43:10 INFO - PROCESS | 1918 | RSS: Main: 288251904 23:43:10 INFO - PROCESS | 1918 | 23:43:14 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49470/tests/kraken/driver.html?testName=audio-fft) 23:43:14 INFO - PROCESS | 1918 | RSS: Main: 375111680 23:43:14 INFO - PROCESS | 1918 | 23:43:16 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49470/tests/kraken/driver.html?testName=audio-oscillator) 23:43:17 INFO - PROCESS | 1918 | RSS: Main: 278970368 23:43:17 INFO - PROCESS | 1918 | 23:43:19 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49470/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:43:20 INFO - PROCESS | 1918 | RSS: Main: 282550272 23:43:20 INFO - PROCESS | 1918 | 23:43:23 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49470/tests/kraken/driver.html?testName=imaging-darkroom) 23:43:23 INFO - PROCESS | 1918 | RSS: Main: 321138688 23:43:23 INFO - PROCESS | 1918 | 23:43:26 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49470/tests/kraken/driver.html?testName=imaging-desaturate) 23:43:27 INFO - PROCESS | 1918 | RSS: Main: 322519040 23:43:27 INFO - PROCESS | 1918 | 23:43:29 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49470/tests/kraken/driver.html?testName=json-parse-financial) 23:43:30 INFO - PROCESS | 1918 | RSS: Main: 321765376 23:43:30 INFO - PROCESS | 1918 | 23:43:32 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49470/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:43:32 INFO - PROCESS | 1918 | RSS: Main: 298156032 23:43:32 INFO - PROCESS | 1918 | 23:43:34 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49470/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:43:35 INFO - PROCESS | 1918 | RSS: Main: 304263168 23:43:35 INFO - PROCESS | 1918 | 23:43:37 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49470/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:43:38 INFO - PROCESS | 1918 | RSS: Main: 301240320 23:43:38 INFO - PROCESS | 1918 | 23:43:41 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49470/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:43:41 INFO - PROCESS | 1918 | RSS: Main: 321421312 23:43:41 INFO - PROCESS | 1918 | 23:43:44 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49470/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:43:45 INFO - PROCESS | 1918 | RSS: Main: 317399040 23:43:45 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49470/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49470/tests/kraken/driver.html?testName=ai-astar) 23:43:47 INFO - PROCESS | 1918 | RSS: Main: 360468480 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | __start_tp_report 23:43:47 INFO - PROCESS | 1918 | _x_x_mozilla_page_load 23:43:47 INFO - PROCESS | 1918 | _x_x_mozilla_page_load_details 23:43:47 INFO - PROCESS | 1918 | |i|pagename|runs| 23:43:47 INFO - PROCESS | 1918 | |0;ai-astar;114;113;133;114;113;114;113;114;113;113 23:43:47 INFO - PROCESS | 1918 | |1;audio-beat-detection;148;110;134;163;110;132;128;132;161;107 23:43:47 INFO - PROCESS | 1918 | |2;audio-dft;187;187;186;183;186;185;183;183;184;185 23:43:47 INFO - PROCESS | 1918 | |3;audio-fft;78;75;78;152;59;57;60;69;137;58 23:43:47 INFO - PROCESS | 1918 | |4;audio-oscillator;83;82;135;72;72;79;119;69;75;124 23:43:47 INFO - PROCESS | 1918 | |5;imaging-gaussian-blur;88;85;87;84;87;87;88;85;92;85 23:43:47 INFO - PROCESS | 1918 | |6;imaging-darkroom;97;97;97;101;99;95;163;98;97;99 23:43:47 INFO - PROCESS | 1918 | |7;imaging-desaturate;86;87;86;85;85;85;86;87;87;86 23:43:47 INFO - PROCESS | 1918 | |8;json-parse-financial;56;54;55;53;54;55;54;54;54;54 23:43:47 INFO - PROCESS | 1918 | |9;json-stringify-tinderbox;67;69;106;122;90;73;70;70;71;70 23:43:47 INFO - PROCESS | 1918 | |10;stanford-crypto-aes;98;105;105;76;80;82;84;76;101;107 23:43:47 INFO - PROCESS | 1918 | |11;stanford-crypto-ccm;139;140;140;137;137;139;138;128;199;150 23:43:47 INFO - PROCESS | 1918 | |12;stanford-crypto-pbkdf2;168;174;169;169;170;168;169;169;168;168 23:43:47 INFO - PROCESS | 1918 | |13;stanford-crypto-sha256-iterative;66;68;67;67;66;69;68;67;65;67 23:43:47 INFO - PROCESS | 1918 | __end_tp_report 23:43:47 INFO - PROCESS | 1918 | __start_cc_report 23:43:47 INFO - PROCESS | 1918 | _x_x_mozilla_cycle_collect,2027 23:43:47 INFO - PROCESS | 1918 | __end_cc_report 23:43:47 INFO - PROCESS | 1918 | __startTimestamp1472539427313__endTimestamp 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | ------- Summary: start ------- 23:43:47 INFO - PROCESS | 1918 | Number of tests: 14 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#0] ai-astar Cycles:10 Average:115.40 Median:114.00 stddev:6.20 (5.4%) stddev-sans-first:6.56 23:43:47 INFO - PROCESS | 1918 | Values: 114.0 113.0 133.0 114.0 113.0 114.0 113.0 114.0 113.0 113.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:133.00 stddev:20.19 (15.2%) stddev-sans-first:20.62 23:43:47 INFO - PROCESS | 1918 | Values: 148.0 110.0 134.0 163.0 110.0 132.0 128.0 132.0 161.0 107.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.50 stddev:1.60 (0.9%) stddev-sans-first:1.50 23:43:47 INFO - PROCESS | 1918 | Values: 187.0 187.0 186.0 183.0 186.0 185.0 183.0 183.0 184.0 185.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#3] audio-fft Cycles:10 Average:82.30 Median:64.50 stddev:33.97 (52.7%) stddev-sans-first:36.00 23:43:47 INFO - PROCESS | 1918 | Values: 78.0 75.0 78.0 152.0 59.0 57.0 60.0 69.0 137.0 58.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:73.50 stddev:24.86 (33.8%) stddev-sans-first:26.19 23:43:47 INFO - PROCESS | 1918 | Values: 83.0 82.0 135.0 72.0 72.0 79.0 119.0 69.0 75.0 124.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.00 stddev:2.30 (2.6%) stddev-sans-first:2.40 23:43:47 INFO - PROCESS | 1918 | Values: 88.0 85.0 87.0 84.0 87.0 87.0 88.0 85.0 92.0 85.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#6] imaging-darkroom Cycles:10 Average:104.30 Median:97.00 stddev:20.69 (21.3%) stddev-sans-first:21.77 23:43:47 INFO - PROCESS | 1918 | Values: 97.0 97.0 97.0 101.0 99.0 95.0 163.0 98.0 97.0 99.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 23:43:47 INFO - PROCESS | 1918 | Values: 86.0 87.0 86.0 85.0 85.0 85.0 86.0 87.0 87.0 86.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.60 23:43:47 INFO - PROCESS | 1918 | Values: 56.0 54.0 55.0 53.0 54.0 55.0 54.0 54.0 54.0 54.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#9] json-stringify-tinderbox Cycles:10 Average:80.80 Median:70.00 stddev:19.01 (27.2%) stddev-sans-first:19.50 23:43:47 INFO - PROCESS | 1918 | Values: 67.0 69.0 106.0 122.0 90.0 73.0 70.0 70.0 71.0 70.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#10] stanford-crypto-aes Cycles:10 Average:91.40 Median:78.00 stddev:12.89 (16.5%) stddev-sans-first:13.45 23:43:47 INFO - PROCESS | 1918 | Values: 98.0 105.0 105.0 76.0 80.0 82.0 84.0 76.0 101.0 107.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:139.50 stddev:19.80 (14.2%) stddev-sans-first:20.89 23:43:47 INFO - PROCESS | 1918 | Values: 139.0 140.0 140.0 137.0 137.0 139.0 138.0 128.0 199.0 150.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.20 Median:169.00 stddev:1.81 (1.1%) stddev-sans-first:1.87 23:43:47 INFO - PROCESS | 1918 | Values: 168.0 174.0 169.0 169.0 170.0 168.0 169.0 169.0 168.0 168.0 23:43:47 INFO - PROCESS | 1918 | 23:43:47 INFO - PROCESS | 1918 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.15 (1.7%) stddev-sans-first:1.17 23:43:47 INFO - PROCESS | 1918 | Values: 66.0 68.0 67.0 67.0 66.0 69.0 68.0 67.0 65.0 67.0 23:43:47 INFO - PROCESS | 1918 | -------- Summary: end -------- 23:43:47 INFO - PROCESS | 1918 | 23:43:48 INFO - TEST-INFO | 1918: exit 0 23:43:48 INFO - TEST-OK | kraken | took 59062ms 23:43:48 INFO - SUITE-END | took 1176s 23:43:48 INFO - Completed test suite (00:19:37) 23:43:48 INFO - javascript benchmark 23:43:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3278.885239016634, 3314.320564284322, 3320.754716981132, 3359.395308844407, 3397.7451691123156, 5553.777848886045, 5593.720313984305, 5618.550515958724, 5630.352509461408, 5674.3190817102, 5640.971795141028, 5718.399568045358, 5732.2548068751, 5791.536677065835, 5799.0, 5180.896382072357, 5226.372835259775, 5245.842624721246, 5262.421133675302, 5280.762365693534, 5149.674086828189, 5277.947220527803, 5278.392984806739, 5292.523672869441, 5303.946960530384, 5275.340582427197, 5281.5774738021055, 5324.76038578263, 5337.306150200468, 5338.0, 4314.82740690372, 5110.207917772714, 5245.108331583602, 5252.959957761129, 5275.050490911634, 5089.694618322913, 5148.665336753136, 5149.716765577892, 5181.5077567631015, 5188.92216616751, 5568.638038527482, 5596.888062238732, 5599.972000140014, 5670.319561652628, 5700.971495142498, 6227.875442491125, 6228.0346546284945, 6231.3145553989025, 6234.750609975596, 6239.032949892774, 4782.545658162469, 6129.509639228851, 6145.262568491765, 6156.6613836238985, 6158.784442544489, 5724.2558467399385, 5788.18965344852, 5828.737706803204, 5829.766809327664, 5856.853578660568, 6251.6561589112125, 6252.249730032334, 6261.436470717566, 6263.78076767311, 6264.185655864709, 5991.460768530853, 6028.698565071729, 6060.818175454743, 6099.015434670893, 6144.047672610753, 6204.410580994799, 6248.562600618004, 6252.0, 6253.937460625427, 6275.0, 6122.938770612326, 6143.754249830046, 6202.596831205957, 6215.502759779207, 6218.0, 5991.011483105239, 6073.726682299307, 6074.210352654127, 6230.906536401958, 6251.406116419024, 4899.240617704261, 4907.386576677915, 4921.114199444063, 4923.359963204832, 4939.90120197594, 4391.560843915583, 4422.09347083847, 4483.14820184164, 4485.730856148576, 4498.347739577743, 4284.764337961442, 5717.285339332583, 5720.799772007959, 5735.827925162252, 5776.0, 6198.194234749454, 6302.653354065479, 6308.179936608211, 6317.936820631827, 6325.462335701517, 4944.8763780905765, 5536.280283563192, 5597.216389705444, 5668.971655141698, 5680.091185410314, 5295.285136506523, 5359.678419294854, 5369.919451208235, 5407.0, 5440.727963601883, 6262.780802671793, 6306.211723534559, 6329.556931014877, 6332.39842214998, 6334.081558174131, 6114.144019837137, 6136.641621527207, 6262.185915830913, 6272.466840318624, 6296.307406185408, 6209.875802483834, 6348.492120630431, 6357.110004599267, 6359.236891572856, 6364.809055728336, 6138.3861613840045, 6158.568900176944, 6193.690315484298, 6194.504439644727, 6197.0, 6170.290416601969, 6227.377262273918, 6237.8752424950335, 6252.062190671436, 6252.687365631609, 5915.526757859447, 5984.401559843981, 6096.993995990611, 6097.5426842987845, 6104.084387341934, 5954.46409823118, 6034.125051867542, 6054.8183554493435, 6122.602030867979, 6128.080787881853, 4985.900281994413, 5760.769569217185, 5901.616394934315, 5912.497437717842, 5919.496842768241, 6033.3061697905305, 6072.696365181811, 6082.695865206811, 6087.786927457518, 6102.7253773579405, 5636.549076073986, 5694.345150307602, 5822.446867547576, 5876.853078673067, 5877.35349111606], "value": 5655.48508814475, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.93126962225296, 524.2320001198246, 525.7949399734104, 532.7283085626332, 552.2489414396417, 30304.242393940163, 30469.84765076174, 31257.84371078147, 39155.60844391559, 40141.78865690745, 41468.37797433041, 41800.58199418005, 41822.95442613929, 41930.58069419305, 41985.7900710496, 9588.856167157499, 9771.462569558671, 9785.706428807112, 9790.314677972545, 9813.852792208123, 8192.467489613156, 9692.369995950276, 9717.46553939533, 9722.90277097227, 9777.31558790885, 8185.181481851797, 9540.379875308083, 9704.417734935927, 9760.755981100494, 9764.414135151914, 9670.0, 9690.46702431366, 9746.463944483086, 9747.610095596168, 9753.609855605768, 9629.759256018586, 9718.222542196678, 9723.0, 9723.416595004323, 9731.854022189673, 39076.804615977024, 39285.0, 39292.21415571701, 39309.60690393088, 39337.803310983545, 3373.190434295776, 3379.104537297618, 3408.3694516514524, 3571.5535558055244, 3726.6832319252894, 26279.08023219178, 29427.705722942927, 29727.554086688717, 30202.69797302043, 30297.697023029927, 3176.6664500227375, 3218.50113232447, 3221.597300337458, 3237.093613788143, 3314.850831712579, 466091.0, 466521.0, 470056.0, 470901.0, 471043.2895671034, 53950.460495394545, 54244.72877635666, 54266.45733542693, 54320.0, 55315.72342138264, 33046.50430243548, 33542.664573353955, 33674.326513470085, 34680.30639387198, 56486.0, 13670.52153174634, 16077.196140192944, 16245.756313655305, 16257.430989915296, 16310.918445407697, 12377.133600648049, 12482.43829027696, 12492.0, 12501.06242031833, 12508.31204283755, 18233.544161396072, 18239.0, 18248.178831952595, 18255.817441825413, 18304.542386440444, 7426.5492387511995, 7566.659500322498, 7584.317411432998, 7643.7324693636565, 7650.502717323357, 865.4028720183054, 968.3995922528078, 984.8276551603441, 986.2750878104588, 1055.36677993205, 28182.43635127286, 28782.85608571944, 29165.56251656227, 29766.40467190644, 29966.0, 999.5302207962246, 1054.8575942247699, 1064.1114669251108, 1130.2596799096605, 1188.2217147768226, 14596.124232545868, 14973.176475293963, 15089.773653395208, 15184.861135414889, 15455.072695638297, 670344.6482767555, 675418.0, 675446.0, 679334.0, 680351.0, 54977.0, 55541.0, 55606.72196638991, 55661.0, 55902.0, 1224.9877501225053, 1264.7344057747976, 1349.190485708567, 1355.7695191817415, 1361.8978576606596, 28830.84676612912, 28860.855695721388, 30043.69956300409, 30245.89221532894, 30896.073117806503, 44847.77576112099, 45413.31880021736, 45477.77261113673, 45497.0, 45581.54418455773, 584288.0, 594378.0, 596643.0, 597691.0, 598357.0, 34598.65401346055, 41773.79113104415, 43213.56786432221, 44327.55672443234, 44334.66995990126, 45501.0, 45618.54381456143, 46233.76883115563, 46284.53715462802, 47037.76481117709, 38015.809920951324, 38040.619593803705, 38333.041673958374, 38895.41656875092, 39340.2131957365, 34455.483167753, 37326.25347492981, 37631.62368376281, 38010.61989380071, 38921.0, 36869.743712384, 37100.81449592735, 37144.81427592953, 37470.250594988494, 38133.0, 32106.03681889547, 33490.49764253587, 33670.0, 33770.49344259887, 33850.153746156546, 22887.08451661915, 23970.88014559916, 24358.63462048106, 24608.138715144883, 24961.625575616745], "value": 19582.101211120847, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.173341046689, 340.9846556904939, 364.1061194766846, 368.9907752306192, 395.4286056648145, 15153.54539363818, 15179.16514591697, 20250.89874550629, 20442.08010639521, 21109.366718998415, 16273.51179464615, 19014.0, 19573.92343421115, 20456.29505213802, 20775.896120519414, 5709.343425506068, 5948.464638182562, 5950.613210141327, 5957.314908785502, 5964.0, 5496.532794712434, 5807.535397168216, 5865.120231965197, 5868.119782032708, 5876.764929402819, 5542.279503664538, 5791.391903850098, 5807.535397168216, 5808.8838223235725, 5817.563682723791, 5645.915311270334, 5778.682172480512, 5779.971100144515, 5783.36382997872, 5785.074388097884, 4833.830815921461, 5045.0, 5051.823186188466, 5054.241863720435, 5064.822731204427, 24384.878075609686, 24404.145854894992, 24499.632505512433, 24745.13392031288, 24751.25746227616, 2214.2139540463063, 2250.493638931244, 2275.4425165834477, 2394.162043284854, 2995.996341225692, 14675.853241467556, 15164.317605707769, 15466.226688665522, 15569.455069072641, 15842.28709708066, 1792.09499202903, 1838.9780154486116, 1841.3095089341498, 1847.7228415737613, 1875.9437216883516, 92313.0, 115990.0, 116855.4157229217, 117300.0, 132133.6786632131, 10938.991777261317, 10971.945140274407, 11012.119030477703, 11102.874229450656, 11268.943655281671, 13844.238566878917, 13848.86151138496, 13850.445982160802, 13868.029237953448, 13882.583522494342, 8189.426740128134, 8419.284360829399, 8461.788455288544, 8572.3999320047, 8609.354298427643, 7912.0505539335645, 8328.375371847136, 8353.665853365797, 8485.745427637181, 8501.319894408432, 3607.639236076424, 3733.383991641403, 3754.6433088856515, 4336.503906152436, 4367.192069467159, 1856.3595559531939, 1892.8296453319267, 1896.7818700849302, 1896.8482521398255, 1908.5133291010704, 817.2603793566832, 851.936104792143, 867.1415298854089, 879.2174964281794, 944.6363150187227, 17649.558761031076, 18749.437516874517, 21192.15231390758, 21402.25092121768, 21592.136314547242, 888.3026823943173, 907.8502047162144, 937.1659223291276, 950.3680052764834, 958.9424634521812, 9497.145256926771, 10612.681619551426, 10787.78424431507, 11098.167637427065, 11135.665930022113, 500422.0, 573497.0, 574233.0, 579349.0, 584328.0, 99918.0, 105099.94900051055, 125908.37045814712, 133288.0, 139058.30470847784, 967.3857100740939, 1005.9597616095274, 1023.3706270643501, 1025.6410256410197, 1028.9125424338865, 18277.54306142357, 19261.614767704847, 19437.51406214856, 19548.21807127699, 19875.602487950448, 55040.0, 55269.1709624364, 55810.744603201325, 56747.0, 57198.7140064297, 69001.30998689949, 76795.0, 78899.60550197212, 83693.58153209195, 83836.16163838284, 7809.648565814438, 7838.921610783819, 8036.156203598772, 8077.0, 8138.796530086795, 12621.28642341901, 12631.557895473614, 12720.173188742701, 13083.476660933837, 13084.803727943898, 11050.226484146033, 11056.060234880126, 11190.720231994264, 11288.887111128784, 11297.152713546353, 4445.555444455528, 9724.173445256949, 10752.462376881282, 10835.695112990614, 10849.837252441062, 9038.265242612199, 11204.383758893338, 11355.489002995051, 11404.885951140383, 11547.364894930859, 7685.154632990479, 7700.961495192488, 8106.635201416068, 8124.67501299965, 8173.509589424653, 9720.708378748648, 10636.41499717523, 11151.330920144817, 11158.88841111611, 11226.382548959558], "value": 9412.042776764314, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.869781719645, 1492.1047371577056, 1623.3912282893914, 1801.3064969986551, 1828.040278853601, 8232.094469608346, 8756.036835948049, 8769.210771030606, 8770.342224333168, 8784.077671844452, 7602.467827252094, 7854.685812567491, 7868.2918537331625, 7971.0434747830195, 8564.100769419214, 2072.0572139676447, 2274.044901141516, 2360.103160798894, 2365.4914193448403, 2369.07606033647, 2345.3433038749176, 2347.6713260143592, 2349.7767712067325, 2352.8588284702973, 2361.787439130478, 1747.4582879307454, 2356.846804957681, 2362.29131260622, 2364.125273648752, 2369.0050178924807, 2319.223060274818, 2332.8950197241165, 2358.2571489980774, 2363.480034392448, 2364.337985364101, 2317.9884100579557, 2326.6393708974965, 2331.219041621059, 2332.93001209964, 2352.364861487406, 8606.698765543177, 8614.138586141398, 8674.349423793177, 8685.0, 8705.608247628872, 5034.295198672189, 5186.611004174702, 5193.610479214074, 5196.844094677166, 5197.662151960149, 3398.6771256730817, 3545.982270088659, 3586.7668601541077, 3592.838322275478, 3595.946060809064, 960.9135177834029, 1034.6999370182639, 1090.6782499162766, 1109.112709832131, 1111.3387534417009, 11116.221864469491, 11185.832212516818, 11199.328040317525, 11203.71990700239, 11217.158713096469, 4305.612494875476, 4465.084657645175, 4638.652101092432, 4661.114388266218, 4661.463931647904, 9536.761580960532, 9545.331826772199, 9552.092551207625, 9576.281778866613, 9584.185344245678, 5184.14461613837, 5186.533212010937, 5187.481251874782, 5189.962007598496, 5193.2989046479, 4572.7713614319155, 4686.882827929329, 4733.621310295168, 4763.54746299108, 4766.4995175506765, 3491.8428670709877, 3618.8371523281517, 3830.9808450958126, 3890.105275786585, 3897.70767192456, 1264.5574049082747, 1272.9363531823371, 1275.3177050278057, 1286.8777466140702, 1290.7676618208625, 885.172363839799, 920.2086205862953, 920.9769755756159, 939.6899023322424, 944.8346539355585, 5723.8569035774435, 5837.374669007245, 5874.9118763218585, 6056.3338032816355, 6159.045347971071, 827.1397746343855, 886.082904194156, 945.333539854401, 951.5432592355708, 966.3235734985539, 3362.9831850840587, 3944.3294639911283, 4024.71209213049, 4027.2146931348107, 4101.5488296287385, 9772.462514561625, 10426.843597346045, 10567.94716026415, 10572.0, 10594.364338139736, 9056.283115065455, 10166.0, 10573.101286390589, 10594.205434592437, 10647.84028239577, 7871.48835325702, 8059.314958228616, 8076.798080048045, 8086.231807978233, 8086.353091752646, 4708.505606911362, 5751.166080918159, 5763.8559036024435, 5766.192733017465, 5906.911396329145, 9087.636494540144, 9362.251019918254, 9543.950165481652, 9755.951220243853, 9833.360831545926, 9757.51212439361, 9868.753281168028, 9935.354201976848, 9942.950285248528, 10000.399976001463, 7201.171865235356, 7294.708211671686, 7326.157491888501, 7357.632118393952, 7367.742129025459, 7261.782146535613, 7542.962285188539, 7711.11322197932, 7899.486533375312, 7978.002749656293, 7140.6072666003865, 7286.781396558112, 7319.707211711685, 7321.853562928818, 7437.962810185914, 6315.842103947438, 7038.718451262097, 7190.1731300901065, 7211.567305961659, 7348.265173482609, 6836.829079273058, 6894.931050689429, 6896.241413444417, 6900.723971041102, 6904.206016308189, 4767.523247675204, 4900.14247506692, 4932.482089380564, 4941.579965702956, 5152.355955505562, 4929.088118698052, 5069.467705890976, 5086.313347698014, 5135.204043373283, 5253.632245742761], "value": 4493.408688676289, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [233.541091754702, 294.6125844514463, 303.77672410778086, 304.9737632865408, 306.1138005474154, 4622.930656040162, 5049.2678561608545, 5192.143296356101, 5262.210668399737, 5268.762905669245, 4725.621950243981, 5154.175331946889, 5177.223416487519, 5203.791848326063, 5224.712640804754, 3791.9620803792022, 3980.6417422431973, 3983.948266642681, 4023.436718859362, 4076.8165432555534, 3900.980495097535, 3935.822887970048, 3970.7419017763896, 4005.699572532057, 4007.238624661305, 3456.8271586420706, 3973.9205215895663, 3979.3235150024566, 4003.299422601048, 4006.318925782624, 3525.94711079334, 3835.9424608630893, 3982.1438390745984, 4027.577104404025, 4052.9189416211807, 3355.4799006154085, 3467.133216695826, 3485.3203625293077, 3497.2655742293896, 3552.520409744677, 3883.1457079442475, 5015.699058056528, 5068.3917929848285, 5091.847244582668, 5092.618053645954, 3815.389537673959, 4533.909321813545, 4568.680192386534, 4581.541845815425, 4583.358329833826, 5011.273365362039, 5204.75085979366, 5503.174523821419, 5525.254090697745, 5534.557235421156, 3448.896533103986, 3766.0396598867114, 3891.1633998690418, 3894.4547763313158, 3910.276598829226, 5504.146857237095, 5504.614676972654, 5729.1119876419225, 5754.251947246831, 5764.250647415851, 3662.597114317423, 3929.410588411704, 3949.644531992077, 3956.940645890314, 4011.8997025074605, 3372.9325413491592, 4023.7325242548504, 4032.334664780337, 4048.716589838742, 4048.8582899598373, 4035.7174997750462, 4577.0, 4581.908361832745, 4644.535546445329, 4648.558386953234, 4437.134758722018, 4875.684050076256, 5242.606804489678, 5549.944500555023, 5590.496855283044, 1165.5104855960346, 1353.966150846217, 1373.203541945669, 1374.587623712882, 1375.4154484344115, 741.4068745004083, 797.1271457753867, 801.935845132388, 831.4886344897965, 848.1942154952819, 4245.8513952011535, 4312.54718254585, 4320.308750599889, 4330.4193969201015, 4337.544557821447, 4234.428352172417, 4662.953370466252, 4708.88227794301, 4727.716337019721, 4786.611882554021, 3646.4530320452145, 4290.29210180323, 4311.116221174714, 4324.362139095149, 4324.394584758136, 2320.6772139880445, 2700.3789128500557, 2823.858807059639, 2991.745701615387, 3009.1042643135124, 5199.922001169985, 5844.12338149281, 5845.707714614337, 5860.033094539439, 5885.941140588539, 5542.307211598551, 5800.709964501758, 5807.593468457168, 5830.329512106077, 5878.735456904449, 5573.714776941434, 5848.64908105515, 5884.911291411103, 5886.440788125121, 5890.469857712741, 5664.762418844616, 5679.9148012779015, 5688.63023903445, 5710.9428905712075, 5729.914051289151, 4606.723596584216, 4878.146324393288, 5474.206240095244, 5482.0, 5641.492265696107, 5496.367917689357, 5858.355580886185, 5859.5019423349495, 5880.79443714029, 5949.583529152917, 3180.775401470404, 3368.612609549933, 3462.8918746002046, 3647.215848592565, 3699.3341198584512, 5401.217598192666, 5408.3509978802795, 5617.859553511195, 5643.915341269966, 5693.9430605693415, 4806.7836947336755, 5176.948230517647, 5430.212619170277, 5443.292371991616, 5489.423610520837, 4847.200211965127, 5515.393306736336, 5559.861003474945, 5570.888582228414, 5583.302087239095, 4586.8165273388695, 4608.262677971508, 5465.180222966586, 5466.835994920159, 5476.495043172294, 4881.585065269492, 5044.537084245528, 5523.392426833126, 5573.7459071708545, 5583.274174357308, 5171.741412929414, 5454.972725136349, 5486.451354864482, 5490.670559766427, 5492.725363731877], "value": 3967.8587514780415, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.9543729657569, 705.5202462325619, 754.8565772503224, 762.1996903251586, 812.082346947949, 15232.695346093085, 15726.764098538531, 15732.921335393321, 15754.212289385514, 15760.527184184493, 13911.791323130135, 13945.233012184326, 13983.93008034961, 13987.090839095452, 13987.440502379917, 670.8859493886026, 692.9965350173242, 820.3519219816338, 834.8288600836844, 865.4417900454204, 823.4976664234812, 838.1409055717908, 838.4214891724721, 839.3914412051275, 841.1378337204353, 901.1619194149439, 901.8286525560122, 902.9909700902973, 903.2818908967387, 906.1618003346911, 718.3821913154683, 881.6297155194834, 896.3456676626067, 896.6996056321108, 907.1518130547893, 670.1187937861745, 670.3732010570084, 670.8993650952348, 670.9731610735565, 671.633959492078, 14552.781708274384, 14574.271286435636, 14595.927020364936, 14602.0, 14665.486707965276, 13502.257375844325, 13732.382042808098, 13743.312834358341, 13864.098833575785, 13975.650608734863, 11170.329780213213, 12538.373081345897, 12670.746585068247, 12834.294113823737, 12849.229046257255, 4476.328550717386, 4484.865454036384, 4552.88617784558, 4674.135284972291, 4688.859334219979, 105890.47054764676, 109008.90991090066, 112491.43754281258, 117140.0, 119093.0, 10790.56837726482, 10854.45727713611, 11044.282121662067, 11094.278871873303, 11167.720806979729, 21533.815640139943, 21534.56930861374, 21564.78435215628, 21571.460713482287, 21602.891985539973, 7716.228377162239, 7932.0, 8018.198180181935, 8081.191880811989, 8119.512829230265, 4330.2638551446325, 4444.3333499974615, 4447.688661793708, 4449.933251001237, 4519.163954668397, 3455.51622772812, 3856.1902000580194, 4079.4288799568085, 4079.8776036718946, 4087.2438598859308, 712.8895021271711, 716.8351279205708, 724.2721065329415, 729.806601250669, 739.2607392607392, 572.7995201679379, 654.7773756922669, 668.6872795638604, 674.8414122681166, 680.2279412866334, 9103.954480227689, 9447.716568502956, 9456.810863782823, 9571.33000689945, 9722.215331491045, 561.8511094559944, 638.6615094000188, 642.325558163926, 659.3044338223125, 701.3022043067181, 4305.784710764449, 4435.445569303837, 4754.976225118852, 4758.214894542361, 4848.854534363974, 152909.0, 159242.0, 159638.0, 160903.0, 161537.19231403767, 130590.0, 132436.0, 133560.9795118444, 133586.0, 141540.0, 110819.0, 116955.0, 117926.99999999828, 120770.3961480187, 124128.37935810444, 8812.779680508038, 9002.504862232377, 9033.59348829291, 9199.586018629312, 9202.53987300619, 53942.0, 65466.34533654793, 65844.67077664581, 67663.0, 68333.50675605361, 93205.60191596996, 97606.0, 99359.0, 101871.0, 102210.0, 10210.642627508001, 11908.04735621131, 12042.518299267931, 12264.877351226374, 12503.124781265224, 11454.942725286654, 13049.217046977212, 13069.542566010145, 13167.539136130572, 13215.339233038118, 12236.388180590757, 12239.510419583117, 12258.080643951562, 12355.135140540435, 12362.44369003415, 12113.357094788336, 12247.75504489923, 12337.568185113838, 12516.499340026297, 12546.811797822857, 11992.520299187934, 12003.099767517297, 12040.578579749666, 12201.145919785884, 12259.693507662023, 9835.655752048644, 10046.799064018825, 10247.692569222936, 10409.68770936873, 10452.477376131315, 10235.232357573062, 10456.215783816397, 10519.842202367123, 10540.894591054299, 10546.630867919586], "value": 7804.116151590458, "unit": "score"}], "name": "dromaeo_css", "value": 7248.57622153854, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 133.0, 114.0, 113.0, 114.0, 113.0, 114.0, 113.0, 113.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 110.0, 134.0, 163.0, 110.0, 132.0, 128.0, 132.0, 161.0, 107.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 186.0, 183.0, 186.0, 185.0, 183.0, 183.0, 184.0, 185.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 78.0, 152.0, 59.0, 57.0, 60.0, 69.0, 137.0, 58.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 135.0, 72.0, 72.0, 79.0, 119.0, 69.0, 75.0, 124.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 84.0, 87.0, 87.0, 88.0, 85.0, 92.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 101.0, 99.0, 95.0, 163.0, 98.0, 97.0, 99.0], "value": 104.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 85.0, 85.0, 85.0, 86.0, 87.0, 87.0, 86.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 53.0, 54.0, 55.0, 54.0, 54.0, 54.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 106.0, 122.0, 90.0, 73.0, 70.0, 70.0, 71.0, 70.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 105.0, 105.0, 76.0, 80.0, 82.0, 84.0, 76.0, 101.0, 107.0], "value": 91.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 140.0, 137.0, 137.0, 139.0, 138.0, 128.0, 199.0, 150.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 174.0, 169.0, 169.0, 170.0, 168.0, 169.0, 169.0, 168.0, 168.0], "value": 169.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 67.0, 66.0, 69.0, 68.0, 67.0, 65.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1490.6, "alertThreshold": 2.0}]} 23:43:48 INFO - Return code: 0 23:43:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:43:48 INFO - # TBPL SUCCESS # 23:43:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:43:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:43:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:43:48 INFO - 2016-08-29 23:43:48.644 screenresolution[1922:12134] starting screenresolution argv=screenresolution get 23:43:48 INFO - 2016-08-29 23:43:48.647 screenresolution[1922:12134] Display 0: 1600x1200x32@60 23:43:48 INFO - 2016-08-29 23:43:48.653 screenresolution[1923:12138] starting screenresolution argv=screenresolution list 23:43:48 INFO - Available Modes on Display 0 23:43:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:43:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:43:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:43:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:43:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:43:48 INFO - Intel Iris: 23:43:48 INFO - Chipset Model: Intel Iris 23:43:48 INFO - Type: GPU 23:43:48 INFO - Bus: Built-In 23:43:48 INFO - VRAM (Dynamic, Max): 1536 MB 23:43:48 INFO - Vendor: Intel (0x8086) 23:43:48 INFO - Device ID: 0x0a2e 23:43:48 INFO - Revision ID: 0x0009 23:43:48 INFO - Displays: 23:43:48 INFO - Display: 23:43:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:43:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:43:48 INFO - Display Serial Number: 1600x1200 60 23:43:48 INFO - Main Display: Yes 23:43:48 INFO - Mirror: Off 23:43:48 INFO - Online: Yes 23:43:48 INFO - Rotation: Supported 23:43:48 INFO - Return code: 0 23:43:48 INFO - Running post-action listener: _resource_record_post_action 23:43:48 INFO - [mozharness: 2016-08-30 06:43:48.888770Z] Finished run-tests step (success) 23:43:48 INFO - Running post-run listener: _resource_record_post_run 23:43:48 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1966080; Write bytes: 706283008; Read time: 23; Write time: 1925 23:43:48 INFO - TinderboxPrint: CPU usage
53.8% 23:43:48 INFO - TinderboxPrint: I/O read bytes / time
1,966,080 / 23 23:43:48 INFO - TinderboxPrint: I/O write bytes / time
706,283,008 / 1,925 23:43:48 INFO - TinderboxPrint: CPU idle
2,242.6 (46.2%) 23:43:48 INFO - TinderboxPrint: CPU system
975.8 (20.1%) 23:43:48 INFO - TinderboxPrint: CPU user
1,631.3 (33.6%) 23:43:48 INFO - TinderboxPrint: Swap in / out
408,854,528 / 0 23:43:48 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390103552; Write bytes: 384710656; Read time: 29515; Write time: 678 23:43:48 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1966080; Write bytes: 297442816; Read time: 23; Write time: 1202 23:43:49 INFO - Running post-run listener: _upload_blobber_files 23:43:49 INFO - Blob upload gear active. 23:43:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:43:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:43:49 INFO - (blobuploader) - INFO - Open directory for files ... 23:43:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:43:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:43:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:43:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:43:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:43:51 INFO - (blobuploader) - INFO - Done attempting. 23:43:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:43:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:43:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:43:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:43:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:43:52 INFO - (blobuploader) - INFO - Done attempting. 23:43:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:43:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:43:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:43:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:43:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:43:54 INFO - (blobuploader) - INFO - Done attempting. 23:43:54 INFO - (blobuploader) - INFO - Iteration through files over. 23:43:54 INFO - Return code: 0 23:43:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:43:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:43:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/970bf1a59222d0d53d814142c3006c1a60662f2a83b4e07a7b929c3259cd89c88db44e4a35015f94df6b94fe64bdf65ff17c6c8caad5d5080731cb6b5e5ecc26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d6c85d403a00000ac1965624904944260ea9bcb2e820d754f91d4631ab2cf371fba89dfca6e857da3199be20fbac25fea72adf4f99c4d7252179e23e42fc8d45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1fc69f64df55b80566037eb2c80839fdc521d592fc58e3987bedbaac43ae1779a79726fb7fa8e533c003787c824b27a001377bb37b07ba0df3d8e16ac1e13b62"} 23:43:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:43:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:43:54 INFO - Contents: 23:43:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/970bf1a59222d0d53d814142c3006c1a60662f2a83b4e07a7b929c3259cd89c88db44e4a35015f94df6b94fe64bdf65ff17c6c8caad5d5080731cb6b5e5ecc26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d6c85d403a00000ac1965624904944260ea9bcb2e820d754f91d4631ab2cf371fba89dfca6e857da3199be20fbac25fea72adf4f99c4d7252179e23e42fc8d45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1fc69f64df55b80566037eb2c80839fdc521d592fc58e3987bedbaac43ae1779a79726fb7fa8e533c003787c824b27a001377bb37b07ba0df3d8e16ac1e13b62"} 23:43:54 INFO - Running post-run listener: copy_logs_to_upload_dir 23:43:54 INFO - Copying logs to upload dir... 23:43:54 INFO - mkdir: /builds/slave/test/build/upload/logs 23:43:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.021083 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-29 23:43:54.395012) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 23:43:54.398108) ========= 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.BAxiv8nwji/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.Ur5msYu6e9/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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/970bf1a59222d0d53d814142c3006c1a60662f2a83b4e07a7b929c3259cd89c88db44e4a35015f94df6b94fe64bdf65ff17c6c8caad5d5080731cb6b5e5ecc26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d6c85d403a00000ac1965624904944260ea9bcb2e820d754f91d4631ab2cf371fba89dfca6e857da3199be20fbac25fea72adf4f99c4d7252179e23e42fc8d45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1fc69f64df55b80566037eb2c80839fdc521d592fc58e3987bedbaac43ae1779a79726fb7fa8e533c003787c824b27a001377bb37b07ba0df3d8e16ac1e13b62"} build_url:https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010998 build_url: 'https://queue.taskcluster.net/v1/task/Bt4cPVauQMiy5UgRphdO9w/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/970bf1a59222d0d53d814142c3006c1a60662f2a83b4e07a7b929c3259cd89c88db44e4a35015f94df6b94fe64bdf65ff17c6c8caad5d5080731cb6b5e5ecc26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d6c85d403a00000ac1965624904944260ea9bcb2e820d754f91d4631ab2cf371fba89dfca6e857da3199be20fbac25fea72adf4f99c4d7252179e23e42fc8d45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1fc69f64df55b80566037eb2c80839fdc521d592fc58e3987bedbaac43ae1779a79726fb7fa8e533c003787c824b27a001377bb37b07ba0df3d8e16ac1e13b62"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 23:43:54.434533) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:43:54.434940) ========= 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.BAxiv8nwji/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.Ur5msYu6e9/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.005060 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 23:43:54.483235) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 23:43:54.483540) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 23:43:54.483843) ========= ========= Total master_lag: 0.19 =========