builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0321 starttime: 1471634378.6 results: success (0) buildid: 20160819095522 builduid: cb62e15829234a60b138658834426601 revision: 8ef9629d8f90d6507b1bad01146b14101de79174 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:38.602509) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:38.602962) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:38.603243) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:38.773428) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:38.773738) ========= 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.JL9N3pTKxO/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.eqrkFM6L98/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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-19 12:19: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% 136M=0s 2016-08-19 12:19:38 (136 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109606 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:38.910563) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:38.911074) ========= 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.JL9N3pTKxO/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.eqrkFM6L98/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.025639 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:38.964093) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:38.964865) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8ef9629d8f90d6507b1bad01146b14101de79174 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8ef9629d8f90d6507b1bad01146b14101de79174 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JL9N3pTKxO/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.eqrkFM6L98/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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-19 12:19:39,051 truncating revision to first 12 chars 2016-08-19 12:19:39,051 Setting DEBUG logging. 2016-08-19 12:19:39,051 attempt 1/10 2016-08-19 12:19:39,051 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8ef9629d8f90?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 12:19:39,705 unpacking tar archive at: mozilla-central-8ef9629d8f90/testing/mozharness/ program finished with exit code 0 elapsedTime=0.944587 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:39.933408) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:39.933778) ========= script_repo_revision: 8ef9629d8f90d6507b1bad01146b14101de79174 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:39.934211) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:39.934535) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 12:19:39.953249) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-08-19 12:19:39.953599) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JL9N3pTKxO/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.eqrkFM6L98/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 12:19:40 INFO - MultiFileLogger online at 20160819 12:19:40 in /builds/slave/test 12:19:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:19:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:19:40 INFO - {'append_to_log': False, 12:19:40 INFO - 'base_work_dir': '/builds/slave/test', 12:19:40 INFO - 'blob_upload_branch': 'Firefox', 12:19:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:19:40 INFO - 'branch': 'Firefox', 12:19:40 INFO - 'buildbot_json_path': 'buildprops.json', 12:19:40 INFO - 'config_files': ('talos/mac_config.py',), 12:19:40 INFO - 'default_actions': ('clobber', 12:19:40 INFO - 'read-buildbot-config', 12:19:40 INFO - 'download-and-extract', 12:19:40 INFO - 'populate-webroot', 12:19:40 INFO - 'create-virtualenv', 12:19:40 INFO - 'install', 12:19:40 INFO - 'run-tests'), 12:19:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:19:40 INFO - 'download_minidump_stackwalk': True, 12:19:40 INFO - 'download_symbols': 'ondemand', 12:19:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:19:40 INFO - 'tooltool.py': '/tools/tooltool.py', 12:19:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:19:40 INFO - '/tools/misc-python/virtualenv.py')}, 12:19:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:19:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:19:40 INFO - 'installer_path': 'installer.exe', 12:19:40 INFO - 'log_level': 'info', 12:19:40 INFO - 'log_name': 'talos', 12:19:40 INFO - 'log_to_console': True, 12:19:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:19:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:19:40 INFO - 'opt_config_files': (), 12:19:40 INFO - 'pip_index': False, 12:19:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:19:40 INFO - 'cmd': ('bash', 12:19:40 INFO - '-c', 12:19:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:19:40 INFO - 'enabled': True, 12:19:40 INFO - 'halt_on_failure': False, 12:19:40 INFO - 'name': 'check_screen_resolution'},), 12:19:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:19:40 INFO - 'cmd': ('bash', 12:19:40 INFO - '-c', 12:19:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:19:40 INFO - 'enabled': True, 12:19:40 INFO - 'halt_on_failure': False, 12:19:40 INFO - 'name': 'check_screen_resolution'},), 12:19:40 INFO - 'run_cmd_checks_enabled': True, 12:19:40 INFO - 'sps_profile': False, 12:19:40 INFO - 'sps_profile_interval': 0, 12:19:40 INFO - 'suite': 'dromaeojs', 12:19:40 INFO - 'system_bits': '32', 12:19:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:19:40 INFO - 'title': 't-yosemite-r7-0321', 12:19:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:19:40 INFO - 'use_talos_json': True, 12:19:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:19:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:19:40 INFO - 'work_dir': 'build'} 12:19:40 INFO - [mozharness: 2016-08-19 19:19:40.149363Z] Running clobber step. 12:19:40 INFO - Running pre-action listener: _resource_record_pre_action 12:19:40 INFO - Running main action method: clobber 12:19:40 INFO - rmtree: /builds/slave/test/build 12:19:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:19:42 INFO - Running post-action listener: _resource_record_post_action 12:19:42 INFO - [mozharness: 2016-08-19 19:19:42.158625Z] Finished clobber step (success) 12:19:42 INFO - [mozharness: 2016-08-19 19:19:42.158752Z] Running read-buildbot-config step. 12:19:42 INFO - Running pre-action listener: _resource_record_pre_action 12:19:42 INFO - Running main action method: read_buildbot_config 12:19:42 INFO - Using buildbot properties: 12:19:42 INFO - { 12:19:42 INFO - "project": "", 12:19:42 INFO - "product": "firefox", 12:19:42 INFO - "script_repo_revision": "production", 12:19:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 12:19:42 INFO - "repository": "", 12:19:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 12:19:42 INFO - "buildid": "20160819095522", 12:19:42 INFO - "pgo_build": "False", 12:19:42 INFO - "basedir": "/builds/slave/test", 12:19:42 INFO - "buildnumber": 35, 12:19:42 INFO - "slavename": "t-yosemite-r7-0321", 12:19:42 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:19:42 INFO - "platform": "yosemite_r7", 12:19:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:19:42 INFO - "branch": "mozilla-central", 12:19:42 INFO - "repo_path": "mozilla-central", 12:19:42 INFO - "revision": "8ef9629d8f90d6507b1bad01146b14101de79174", 12:19:42 INFO - "stage_platform": "macosx64", 12:19:42 INFO - "builduid": "cb62e15829234a60b138658834426601", 12:19:42 INFO - "slavebuilddir": "test" 12:19:42 INFO - } 12:19:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:19:42 INFO - Running post-action listener: _resource_record_post_action 12:19:42 INFO - [mozharness: 2016-08-19 19:19:42.162124Z] Finished read-buildbot-config step (success) 12:19:42 INFO - [mozharness: 2016-08-19 19:19:42.162254Z] Running download-and-extract step. 12:19:42 INFO - Running pre-action listener: _resource_record_pre_action 12:19:42 INFO - Running main action method: download_and_extract 12:19:42 INFO - mkdir: /builds/slave/test/build/tests 12:19: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')]} 12:19:42 INFO - trying https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:19:42 INFO - Downloading https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/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 12:19:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/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 12:19:43 INFO - Downloaded 1199 bytes. 12:19:43 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:19:43 INFO - Using the following test package requirements: 12:19:43 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:19:43 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:19:43 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 12:19:43 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:19:43 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 12:19:43 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:19:43 INFO - u'jsshell-mac.zip'], 12:19:43 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:19:43 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 12:19:43 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:19:43 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:19:43 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 12:19:43 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:19:43 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 12:19:43 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:19:43 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 12:19:43 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:19:43 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 12:19:43 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:19:43 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')]} 12:19:43 INFO - trying https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:19:43 INFO - Downloading https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/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 12:19:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/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 12:19:44 INFO - Downloaded 16350106 bytes. 12:19:44 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 12:19:51 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 12:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:51 INFO - trying https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:19:51 INFO - Downloading https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/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 12:19:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/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 12:19:52 INFO - Downloaded 16350106 bytes. 12:19: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 12:19:58 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')]} 12:19:58 INFO - trying https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:19:58 INFO - Downloading https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/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 12:19:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/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 12:19:59 INFO - Downloaded 14413981 bytes. 12:19:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 12:19: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')]} 12:19:59 INFO - trying https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:19:59 INFO - Downloading https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 12:19:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/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 12:20:02 INFO - Downloaded 111377632 bytes. 12:20:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:20:02 INFO - mkdir: /builds/slave/test/properties 12:20:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:20:02 INFO - Writing to file /builds/slave/test/properties/build_url 12:20:02 INFO - Contents: 12:20:02 INFO - build_url:https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:20:03 INFO - Running post-action listener: _resource_record_post_action 12:20:03 INFO - Running post-action listener: set_extra_try_arguments 12:20:03 INFO - [mozharness: 2016-08-19 19:20:03.436685Z] Finished download-and-extract step (success) 12:20:03 INFO - [mozharness: 2016-08-19 19:20:03.436824Z] Running populate-webroot step. 12:20:03 INFO - Running pre-action listener: _resource_record_pre_action 12:20:03 INFO - Running main action method: populate_webroot 12:20:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:20:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:20:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:20:03 INFO - '--tppagecycles', 12:20:03 INFO - '7'], 12:20:03 INFO - 'tests': ['tsvgm']}}, 12:20:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:20:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:20:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:20:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:20:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:20:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:20:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:20:03 INFO - 'talos_options': ['--disable-e10s'], 12:20:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:20:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:20:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:20:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:20:03 INFO - 'talos_options': ['--disable-e10s'], 12:20:03 INFO - 'tests': ['damp', 'tps']}, 12:20:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:20:03 INFO - 'tests': ['damp', 'tps']}, 12:20:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:20:03 INFO - 'tests': ['dromaeo_dom']}, 12:20:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:20:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:20:03 INFO - 'tests': ['basic_compositor_video']}, 12:20:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:20:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:20:03 INFO - 'tests': ['a11yr', 12:20:03 INFO - 'ts_paint', 12:20:03 INFO - 'tpaint', 12:20:03 INFO - 'sessionrestore', 12:20:03 INFO - 'sessionrestore_no_auto_restore', 12:20:03 INFO - 'tabpaint']}, 12:20:03 INFO - 'other-e10s': {'tests': ['a11yr', 12:20:03 INFO - 'ts_paint', 12:20:03 INFO - 'tpaint', 12:20:03 INFO - 'sessionrestore', 12:20:03 INFO - 'sessionrestore_no_auto_restore', 12:20:03 INFO - 'tabpaint']}, 12:20:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:20:03 INFO - 'ts_paint', 12:20:03 INFO - 'tpaint', 12:20:03 INFO - 'sessionrestore', 12:20:03 INFO - 'sessionrestore_no_auto_restore', 12:20:03 INFO - 'tabpaint']}, 12:20:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:20:03 INFO - 'ts_paint', 12:20:03 INFO - 'tpaint', 12:20:03 INFO - 'sessionrestore', 12:20:03 INFO - 'sessionrestore_no_auto_restore', 12:20:03 INFO - 'tabpaint']}, 12:20:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:20:03 INFO - 'tests': ['a11yr', 12:20:03 INFO - 'ts_paint', 12:20:03 INFO - 'tpaint', 12:20:03 INFO - 'sessionrestore', 12:20:03 INFO - 'sessionrestore_no_auto_restore', 12:20:03 INFO - 'tabpaint']}, 12:20:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:20:03 INFO - 'tests': ['a11yr', 12:20:03 INFO - 'ts_paint', 12:20:03 INFO - 'tpaint', 12:20:03 INFO - 'sessionrestore', 12:20:03 INFO - 'sessionrestore_no_auto_restore', 12:20:03 INFO - 'tabpaint']}, 12:20:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:20:03 INFO - 'tests': ['tsvgx', 12:20:03 INFO - 'tsvgr_opacity', 12:20:03 INFO - 'tart', 12:20:03 INFO - 'tscrollx', 12:20:03 INFO - 'cart']}, 12:20:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:20:03 INFO - 'tsvgr_opacity', 12:20:03 INFO - 'tart', 12:20:03 INFO - 'tscrollx', 12:20:03 INFO - 'cart']}, 12:20:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:20:03 INFO - 'talos_options': ['--disable-e10s'], 12:20:03 INFO - 'tests': ['tp5o']}, 12:20:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:20:03 INFO - 'tests': ['tp5o']}, 12:20:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:20:03 INFO - 'talos_options': ['--disable-e10s', 12:20:03 INFO - '--xperf_path', 12:20:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:20:03 INFO - 'tests': ['tp5n']}, 12:20:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:20:03 INFO - 'talos_options': ['--xperf_path', 12:20:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:20:03 INFO - 'tests': ['tp5n']}}, 12:20:03 INFO - 'talos.zip': {'path': '', 12:20:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:20:03 INFO - Running post-action listener: _resource_record_post_action 12:20:03 INFO - [mozharness: 2016-08-19 19:20:03.454919Z] Finished populate-webroot step (success) 12:20:03 INFO - [mozharness: 2016-08-19 19:20:03.455037Z] Running create-virtualenv step. 12:20:03 INFO - Running pre-action listener: _resource_record_pre_action 12:20:03 INFO - Running main action method: create_virtualenv 12:20:03 INFO - Creating virtualenv /builds/slave/test/build/venv 12:20:03 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 12:20:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:20:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:20:03 INFO - Using real prefix '/tools/python27' 12:20:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:20:04 INFO - Installing distribute.............................................................................................................................................................................................done. 12:20:07 INFO - Installing pip.................done. 12:20:07 INFO - Return code: 0 12:20:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106477a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10650c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddeacc5710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063dbd30>, '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 0x106140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d3b20>, '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.JL9N3pTKxO/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.eqrkFM6L98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 12:20:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:20:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JL9N3pTKxO/Render', 12:20:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:07 INFO - 'HOME': '/Users/cltbld', 12:20:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:07 INFO - 'LOGNAME': 'cltbld', 12:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:07 INFO - 'MOZ_NO_REMOTE': '1', 12:20:07 INFO - 'NO_EM_RESTART': '1', 12:20:07 INFO - 'PAGER': '/bin/cat', 12:20:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:07 INFO - 'PWD': '/builds/slave/test', 12:20:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:07 INFO - 'SHELL': '/bin/bash', 12:20:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqrkFM6L98/Listeners', 12:20:07 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 12:20:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:07 INFO - 'USER': 'cltbld', 12:20:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:07 INFO - 'XPC_FLAGS': '0x0', 12:20:07 INFO - 'XPC_SERVICE_NAME': '0', 12:20:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:07 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 12:20:07 INFO - Cleaning up... 12:20:07 INFO - Return code: 0 12:20:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106477a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10650c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddeacc5710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063dbd30>, '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 0x106140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d3b20>, '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.JL9N3pTKxO/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.eqrkFM6L98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 12:20:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:20:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JL9N3pTKxO/Render', 12:20:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:07 INFO - 'HOME': '/Users/cltbld', 12:20:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:07 INFO - 'LOGNAME': 'cltbld', 12:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:07 INFO - 'MOZ_NO_REMOTE': '1', 12:20:07 INFO - 'NO_EM_RESTART': '1', 12:20:07 INFO - 'PAGER': '/bin/cat', 12:20:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:07 INFO - 'PWD': '/builds/slave/test', 12:20:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:07 INFO - 'SHELL': '/bin/bash', 12:20:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqrkFM6L98/Listeners', 12:20:07 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 12:20:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:07 INFO - 'USER': 'cltbld', 12:20:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:07 INFO - 'XPC_FLAGS': '0x0', 12:20:07 INFO - 'XPC_SERVICE_NAME': '0', 12:20:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:07 INFO - Downloading/unpacking psutil>=3.1.1 12:20:07 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 12:20:07 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 12:20:07 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 12:20:07 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 12:20:07 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 12:20:07 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 12:20:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:20:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:20:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:20:10 INFO - Installing collected packages: psutil 12:20:10 INFO - Running setup.py install for psutil 12:20:10 INFO - building 'psutil._psutil_osx' extension 12:20:10 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 12:20:11 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 12:20:11 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 12:20:11 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 12:20:11 INFO - building 'psutil._psutil_posix' extension 12:20:11 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 12:20:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:20:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:20:11 INFO - ^ 12:20:11 INFO - 1 warning generated. 12:20:11 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 12:20:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:20:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:20:11 INFO - Successfully installed psutil 12:20:11 INFO - Cleaning up... 12:20:11 INFO - Return code: 0 12:20:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:20: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')]} 12:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20: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')]} 12:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106477a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10650c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddeacc5710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063dbd30>, '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 0x106140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d3b20>, '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.JL9N3pTKxO/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.eqrkFM6L98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 12:20:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:20:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JL9N3pTKxO/Render', 12:20:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:11 INFO - 'HOME': '/Users/cltbld', 12:20:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:11 INFO - 'LOGNAME': 'cltbld', 12:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:11 INFO - 'MOZ_NO_REMOTE': '1', 12:20:11 INFO - 'NO_EM_RESTART': '1', 12:20:11 INFO - 'PAGER': '/bin/cat', 12:20:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:11 INFO - 'PWD': '/builds/slave/test', 12:20:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:11 INFO - 'SHELL': '/bin/bash', 12:20:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqrkFM6L98/Listeners', 12:20:11 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 12:20:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:11 INFO - 'USER': 'cltbld', 12:20:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:11 INFO - 'XPC_FLAGS': '0x0', 12:20:11 INFO - 'XPC_SERVICE_NAME': '0', 12:20:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:20:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:20:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:20:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:20:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:20:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:20:14 INFO - Installing collected packages: mozsystemmonitor 12:20:14 INFO - Running setup.py install for mozsystemmonitor 12:20:14 INFO - Successfully installed mozsystemmonitor 12:20:14 INFO - Cleaning up... 12:20:14 INFO - Return code: 0 12:20:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:20:14 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')]} 12:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:14 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')]} 12:20:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:14 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 0x106477a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10650c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddeacc5710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063dbd30>, '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 0x106140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d3b20>, '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.JL9N3pTKxO/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.eqrkFM6L98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 12:20:14 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 12:20:14 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 12:20:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JL9N3pTKxO/Render', 12:20:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:14 INFO - 'HOME': '/Users/cltbld', 12:20:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:14 INFO - 'LOGNAME': 'cltbld', 12:20:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:14 INFO - 'MOZ_NO_REMOTE': '1', 12:20:14 INFO - 'NO_EM_RESTART': '1', 12:20:14 INFO - 'PAGER': '/bin/cat', 12:20:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:14 INFO - 'PWD': '/builds/slave/test', 12:20:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:14 INFO - 'SHELL': '/bin/bash', 12:20:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqrkFM6L98/Listeners', 12:20:14 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 12:20:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:14 INFO - 'USER': 'cltbld', 12:20:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:14 INFO - 'XPC_FLAGS': '0x0', 12:20:14 INFO - 'XPC_SERVICE_NAME': '0', 12:20:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:15 INFO - Downloading/unpacking jsonschema==2.5.1 12:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:20:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:20:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:20:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:20:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:20:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:20:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:20: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 12:20: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 12:20: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 12:20: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 12:20: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 12:20: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 12:20:17 INFO - Downloading functools32-3.2.3-2.tar.gz 12:20:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:20:17 INFO - warning: no files found matching '*.txt' 12:20:17 INFO - no previously-included directories found matching 'build' 12:20:17 INFO - no previously-included directories found matching 'dist' 12:20:17 INFO - no previously-included directories found matching '.git*' 12:20:17 INFO - Installing collected packages: jsonschema, functools32 12:20:18 INFO - Running setup.py install for functools32 12:20:18 INFO - warning: no files found matching '*.txt' 12:20:18 INFO - no previously-included directories found matching 'build' 12:20:18 INFO - no previously-included directories found matching 'dist' 12:20:18 INFO - no previously-included directories found matching '.git*' 12:20:18 INFO - Successfully installed jsonschema functools32 12:20:18 INFO - Cleaning up... 12:20:18 INFO - Return code: 0 12:20:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:20:18 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')]} 12:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:18 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')]} 12:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:18 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 0x106477a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10650c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddeacc5710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063dbd30>, '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 0x106140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d3b20>, '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.JL9N3pTKxO/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.eqrkFM6L98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 12:20:18 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 12:20:18 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 12:20:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JL9N3pTKxO/Render', 12:20:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:18 INFO - 'HOME': '/Users/cltbld', 12:20:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:18 INFO - 'LOGNAME': 'cltbld', 12:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:18 INFO - 'MOZ_NO_REMOTE': '1', 12:20:18 INFO - 'NO_EM_RESTART': '1', 12:20:18 INFO - 'PAGER': '/bin/cat', 12:20:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:18 INFO - 'PWD': '/builds/slave/test', 12:20:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:18 INFO - 'SHELL': '/bin/bash', 12:20:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqrkFM6L98/Listeners', 12:20:18 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 12:20:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:18 INFO - 'USER': 'cltbld', 12:20:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:18 INFO - 'XPC_FLAGS': '0x0', 12:20:18 INFO - 'XPC_SERVICE_NAME': '0', 12:20:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:20:18 INFO - Cleaning up... 12:20:18 INFO - Return code: 0 12:20:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:20:18 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')]} 12:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:18 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')]} 12:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:18 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 0x106477a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10650c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddeacc5710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063dbd30>, '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 0x106140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d3b20>, '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.JL9N3pTKxO/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.eqrkFM6L98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 12:20:18 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 12:20:18 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 12:20:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JL9N3pTKxO/Render', 12:20:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:18 INFO - 'HOME': '/Users/cltbld', 12:20:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:18 INFO - 'LOGNAME': 'cltbld', 12:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:18 INFO - 'MOZ_NO_REMOTE': '1', 12:20:18 INFO - 'NO_EM_RESTART': '1', 12:20:18 INFO - 'PAGER': '/bin/cat', 12:20:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:18 INFO - 'PWD': '/builds/slave/test', 12:20:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:18 INFO - 'SHELL': '/bin/bash', 12:20:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqrkFM6L98/Listeners', 12:20:18 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 12:20:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:18 INFO - 'USER': 'cltbld', 12:20:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:18 INFO - 'XPC_FLAGS': '0x0', 12:20:18 INFO - 'XPC_SERVICE_NAME': '0', 12:20:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:18 INFO - Downloading/unpacking blobuploader==1.2.4 12:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:20:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:20:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:20:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:20:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:20:21 INFO - Downloading blobuploader-1.2.4.tar.gz 12:20:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:20:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:20:21 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 12:20:21 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 12:20:21 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 12:20:21 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 12:20:21 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 12:20:21 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 12:20:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:20:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:20:21 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 12:20:21 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 12:20:21 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 12:20:21 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 12:20:21 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 12:20:21 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 12:20:22 INFO - Downloading docopt-0.6.1.tar.gz 12:20:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:20:22 INFO - Installing collected packages: blobuploader, requests, docopt 12:20:22 INFO - Running setup.py install for blobuploader 12:20:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:20:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:20:22 INFO - Running setup.py install for requests 12:20:23 INFO - Running setup.py install for docopt 12:20:23 INFO - Successfully installed blobuploader requests docopt 12:20:23 INFO - Cleaning up... 12:20:23 INFO - Return code: 0 12:20:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:23 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 0x106477a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10650c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddeacc5710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063dbd30>, '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 0x106140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d3b20>, '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.JL9N3pTKxO/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.eqrkFM6L98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 12:20:23 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 12:20:23 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 12:20:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JL9N3pTKxO/Render', 12:20:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:23 INFO - 'HOME': '/Users/cltbld', 12:20:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:23 INFO - 'LOGNAME': 'cltbld', 12:20:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:23 INFO - 'MOZ_NO_REMOTE': '1', 12:20:23 INFO - 'NO_EM_RESTART': '1', 12:20:23 INFO - 'PAGER': '/bin/cat', 12:20:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:23 INFO - 'PWD': '/builds/slave/test', 12:20:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:23 INFO - 'SHELL': '/bin/bash', 12:20:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqrkFM6L98/Listeners', 12:20:23 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 12:20:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:23 INFO - 'USER': 'cltbld', 12:20:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:23 INFO - 'XPC_FLAGS': '0x0', 12:20:23 INFO - 'XPC_SERVICE_NAME': '0', 12:20:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:20:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-io2PtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:20:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-u4FXGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:20:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-ykl9Ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:20:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-FGIXlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:20:24 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-ZpVoUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:20:24 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-gqBAlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:20:24 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-VpSnMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:20:24 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-jU0d_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:20:24 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-NXgZu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:20:24 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-d7ZAiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:20:24 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-uOLigI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:20:24 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-pmRqzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:20:24 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-MiTjaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:20:25 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-pACnex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:20:25 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-6hrz5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:20:25 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-aUO1iI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:20:25 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-oZvylr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:20:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:20:25 INFO - Running setup.py install for manifestparser 12:20:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:20:25 INFO - Running setup.py install for mozcrash 12:20:25 INFO - Running setup.py install for mozdebug 12:20:25 INFO - Running setup.py install for mozdevice 12:20:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:20:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:20:26 INFO - Running setup.py install for mozfile 12:20:26 INFO - Running setup.py install for mozhttpd 12:20:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:20:26 INFO - Running setup.py install for mozinfo 12:20:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:20:26 INFO - Running setup.py install for mozInstall 12:20:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:20:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:20:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:20:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:20:26 INFO - Running setup.py install for mozleak 12:20:26 INFO - Running setup.py install for mozlog 12:20:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:20:26 INFO - Running setup.py install for moznetwork 12:20:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:20:27 INFO - Running setup.py install for mozprocess 12:20:27 INFO - Running setup.py install for mozprofile 12:20:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:20:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:20:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:20:27 INFO - Running setup.py install for mozrunner 12:20:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:20:27 INFO - Running setup.py install for mozscreenshot 12:20:27 INFO - Running setup.py install for moztest 12:20:27 INFO - Running setup.py install for mozversion 12:20:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:20:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:20:28 INFO - Cleaning up... 12:20:28 INFO - Return code: 0 12:20:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:28 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 0x106477a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10650c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddeacc5710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063dbd30>, '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 0x106140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d3b20>, '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.JL9N3pTKxO/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.eqrkFM6L98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 12:20:28 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 12:20:28 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 12:20:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JL9N3pTKxO/Render', 12:20:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:28 INFO - 'HOME': '/Users/cltbld', 12:20:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:28 INFO - 'LOGNAME': 'cltbld', 12:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:28 INFO - 'MOZ_NO_REMOTE': '1', 12:20:28 INFO - 'NO_EM_RESTART': '1', 12:20:28 INFO - 'PAGER': '/bin/cat', 12:20:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:28 INFO - 'PWD': '/builds/slave/test', 12:20:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:28 INFO - 'SHELL': '/bin/bash', 12:20:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqrkFM6L98/Listeners', 12:20:28 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 12:20:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:28 INFO - 'USER': 'cltbld', 12:20:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:28 INFO - 'XPC_FLAGS': '0x0', 12:20:28 INFO - 'XPC_SERVICE_NAME': '0', 12:20:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:20:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-EMysEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:20:28 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)) 12:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:20:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-sJYcV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:20:28 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)) 12:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:20:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Aot3dE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:20:28 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)) 12:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:20:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-M5m5qn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:20:28 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)) 12:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:20:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-jegq3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:20:28 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)) 12:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:20:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-96VHvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:20:28 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)) 12:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:20:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-eBus0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:20:29 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)) 12:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:20:29 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-h6tG7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:20:29 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)) 12:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:20:29 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-HUPeAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:20:29 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)) 12:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:20:29 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-OzkDfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:20:29 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)) 12:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:20:29 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-FAwd3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:20:29 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)) 12:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:20:29 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-f3PDDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:20:29 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)) 12:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:20:29 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-lwIK8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:20:29 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)) 12:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:20:29 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Bas7Xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:20:29 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)) 12:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:20:29 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-b1USdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:20:29 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)) 12:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:20:29 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-sN7FXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:20:30 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)) 12:20:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:20:30 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Drz28I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:20:30 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)) 12:20:30 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)) 12:20:30 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)) 12:20:30 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)) 12:20:30 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)) 12:20:30 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)) 12:20:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:20:30 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 12:20:30 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 12:20:30 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 12:20:30 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 12:20:30 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 12:20:30 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 12:20:32 INFO - Downloading blessings-1.6.tar.gz 12:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:20:33 INFO - Installing collected packages: blessings 12:20:33 INFO - Running setup.py install for blessings 12:20:33 INFO - Successfully installed blessings 12:20:33 INFO - Cleaning up... 12:20:33 INFO - Return code: 0 12:20:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:20:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:20:33 INFO - Reading from file tmpfile_stdout 12:20:33 INFO - Current package versions: 12:20:33 INFO - blessings == 1.6 12:20:33 INFO - blobuploader == 1.2.4 12:20:33 INFO - docopt == 0.6.1 12:20:33 INFO - functools32 == 3.2.3-2 12:20:33 INFO - jsonschema == 2.5.1 12:20:33 INFO - manifestparser == 1.1 12:20:33 INFO - mozInstall == 1.12 12:20:33 INFO - mozcrash == 0.17 12:20:33 INFO - mozdebug == 0.1 12:20:33 INFO - mozdevice == 0.48 12:20:33 INFO - mozfile == 1.2 12:20:33 INFO - mozhttpd == 0.7 12:20:33 INFO - mozinfo == 0.9 12:20:33 INFO - mozleak == 0.1 12:20:33 INFO - mozlog == 3.3 12:20:33 INFO - moznetwork == 0.27 12:20:33 INFO - mozprocess == 0.23 12:20:33 INFO - mozprofile == 0.28 12:20:33 INFO - mozrunner == 6.12 12:20:33 INFO - mozscreenshot == 0.1 12:20:33 INFO - mozsystemmonitor == 0.3 12:20:33 INFO - moztest == 0.7 12:20:33 INFO - mozversion == 1.4 12:20:33 INFO - psutil == 3.1.1 12:20:33 INFO - requests == 1.2.3 12:20:33 INFO - wsgiref == 0.1.2 12:20:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:20:33 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')]} 12:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:33 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')]} 12:20:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:33 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 0x106477a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10650c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddeacc5710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063dbd30>, '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 0x106140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d3b20>, '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.JL9N3pTKxO/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.eqrkFM6L98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 12:20:33 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 12:20:33 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 12:20:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JL9N3pTKxO/Render', 12:20:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:33 INFO - 'HOME': '/Users/cltbld', 12:20:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:33 INFO - 'LOGNAME': 'cltbld', 12:20:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:33 INFO - 'MOZ_NO_REMOTE': '1', 12:20:33 INFO - 'NO_EM_RESTART': '1', 12:20:33 INFO - 'PAGER': '/bin/cat', 12:20:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:33 INFO - 'PWD': '/builds/slave/test', 12:20:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:33 INFO - 'SHELL': '/bin/bash', 12:20:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqrkFM6L98/Listeners', 12:20:33 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 12:20:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:33 INFO - 'USER': 'cltbld', 12:20:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:33 INFO - 'XPC_FLAGS': '0x0', 12:20:33 INFO - 'XPC_SERVICE_NAME': '0', 12:20:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:34 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)) 12:20:34 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)) 12:20:34 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)) 12:20:34 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)) 12:20:34 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)) 12:20:34 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)) 12:20:34 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)) 12:20:34 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)) 12:20:34 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)) 12:20:34 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)) 12:20:34 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)) 12:20:34 INFO - Cleaning up... 12:20:34 INFO - Return code: 0 12:20:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:34 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 0x106477a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10650c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddeacc5710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063dbd30>, '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 0x106140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d3b20>, '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.JL9N3pTKxO/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.eqrkFM6L98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 12:20:34 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 12:20:34 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 12:20:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JL9N3pTKxO/Render', 12:20:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:34 INFO - 'HOME': '/Users/cltbld', 12:20:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:34 INFO - 'LOGNAME': 'cltbld', 12:20:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:34 INFO - 'MOZ_NO_REMOTE': '1', 12:20:34 INFO - 'NO_EM_RESTART': '1', 12:20:34 INFO - 'PAGER': '/bin/cat', 12:20:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:34 INFO - 'PWD': '/builds/slave/test', 12:20:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:34 INFO - 'SHELL': '/bin/bash', 12:20:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqrkFM6L98/Listeners', 12:20:34 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 12:20:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:34 INFO - 'USER': 'cltbld', 12:20:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:34 INFO - 'XPC_FLAGS': '0x0', 12:20:34 INFO - 'XPC_SERVICE_NAME': '0', 12:20:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:20:34 INFO - Cleaning up... 12:20:34 INFO - Return code: 0 12:20:34 INFO - Running post-action listener: _resource_record_post_action 12:20:34 INFO - Running post-action listener: _start_resource_monitoring 12:20:34 INFO - Starting resource monitoring. 12:20:34 INFO - [mozharness: 2016-08-19 19:20:34.473061Z] Finished create-virtualenv step (success) 12:20:34 INFO - [mozharness: 2016-08-19 19:20:34.474047Z] Running install step. 12:20:34 INFO - Running pre-action listener: _resource_record_pre_action 12:20:34 INFO - Running main action method: install 12:20:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:20:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:20:34 INFO - Reading from file tmpfile_stdout 12:20:34 INFO - Detecting whether we're running mozinstall >=1.0... 12:20:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:20:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:20:34 INFO - Reading from file tmpfile_stdout 12:20:34 INFO - Output received: 12:20:34 INFO - Usage: mozinstall [options] installer 12:20:34 INFO - Options: 12:20:34 INFO - -h, --help show this help message and exit 12:20:34 INFO - -d DEST, --destination=DEST 12:20:34 INFO - Directory to install application into. [default: 12:20:34 INFO - "/builds/slave/test"] 12:20:34 INFO - --app=APP Application being installed. [default: firefox] 12:20:34 INFO - mkdir: /builds/slave/test/build/application 12:20:34 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'] 12:20:34 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 12:21:08 INFO - Reading from file tmpfile_stdout 12:21:08 INFO - Output received: 12:21:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:21:08 INFO - Running post-action listener: _resource_record_post_action 12:21:08 INFO - [mozharness: 2016-08-19 19:21:08.980150Z] Finished install step (success) 12:21:08 INFO - [mozharness: 2016-08-19 19:21:08.980301Z] Running run-tests step. 12:21:08 INFO - Running pre-action listener: _resource_record_pre_action 12:21:08 INFO - Running main action method: run_tests 12:21:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:21:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:21:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:21:08 INFO - 2016-08-19 12:21:08.995 screenresolution[1627:7380] starting screenresolution argv=screenresolution get 12:21:08 INFO - 2016-08-19 12:21:08.997 screenresolution[1627:7380] Display 0: 1600x1200x32@60 12:21:09 INFO - 2016-08-19 12:21:09.003 screenresolution[1628:7383] starting screenresolution argv=screenresolution list 12:21:09 INFO - Available Modes on Display 0 12:21:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:21:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:21:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:21:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:21:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:21:09 INFO - Intel Iris: 12:21:09 INFO - Chipset Model: Intel Iris 12:21:09 INFO - Type: GPU 12:21:09 INFO - Bus: Built-In 12:21:09 INFO - VRAM (Dynamic, Max): 1536 MB 12:21:09 INFO - Vendor: Intel (0x8086) 12:21:09 INFO - Device ID: 0x0a2e 12:21:09 INFO - Revision ID: 0x0009 12:21:09 INFO - Displays: 12:21:09 INFO - Display: 12:21:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:21:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:21:09 INFO - Display Serial Number: 1600x1200 60 12:21:09 INFO - Main Display: Yes 12:21:09 INFO - Mirror: Off 12:21:09 INFO - Online: Yes 12:21:09 INFO - Rotation: Supported 12:21:09 INFO - Return code: 0 12:21:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:21:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:21:09 INFO - Python 2.7.3 12:21:09 INFO - Return code: 0 12:21:09 INFO - grabbing minidump binary from tooltool 12:21: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')]} 12:21:09 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 0x1063dbd30>, '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 0x106140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063d3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:21:09 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 12:21:09 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 12:21:09 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 12:21:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:21:09 INFO - Return code: 0 12:21:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:21:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:21:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:21:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:21:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:21:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 12:21:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0321 --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 12:21:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JL9N3pTKxO/Render', 12:21:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:09 INFO - 'HOME': '/Users/cltbld', 12:21:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:09 INFO - 'LOGNAME': 'cltbld', 12:21:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:21:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:21:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:09 INFO - 'MOZ_NO_REMOTE': '1', 12:21:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:21:09 INFO - 'NO_EM_RESTART': '1', 12:21:09 INFO - 'PAGER': '/bin/cat', 12:21:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:09 INFO - 'PWD': '/builds/slave/test', 12:21:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:21:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:09 INFO - 'SHELL': '/bin/bash', 12:21:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqrkFM6L98/Listeners', 12:21:09 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 12:21:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:09 INFO - 'USER': 'cltbld', 12:21:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:09 INFO - 'XPC_FLAGS': '0x0', 12:21:09 INFO - 'XPC_SERVICE_NAME': '0', 12:21:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 12:21:09 INFO - mozversion application_buildid: 20160819095522 12:21:09 INFO - mozversion application_changeset: 8ef9629d8f90d6507b1bad01146b14101de79174 12:21:09 INFO - mozversion application_display_name: Nightly 12:21:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:21:09 INFO - mozversion application_name: Firefox 12:21:09 INFO - mozversion application_remotingname: firefox 12:21:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:21:09 INFO - mozversion application_vendor: Mozilla 12:21:09 INFO - mozversion application_version: 51.0a1 12:21:09 INFO - mozversion platform_buildid: 20160819095522 12:21:09 INFO - mozversion platform_changeset: 8ef9629d8f90d6507b1bad01146b14101de79174 12:21:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:21:09 INFO - mozversion platform_version: 51.0a1 12:21:09 INFO - using testdate: 1471634469 12:21:09 INFO - actual date: 1471634469 12:21:09 INFO - starting webserver on 'localhost:49263' 12:21:09 INFO - SUITE-START | Running 2 tests 12:21:09 INFO - TEST-START | dromaeo_css 12:21:09 INFO - operating with platform_type : mac_ 12:21:09 INFO - Initialising browser for dromaeo_css test... 12:21:09 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpQilwq7/profile http://localhost:49263/getInfo.html) 12:21:11 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 12:21:11 INFO - PROCESS | 1638 | colorDepth:24 12:21:11 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 12:21:11 INFO - PROCESS | 1638 | __metrics 12:21:12 INFO - PROCESS | 1638 | 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" 12:21:12 INFO - PROCESS | 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:21:12 INFO - TEST-INFO | 1638: exit 0 12:21:12 INFO - Browser initialized. 12:21:12 INFO - Running cycle 1/1 for dromaeo_css test... 12:21:12 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpQilwq7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:21:24 INFO - PROCESS | 1640 | RSS: Main: 228241408 12:21:24 INFO - PROCESS | 1640 | 12:22:15 INFO - PROCESS | 1640 | 1471634535577 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] 12:24:27 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49263/tests/dromaeo/cssquery-ext.html) 12:24:28 INFO - PROCESS | 1640 | RSS: Main: 344764416 12:24:28 INFO - PROCESS | 1640 | 12:27:31 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-ext.html (next: http://localhost:49263/tests/dromaeo/cssquery-jquery.html) 12:27:32 INFO - PROCESS | 1640 | RSS: Main: 395911168 12:27:32 INFO - PROCESS | 1640 | 12:30:34 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49263/tests/dromaeo/cssquery-mootools.html) 12:30:35 INFO - PROCESS | 1640 | RSS: Main: 343334912 12:30:35 INFO - PROCESS | 1640 | 12:33:37 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49263/tests/dromaeo/cssquery-prototype.html) 12:33:38 INFO - PROCESS | 1640 | RSS: Main: 342360064 12:33:38 INFO - PROCESS | 1640 | 12:36:41 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49263/tests/dromaeo/cssquery-yui.html) 12:36:42 INFO - PROCESS | 1640 | RSS: Main: 334311424 12:36:42 INFO - PROCESS | 1640 | 12:39:45 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-yui.html (next: http://localhost:49263/tests/dromaeo/cssquery-dojo.html) 12:39:45 INFO - PROCESS | 1640 | RSS: Main: 467300352 12:39:45 INFO - PROCESS | 1640 | 12:39:45 INFO - PROCESS | 1640 | __start_tp_report 12:39:45 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 12:39:45 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 12:39:45 INFO - PROCESS | 1640 | |i|pagename|runs| 12:39:45 INFO - PROCESS | 1640 | |0;dojo.html;3358.781679190853;3361.8151001694896;3361.8991430257092;3390.3388839176355;3429.4512877939533;5579.05156123459;5674.177244299584;5678.034734095203;5750.971245143779;5776.797812076578;5630.436956304367;5660.433956604337;5738.254026976487;5831.979403604363;5889.440503152194;5121.28302037715;5164.483551644843;5201.713905735183;5207.505286997748;5296.8072879610345;5339.6262261641705;5364.329458817648;5375.354957405117;5378.91931621026;5387.542058924996;5120.15517439622;5163.380394352665;5177.715225662587;5235.240890070919;5269.420363759983;5230.660007099526;5282.286891269706;5282.867928301785;5289.471052894718;5299.920501192485;5217.139172036609;5231.607629427808;5260.263563101169;5270.578353731692;5276.657017293864;5606.691631960241;5693.4875861172695;5746.109353050284;5798.7390567424145;5816.331121920949;6271.8745625087695;6301.747930082791;6310.1481300024825;6310.9684451577905;6339.936600633981;6320.873582528369;6359.20509936258;6370.681465926685;6376.9681151594405;6382.20222472191;5653.406392328788;5686.715664216773;5755.079187330007;5756.280464941882;5860.589758716891;6305.369463053749;6308.432241098323;6335.873282534323;6344.333844946306;6348.587341822767;6126.172966649446;6133.846653833601;6144.815655530341;6147.108669243024;6149;6087.512998960073;6195.256569211723;6236.469900058546;6250.9998400255745;6265.4361107500545;6218.844528886814;6274.1843560336865;6276.905846412307;6290.528210384239;6294.559380843389;6225.688715564203;6306.621602703853;6316.8420789479715;6327.3988971047675;6340.270868850049;4790.640701947368;4871.439784424766;4873;4894.461609222983;4940.728259945666;4365.585269399402;4410.404595379647;4427.69006169565;4453.75504347264;4456.665750068758;5861.70691465425;5873.794417195463;5882.470577647947;5898.14476900847;5900.970495147497;6169.413905678954;6236.314005459396;6309.968450157719;6315.526335524854;6318.589291696025;4967.5032496750755;5818.33089194748;5915.881682366328;5916.645001299936;5937.554683398763;5423.755930983115;5446.673199608036;5478.835634930959;5491.917621235684;5501.4223506532035;5840.970795145997;6208.0998255253635;6325.810225693144;6389.808305750835;6395.072714456378;5937;6279.183706118175;6303.149074874833;6315.4947604192475;6329.8734025320155;5460.972695136499;6396.42432181106;6397.976323788172;6398.616083035033;6398.872022559616;5853.8536536585225;5893.5874488785375;6041;6277.372262773686;6285.2457705075685;5985.760569577168;6012.699365031818;6119.296280927751;6121.296050954197;6127.571070025056;5876.265466816648;5931.377205393372;5935.465808077293;5959.7020148991505;5970.9701451492465;5901.940980590139;5924.822255332347;5947.226860508106;5963.463288304073;6022.518198544019;5891.086881533369;6004.729787159676;6011.158437818796;6032.4570788629235;6042.607230530001;6152.538559607958;6170.259568851767;6170.5372097092;6177.104319873503;6186.505079593711;5735.971320143372;5811.53507719373;5957;5972.462478376793;5989.940100598938 12:39:45 INFO - PROCESS | 1640 | |1;ext.html;478.7199488299345;483.65902039062445;517.8291163915908;529.5075579710868;535.9222912677661;30163.944261950837;39806.60193398065;40912.59087409125;41031.58968410315;41089;42169;42442.787786061024;42460;42551.361729574004;42583;9857.408555486676;10027.699169024905;10031.147352475049;10048.246381521378;10055.748606284827;9950.65172718955;10066.848997265048;10091.697249082503;10107.54516046776;10114.494275286243;10046.397216167017;10071.798564028752;10114.393136411838;10139.34094283873;10142.49287535624;9989.350692204984;10047.598096076148;10080.64717734883;10099.899001010042;10115.342502737298;9895.8515622265;9983.75040623983;9989.400635961865;9991.75020624483;9999.400035997864;40115.39826902599;40365.394519082234;40380.99047523835;40434.797826010974;40546.728925239826;3025.485667436541;3428.468587368962;3447.896563103086;3452.378571857065;3457.913552161191;31170.532442013387;31487.527687084712;31534.36931261362;31753.682463175533;32059.35881282361;2792.7506052786325;3117.547955546433;3174.5238214267815;3185.378851124032;3261.6249131349723;517956;519405;522104;522760.9999999962;530197;56382.43617563854;56638;56669.632947716535;56746.43253567412;56834.7158264206;34385.968420947414;34892.65107348894;35155;60746.69626651838;61094.694526527084;16340.428085016967;16872.83127168737;16873;16883.662326753398;16902;12883.162594431333;12993.610191694264;13003.544875929298;13006.739865202831;13010.414531346112;18782.624347512974;18815.623687526448;18903.960282184336;18945.526361841064;18945.621087578173;7680.807979800438;7748;7775.49459285156;7805.960970195226;7857;849.099954048698;923.7736754495191;957.4494665567327;1037.1443559063803;1094.698957786615;29915.700842991726;30274.394512109633;30574.69425305763;31152;31761;978.6966040527459;1024.2267088348249;1033.4316126130598;1050.702382557541;1066.818640831045;14575.271236438135;15553.688926221412;15762.842371576138;15821.762673559906;15995.840041599435;732712.383009358;739960;742867;743098;743481;58760.41239587549;59253.703731481066;59258.70370648119;59265.703671481366;59391;1235.5799028330412;1361.4077876123918;1372.7803551431923;1376.078027721449;1408.091780801357;30145.576707726763;31254.062378128692;31550.211244718146;31822.681773181972;32019.6798032026;48906.75546622386;49451.258231125845;49453.01093978172;49475;49507.25739113988;555055;557166;557498;558321;558340;23151;25845;26297.553634550284;26383.472330552897;26688.599671004562;47218.76390618025;47720.522794771605;48281.27578086261;48410;48846;38275.42586861143;38722.03194920037;38896.80551597337;40075.39886901757;40534;35073.08976743207;37426.8128659355;39584.20831583295;40014.39978400384;40603.390949135195;37370;38446.80776596099;38572.421413678254;39400.802995984835;39441;31096.378072438878;32557.837210813796;33962.66037339595;34136;34366;22232.666510002688;25076.498470030863;25100.24699259025;25233.747662523874;25662.486750265263 12:39:45 INFO - PROCESS | 1640 | |2;jquery.html;352.5628221005953;371.4113130687858;373.78694144337726;383.52251446948543;405.1592944639871;15729.449469268578;16099.631531319837;20921.790782092176;21127.577448451022;21297.935103244814;21021.36935891922;21288.36134915951;21764.12943482259;21886.90565471724;22127.778722212774;5462.617616766828;5880.206172166767;5976.731047102869;6065.757369705207;6152.661603611799;4149.854755083573;5960.821175364746;6058.333583305833;6077.11881777142;6408.647524386157;6651.733930642768;6657.966710166466;6692.63190524521;6699.263081061104;6773.8983915241315;5332.840014799543;6661.233958094833;6722.226943901465;6722.83192920176;6741.359570840811;5709.942900571024;5815.371695925148;5916.822495325147;5921.881562368729;5934.080217566284;12719.17325373857;13817.447302107903;13829.79255311161;13833.308334583331;17656.646867062715;2153.3432303147533;2173.5870184665023;2198.2416066457045;2425.50277193175;2905.680375158731;10045.949770251174;10667.466626668713;10750.569977200903;11350.886491135065;16513.587160321087;1863.1150203653292;1866.6360059788346;1887.5469887227105;1930.7490026296632;1973.516488460322;120549;128908;129191;130563;130757.99999999905;11190.440477976232;11353.716157095974;11441.913018263418;11540.365279909518;11723.296602203895;14467.059641123293;14474.565763027125;14475.059121157092;14479.276036198147;14489.710205796036;8361;8556.871646925301;8558.52928088961;8563.785905352292;8632.223099921037;8484.53335066565;8498.107698691672;8519.701810436729;8655.870161947576;8669.176428239307;4489.685721999429;4503.166914120898;4511.819527218874;4517.186906356887;4599.310103484504;1661.6593598312318;1717.6221231329087;1813.963720725578;1928.7106933959737;1938.0987840654163;758.4122305213504;856.6530555125184;899.3344924755764;950.1496160935924;968.0831952733627;20460.283890064075;20954.895225523775;21583;21628.56742865134;21658.458538536663;843.3295530053622;921.6681994481982;937.2501998401252;959.64972784933;1012.367270455965;10090.752372837731;10473.581056757797;11225.887741122648;11396.264896424798;11427.971482566609;616010;630252;634556;635942;642274;113433;143450.2827485856;144030;144395;146475;975.11264749078;983.6458874804921;998.8951160128228;1001.0990108902047;1003.8193125237527;18894.905525472284;19188.232470701016;19251.614967700287;19306.93811840362;19331;56563.71718141383;58225;58354.7082264586;58788.412115878295;59316.703416484364;78447.60776196311;81091.59454202691;82256.58871705603;83139;83836;6092.573519853568;7932.16712245206;8010;8217.465864718773;8451.070382257823;11393.715157121138;12944.352782360656;13018.934905325414;13335.466581336637;13347.733045338844;11304.773904521699;11438.656840294805;11465.312081274817;11580.073594112619;11636.825447618123;9846.507674616383;10737.14102871784;11391;11442.539853931445;11562.364069976233;9721.659741909;10962.56149754001;11079.501422436171;11441.48513316886;11502.827457587975;7950;8073.636686349246;8168.346532277523;8371.037330707046;8387.538685372363;10468.110210632181;10884.346939183675;11191.552337906392;11241.381724005258;11248.381339026431 12:39:45 INFO - PROCESS | 1640 | |3;mootools.html;1366.6924941888083;1537.7923980262667;1595.8803089768269;1750.2911320915025;1872.5786697992953;7300;7836.529808211504;8310.085890552034;8310.626021829017;8324.209200125993;7836.02049743782;8384.119667434923;8776.868346974801;8889.777755556115;8897.777555561099;2389.2593296078226;2414.9396265093424;2418.2503423938633;2420.5279970405777;2430.4652976345265;2088.7702352741185;2354.470244195051;2403.194929698553;2404.038384646138;2409.6987876515436;2374.1334412939327;2394.580948334043;2401.8198635102435;2407.037185125946;2410.68661074061;2339.4385347516472;2366.4557151855083;2368.2066507719933;2368.3842201027855;2386.749391313922;2338.5206032763244;2362.4802543440414;2368.7394386617457;2372.1223147435385;2376.9881150594138;8820.029796722356;8872.55637218143;8924.929008518955;8934.240589549896;8998.775030624221;5211.5049070338255;5253.947460525423;5386.649867758583;5392.2181283714035;5394.595405344576;3642.453631955201;3722.5346831646043;3731.925361492782;3732.9440058399146;3736.7757934524016;1105.6682995101437;1112.7440688641657;1135.3698697223037;1136.0911270983263;1142.1719253541116;11664.183507154503;11702.76594468106;11840.585579504763;11840.763184736257;11880.465379057963;4492.707973981681;4531;4667.929981050287;4671.369365135731;4720.433547974265;9263.212626926786;9271;9276;9276.72169834906;9276.814463710689;5307.893842123213;5308.946910530923;5312.2297266896085;5323.733813309319;5336.573074154136;4446.555344465528;4629.768511574475;4742.596879265301;4882.804687812448;4968.652194346361;3187.713105820441;3273.4271502486968;3761.269815884724;3845.442410850411;3872.012636777604;1171.654361963223;1173;1177.5996161305197;1183.1422218891407;1188.5483516263937;837.3092198935902;897.9506127163069;903.1284810158229;927.9396839205565;934.5093825741404;5731.455511726379;5800.854978625568;6058.848818724428;6148;6169.96915015422;432.0408692702196;435.6427729262038;444.38452742951245;448.96857219994286;458.33999041380974;3777.792221427848;3910.608939106067;3942.9014274643364;4028.879133625996;4032.4354590357375;10816.351018938889;11104.000639942444;11205.103591712643;11210.21528493014;11219.887801122048;11102.167337449724;11103.666889993314;11119.666410007712;11135.33188008722;11169.329840209613;6826.692798824065;8312.667493300321;8396.160383961555;8402.579871006425;8403.033651130198;5195.480451954774;5405.189221616632;6106.9083963739695;6110.144579758926;6133.141360209664;9851.655192068243;10062.39625622465;10122.595096196364;10212.285140040125;10378.636747713794;8533.615987280464;10096.29325947206;10348.327358721659;10523.894761052597;10578.682639520826;7120.465965052538;7218.530795498275;7358.411327093712;7744.225577442211;7864;8310.70912518059;8540.530270835165;8638.740837774876;8720.781980450285;8744.475331480131;7526.360259378014;7603.2776886195725;7672.8849067265155;7802.414818888493;7836.764897053097;7438.367738742051;7505;7521.172671006294;7535.73624923125;7575.659095340833;6041.426064523863;7097.503174777716;7098.893516597152;7099.290070992859;7107.680154392961;4924.8768780780765;4999.427242346174;5056.140456122542;5097.796088156432;5201.661891977009;4879.268109783561;4959.479254678351;4986.576141028053;5034.798608055637;5265.447128051653 12:39:45 INFO - PROCESS | 1640 | |4;prototype.html;297.6116168400238;303.675067677585;313.5046626330398;316.8352456722506;317.26078237706145;5237.84286471406;5247.422783493817;5257.658252213603;5261.921071183935;5270.341207349082;4929.926051109236;4987.675801072928;5085.821996230133;5176.922346164811;5196.128883981439;3405.9318813623713;3689.0777305673582;4014.4179094031347;4021.115354621993;4108.28105081611;3458.844352004166;3911;3992.5408578013466;4061.1877624475073;4080.878128231905;3329.5671562696934;3618.7105031597407;3743.8128093595346;4016.5581786003518;4056.8377264909514;3543.167355671415;3918.45141680165;3937.5668676445566;3947.743396679221;3954.3277642800504;3251.6910893465083;3385.2044769479153;3428.777129486575;3433.364827506919;3443.9655603443894;4205.053862880827;4501.006830457742;5158.896822063575;5170.482951704799;5187.299714538564;3964.4251583520227;4462.7991740371435;4505.07645932583;4559.3844830948065;4568.406107206075;4738.526147385269;5506.201600767907;5512.558995280408;5522.282103326582;5547.389787123413;3182.8249446280447;3768.9434658480145;3806.600306967784;3822.2737679840743;3838;5136.484737002594;5287.735613219362;5441.374241962186;5752.511036561939;5815.73829177688;3075.615548056493;3807.5050243468727;3818.560865500503;3845.115623406632;3853.3256680081436;3707.7589956652732;3910.3156947534067;3915.040815000343;3922.2155568886337;3924.5486769021927;4344.761038142932;4497.392851965008;4588.518205588365;4592.517785632527;4593.058423023339;5531.474509921551;5534.584906132056;5539.390667026624;5543;5557.582816381878;1152.6053475294987;1320.5972178485654;1359.3950691942189;1365.6244532753374;1367.4325155060737;844.7381311793364;866.6923242249037;886.3795343259744;892.4823602310644;894.9194572488507;4402.456022901595;4480.663950203748;4842.070547318892;5013.248012798036;5099.184130539096;5266.2890509781455;5366.209571023046;5444.386694386717;5475.780968761284;5775;5221.232746644285;5221.947780522146;5226.425093239741;5233.633645644844;5264.341957255343;2767.4465106978687;2859.6711378191776;2987.9402411951637;3002.8798848045835;3022.727954484063;5554.611177217556;5910.586258961831;5919.911201331984;5932.436418540232;5998.460138587461;4653.930191047137;5385.569154467633;5803.129530570448;5852.502537284378;5963.493103086286;5229;5353.170258609922;5594.104943209067;5927.1405646181065;5936.673482958392;4936.506349365179;5249.084084309124;5709.200711900255;5718.3423906249645;5731.168980497888;4990.850274491771;5129.74321291286;5205.58355331565;5272.367315922113;5277.234800953764;5451.7001564912725;5875.882482350244;5888.4700376966275;5893.263342082239;5944.613600115978;2914.21316244617;3105.1771280611106;3262.4127657021013;3284.5712115229953;3312.8343582821244;4148.917021659489;4250.638695710899;4477.287975058221;4624.993754059888;4714.457837348748;4541.636669066533;4576.597286424653;5438.265834112344;5440.755166017618;5448;4524.778309856386;4700.989287303246;5217.991073436679;5565.8051968182535;5566.972165139148;4712;5155.252488389237;5226.425093239664;5333.386660534088;5408.07864842991;4491.393661855608;4632.606228470483;4686.531346865286;4783.354247176587;5255.763490642854;4839.395075615548;5406.297181366397;5416.106342453451;5421.484958928895;5483 12:39:45 INFO - PROCESS | 1640 | |5;yui.html;728.9890651640226;730.3135053050132;776.7630872583863;812.5206128384252;836.0803116571772;15379.077255364686;16445;16481.917590412046;16489.91755041226;16527.50417487477;14956;15125.773113403307;15142.318595663193;15158.166300853449;15257.38970441181;680.4998326230217;707.9150501939748;833.3583140981441;847.656699036891;862.1766213266347;865.1478293880523;869.443556124081;870.0168809245544;870.6125774030559;874.5933141089392;919.6045700348848;921.2767977137948;921.9585118669643;922.6263363337858;924.2790623313792;920.1120918313826;920.2913756407596;921.7695576105973;922.4834092907993;923.7598224461655;535.9705216213107;667.2926697700453;689.7137687859582;691.6645426967916;691.9065926099962;14873.47942822007;14911.32899019536;14912.254387280593;14929.328180231807;14930.47743328989;14080.366383512766;14187.14877107377;14222.857771422257;14245.857541424557;14355.71288574233;12895.871041289562;12924.806127908088;12995.155314904501;13137.27744974026;13208.207507549578;4602.194615942197;4709.387779588666;4736.005438857844;4767.046590681877;4861.683990540639;103686;124240;124314;130117.34941325328;130653.34673326573;10423.478826058667;11190.66428007161;11300.660980170607;11657.88342116568;11685.941570292094;21097.261595844073;21639.459013524473;21997.340079797632;22009;22015.889920550293;7764.728234511765;7784.766457006299;7803.765887023398;7812.492188007761;7942.761717148494;4426.44669416323;4461.263891457873;4501.504834468205;4652.441706995182;4666.62666986647;3340.1315657929113;3469.548958635361;3596.496490491333;3599.6580324869096;3611.368010598135;562.572444941839;682.3313153109881;697.5500801982693;718.7493760856073;720.5640587444625;576.9423057694281;664.1996394344803;671.6809515480107;674.9358810913054;688.084847153285;8458.830823383498;9666.226701863834;9736;9740.902590974141;9774.169195618451;526.5419085395654;613.9693015349214;647.1328419917334;675.9324067593201;698.2528694297054;4213.810378532973;4496.437945256843;4676.953230467651;4690.6012988895645;4810.807567697323;124863;159638;161117;161659;168316;124469;126486;133691;135484.70956420954;148378;114914;118563.22155167679;119164.2543234687;121982.390088049;122156;10131.290809643255;10213.897861021294;10261.794764104825;10326.793464130526;10397.584096636352;64323;68501.65749171222;69697.65151174212;69714.30285697078;70312;68036.6598167006;74795;83928.58035709782;84967;88850.11149888676;14347.92826035863;14356.35396407185;14457.132572045712;14601.269936503344;15025.474108406153;14439.92780036093;15245;15415.537533874001;15558.222088895736;16073.276702548179;10290.382577045102;13932;14275.42898284057;14519.782203266748;14658.560243192722;13974.790378144133;14269.072510286798;14379.424823006962;14710.591286565665;14802.111873287637;13725.794113088512;13855.515056972958;13914.791278130633;13961.720765584834;14084;9167.7668451862;11464.541418343173;11504.756572468288;11614;12228.08289378318;10073.445960472245;10273.640422585173;11586.536538538701;11889.405529723652;12071.336076515525 12:39:45 INFO - PROCESS | 1640 | __end_tp_report 12:39:45 INFO - PROCESS | 1640 | __start_cc_report 12:39:45 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1295 12:39:45 INFO - PROCESS | 1640 | __end_cc_report 12:39:45 INFO - PROCESS | 1640 | __startTimestamp1471635585958__endTimestamp 12:39:45 INFO - PROCESS | 1640 | 12:39:45 INFO - PROCESS | 1640 | ------- Summary: start ------- 12:39:45 INFO - PROCESS | 1640 | Number of tests: 6 12:39:45 INFO - PROCESS | 1640 | 12:39:45 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5754.61 Median:5916.65 stddev:638.51 (10.8%) stddev-sans-first:612.18 12:39:45 INFO - PROCESS | 1640 | Values: 3358.8 3361.8 3361.9 3390.3 3429.5 5579.1 5674.2 5678.0 5751.0 5776.8 5630.4 5660.4 5738.3 5832.0 5889.4 5121.3 5164.5 5201.7 5207.5 5296.8 5339.6 5364.3 5375.4 5378.9 5387.5 5120.2 5163.4 5177.7 5235.2 5269.4 5230.7 5282.3 5282.9 5289.5 5299.9 5217.1 5231.6 5260.3 5270.6 5276.7 5606.7 5693.5 5746.1 5798.7 5816.3 6271.9 6301.7 6310.1 6311.0 6339.9 6320.9 6359.2 6370.7 6377.0 6382.2 5653.4 5686.7 5755.1 5756.3 5860.6 6305.4 6308.4 6335.9 6344.3 6348.6 6126.2 6133.8 6144.8 6147.1 6149.0 6087.5 6195.3 6236.5 6251.0 6265.4 6218.8 6274.2 6276.9 6290.5 6294.6 6225.7 6306.6 6316.8 6327.4 6340.3 4790.6 4871.4 4873.0 4894.5 4940.7 4365.6 4410.4 4427.7 4453.8 4456.7 5861.7 5873.8 5882.5 5898.1 5901.0 6169.4 6236.3 6310.0 6315.5 6318.6 4967.5 5818.3 5915.9 5916.6 5937.6 5423.8 5446.7 5478.8 5491.9 5501.4 5841.0 6208.1 6325.8 6389.8 6395.1 5937.0 6279.2 6303.1 6315.5 6329.9 5461.0 6396.4 6398.0 6398.6 6398.9 5853.9 5893.6 6041.0 6277.4 6285.2 5985.8 6012.7 6119.3 6121.3 6127.6 5876.3 5931.4 5935.5 5959.7 5971.0 5901.9 5924.8 5947.2 5963.5 6022.5 5891.1 6004.7 6011.2 6032.5 6042.6 6152.5 6170.3 6170.5 6177.1 6186.5 5736.0 5811.5 5957.0 5972.5 5989.9 12:39:45 INFO - PROCESS | 1640 | 12:39:45 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:72954.89 Median:34982.87 stddev:164629.79 (470.6%) stddev-sans-first:165001.68 12:39:45 INFO - PROCESS | 1640 | Values: 478.7 483.7 517.8 529.5 535.9 30163.9 39806.6 40912.6 41031.6 41089.0 42169.0 42442.8 42460.0 42551.4 42583.0 9857.4 10027.7 10031.1 10048.2 10055.7 9950.7 10066.8 10091.7 10107.5 10114.5 10046.4 10071.8 10114.4 10139.3 10142.5 9989.4 10047.6 10080.6 10099.9 10115.3 9895.9 9983.8 9989.4 9991.8 9999.4 40115.4 40365.4 40381.0 40434.8 40546.7 3025.5 3428.5 3447.9 3452.4 3457.9 31170.5 31487.5 31534.4 31753.7 32059.4 2792.8 3117.5 3174.5 3185.4 3261.6 517956.0 519405.0 522104.0 522761.0 530197.0 56382.4 56638.0 56669.6 56746.4 56834.7 34386.0 34892.7 35155.0 60746.7 61094.7 16340.4 16872.8 16873.0 16883.7 16902.0 12883.2 12993.6 13003.5 13006.7 13010.4 18782.6 18815.6 18904.0 18945.5 18945.6 7680.8 7748.0 7775.5 7806.0 7857.0 849.1 923.8 957.4 1037.1 1094.7 29915.7 30274.4 30574.7 31152.0 31761.0 978.7 1024.2 1033.4 1050.7 1066.8 14575.3 15553.7 15762.8 15821.8 15995.8 732712.4 739960.0 742867.0 743098.0 743481.0 58760.4 59253.7 59258.7 59265.7 59391.0 1235.6 1361.4 1372.8 1376.1 1408.1 30145.6 31254.1 31550.2 31822.7 32019.7 48906.8 49451.3 49453.0 49475.0 49507.3 555055.0 557166.0 557498.0 558321.0 558340.0 23151.0 25845.0 26297.6 26383.5 26688.6 47218.8 47720.5 48281.3 48410.0 48846.0 38275.4 38722.0 38896.8 40075.4 40534.0 35073.1 37426.8 39584.2 40014.4 40603.4 37370.0 38446.8 38572.4 39400.8 39441.0 31096.4 32557.8 33962.7 34136.0 34366.0 22232.7 25076.5 25100.2 25233.7 25662.5 12:39:45 INFO - PROCESS | 1640 | 12:39:45 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:36584.81 Median:21825.52 stddev:105766.85 (484.6%) stddev-sans-first:106028.56 12:39:45 INFO - PROCESS | 1640 | Values: 352.6 371.4 373.8 383.5 405.2 15729.4 16099.6 20921.8 21127.6 21297.9 21021.4 21288.4 21764.1 21886.9 22127.8 5462.6 5880.2 5976.7 6065.8 6152.7 4149.9 5960.8 6058.3 6077.1 6408.6 6651.7 6658.0 6692.6 6699.3 6773.9 5332.8 6661.2 6722.2 6722.8 6741.4 5709.9 5815.4 5916.8 5921.9 5934.1 12719.2 13817.4 13829.8 13833.3 17656.6 2153.3 2173.6 2198.2 2425.5 2905.7 10045.9 10667.5 10750.6 11350.9 16513.6 1863.1 1866.6 1887.5 1930.7 1973.5 120549.0 128908.0 129191.0 130563.0 130758.0 11190.4 11353.7 11441.9 11540.4 11723.3 14467.1 14474.6 14475.1 14479.3 14489.7 8361.0 8556.9 8558.5 8563.8 8632.2 8484.5 8498.1 8519.7 8655.9 8669.2 4489.7 4503.2 4511.8 4517.2 4599.3 1661.7 1717.6 1814.0 1928.7 1938.1 758.4 856.7 899.3 950.1 968.1 20460.3 20954.9 21583.0 21628.6 21658.5 843.3 921.7 937.3 959.6 1012.4 10090.8 10473.6 11225.9 11396.3 11428.0 616010.0 630252.0 634556.0 635942.0 642274.0 113433.0 143450.3 144030.0 144395.0 146475.0 975.1 983.6 998.9 1001.1 1003.8 18894.9 19188.2 19251.6 19306.9 19331.0 56563.7 58225.0 58354.7 58788.4 59316.7 78447.6 81091.6 82256.6 83139.0 83836.0 6092.6 7932.2 8010.0 8217.5 8451.1 11393.7 12944.4 13018.9 13335.5 13347.7 11304.8 11438.7 11465.3 11580.1 11636.8 9846.5 10737.1 11391.0 11442.5 11562.4 9721.7 10962.6 11079.5 11441.5 11502.8 7950.0 8073.6 8168.3 8371.0 8387.5 10468.1 10884.3 11191.6 11241.4 11248.4 12:39:45 INFO - PROCESS | 1640 | 12:39:45 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5704.74 Median:4810.93 stddev:3245.79 (67.5%) stddev-sans-first:3238.52 12:39:45 INFO - PROCESS | 1640 | Values: 1366.7 1537.8 1595.9 1750.3 1872.6 7300.0 7836.5 8310.1 8310.6 8324.2 7836.0 8384.1 8776.9 8889.8 8897.8 2389.3 2414.9 2418.3 2420.5 2430.5 2088.8 2354.5 2403.2 2404.0 2409.7 2374.1 2394.6 2401.8 2407.0 2410.7 2339.4 2366.5 2368.2 2368.4 2386.7 2338.5 2362.5 2368.7 2372.1 2377.0 8820.0 8872.6 8924.9 8934.2 8998.8 5211.5 5253.9 5386.6 5392.2 5394.6 3642.5 3722.5 3731.9 3732.9 3736.8 1105.7 1112.7 1135.4 1136.1 1142.2 11664.2 11702.8 11840.6 11840.8 11880.5 4492.7 4531.0 4667.9 4671.4 4720.4 9263.2 9271.0 9276.0 9276.7 9276.8 5307.9 5308.9 5312.2 5323.7 5336.6 4446.6 4629.8 4742.6 4882.8 4968.7 3187.7 3273.4 3761.3 3845.4 3872.0 1171.7 1173.0 1177.6 1183.1 1188.5 837.3 898.0 903.1 927.9 934.5 5731.5 5800.9 6058.8 6148.0 6170.0 432.0 435.6 444.4 449.0 458.3 3777.8 3910.6 3942.9 4028.9 4032.4 10816.4 11104.0 11205.1 11210.2 11219.9 11102.2 11103.7 11119.7 11135.3 11169.3 6826.7 8312.7 8396.2 8402.6 8403.0 5195.5 5405.2 6106.9 6110.1 6133.1 9851.7 10062.4 10122.6 10212.3 10378.6 8533.6 10096.3 10348.3 10523.9 10578.7 7120.5 7218.5 7358.4 7744.2 7864.0 8310.7 8540.5 8638.7 8720.8 8744.5 7526.4 7603.3 7672.9 7802.4 7836.8 7438.4 7505.0 7521.2 7535.7 7575.7 6041.4 7097.5 7098.9 7099.3 7107.7 4924.9 4999.4 5056.1 5097.8 5201.7 4879.3 4959.5 4986.6 5034.8 5265.4 12:39:45 INFO - PROCESS | 1640 | 12:39:45 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4386.66 Median:4840.73 stddev:1356.74 (28.0%) stddev-sans-first:1325.38 12:39:45 INFO - PROCESS | 1640 | Values: 297.6 303.7 313.5 316.8 317.3 5237.8 5247.4 5257.7 5261.9 5270.3 4929.9 4987.7 5085.8 5176.9 5196.1 3405.9 3689.1 4014.4 4021.1 4108.3 3458.8 3911.0 3992.5 4061.2 4080.9 3329.6 3618.7 3743.8 4016.6 4056.8 3543.2 3918.5 3937.6 3947.7 3954.3 3251.7 3385.2 3428.8 3433.4 3444.0 4205.1 4501.0 5158.9 5170.5 5187.3 3964.4 4462.8 4505.1 4559.4 4568.4 4738.5 5506.2 5512.6 5522.3 5547.4 3182.8 3768.9 3806.6 3822.3 3838.0 5136.5 5287.7 5441.4 5752.5 5815.7 3075.6 3807.5 3818.6 3845.1 3853.3 3707.8 3910.3 3915.0 3922.2 3924.5 4344.8 4497.4 4588.5 4592.5 4593.1 5531.5 5534.6 5539.4 5543.0 5557.6 1152.6 1320.6 1359.4 1365.6 1367.4 844.7 866.7 886.4 892.5 894.9 4402.5 4480.7 4842.1 5013.2 5099.2 5266.3 5366.2 5444.4 5475.8 5775.0 5221.2 5221.9 5226.4 5233.6 5264.3 2767.4 2859.7 2987.9 3002.9 3022.7 5554.6 5910.6 5919.9 5932.4 5998.5 4653.9 5385.6 5803.1 5852.5 5963.5 5229.0 5353.2 5594.1 5927.1 5936.7 4936.5 5249.1 5709.2 5718.3 5731.2 4990.9 5129.7 5205.6 5272.4 5277.2 5451.7 5875.9 5888.5 5893.3 5944.6 2914.2 3105.2 3262.4 3284.6 3312.8 4148.9 4250.6 4477.3 4625.0 4714.5 4541.6 4576.6 5438.3 5440.8 5448.0 4524.8 4701.0 5218.0 5565.8 5567.0 4712.0 5155.3 5226.4 5333.4 5408.1 4491.4 4632.6 4686.5 4783.4 5255.8 4839.4 5406.3 5416.1 5421.5 5483.0 12:39:45 INFO - PROCESS | 1640 | 12:39:45 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:25887.46 Median:21818.40 stddev:41612.33 (190.7%) stddev-sans-first:41686.19 12:39:45 INFO - PROCESS | 1640 | Values: 729.0 730.3 776.8 812.5 836.1 15379.1 16445.0 16481.9 16489.9 16527.5 14956.0 15125.8 15142.3 15158.2 15257.4 680.5 707.9 833.4 847.7 862.2 865.1 869.4 870.0 870.6 874.6 919.6 921.3 922.0 922.6 924.3 920.1 920.3 921.8 922.5 923.8 536.0 667.3 689.7 691.7 691.9 14873.5 14911.3 14912.3 14929.3 14930.5 14080.4 14187.1 14222.9 14245.9 14355.7 12895.9 12924.8 12995.2 13137.3 13208.2 4602.2 4709.4 4736.0 4767.0 4861.7 103686.0 124240.0 124314.0 130117.3 130653.3 10423.5 11190.7 11300.7 11657.9 11685.9 21097.3 21639.5 21997.3 22009.0 22015.9 7764.7 7784.8 7803.8 7812.5 7942.8 4426.4 4461.3 4501.5 4652.4 4666.6 3340.1 3469.5 3596.5 3599.7 3611.4 562.6 682.3 697.6 718.7 720.6 576.9 664.2 671.7 674.9 688.1 8458.8 9666.2 9736.0 9740.9 9774.2 526.5 614.0 647.1 675.9 698.3 4213.8 4496.4 4677.0 4690.6 4810.8 124863.0 159638.0 161117.0 161659.0 168316.0 124469.0 126486.0 133691.0 135484.7 148378.0 114914.0 118563.2 119164.3 121982.4 122156.0 10131.3 10213.9 10261.8 10326.8 10397.6 64323.0 68501.7 69697.7 69714.3 70312.0 68036.7 74795.0 83928.6 84967.0 88850.1 14347.9 14356.4 14457.1 14601.3 15025.5 14439.9 15245.0 15415.5 15558.2 16073.3 10290.4 13932.0 14275.4 14519.8 14658.6 13974.8 14269.1 14379.4 14710.6 14802.1 13725.8 13855.5 13914.8 13961.7 14084.0 9167.8 11464.5 11504.8 11614.0 12228.1 10073.4 10273.6 11586.5 11889.4 12071.3 12:39:45 INFO - PROCESS | 1640 | -------- Summary: end -------- 12:39:45 INFO - PROCESS | 1640 | 12:39:46 INFO - TEST-INFO | 1640: exit 0 12:39:46 INFO - TEST-OK | dromaeo_css | took 1117039ms 12:39:46 INFO - TEST-START | kraken 12:39:46 INFO - operating with platform_type : mac_ 12:39:46 INFO - Initialising browser for kraken test... 12:39:46 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpfqDAJI/profile http://localhost:49263/getInfo.html) 12:39:49 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 12:39:49 INFO - PROCESS | 1650 | colorDepth:24 12:39:49 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 12:39:49 INFO - PROCESS | 1650 | __metrics 12:39:49 INFO - PROCESS | 1650 | 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" 12:39:49 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:39:49 INFO - TEST-INFO | 1650: exit 0 12:39:49 INFO - Browser initialized. 12:39:49 INFO - Running cycle 1/1 for kraken test... 12:39:49 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpfqDAJI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:40:01 INFO - PROCESS | 1651 | RSS: Main: 228290560 12:40:01 INFO - PROCESS | 1651 | 12:40:04 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection) 12:40:05 INFO - PROCESS | 1651 | RSS: Main: 292261888 12:40:05 INFO - PROCESS | 1651 | 12:40:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-dft) 12:40:08 INFO - PROCESS | 1651 | RSS: Main: 288407552 12:40:08 INFO - PROCESS | 1651 | 12:40:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-fft) 12:40:13 INFO - PROCESS | 1651 | RSS: Main: 381767680 12:40:13 INFO - PROCESS | 1651 | 12:40:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator) 12:40:15 INFO - PROCESS | 1651 | RSS: Main: 282144768 12:40:15 INFO - PROCESS | 1651 | 12:40:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:40:18 INFO - PROCESS | 1651 | RSS: Main: 286269440 12:40:18 INFO - PROCESS | 1651 | 12:40:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom) 12:40:21 INFO - PROCESS | 1651 | RSS: Main: 323346432 12:40:21 INFO - PROCESS | 1651 | 12:40:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate) 12:40:25 INFO - PROCESS | 1651 | RSS: Main: 324812800 12:40:25 INFO - PROCESS | 1651 | 12:40:28 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial) 12:40:28 INFO - PROCESS | 1651 | RSS: Main: 321568768 12:40:28 INFO - PROCESS | 1651 | 12:40:30 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:40:31 INFO - PROCESS | 1651 | RSS: Main: 303144960 12:40:31 INFO - PROCESS | 1651 | 12:40:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:40:33 INFO - PROCESS | 1651 | RSS: Main: 306135040 12:40:33 INFO - PROCESS | 1651 | 12:40:35 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:40:36 INFO - PROCESS | 1651 | RSS: Main: 305299456 12:40:36 INFO - PROCESS | 1651 | 12:40:39 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:40:39 INFO - PROCESS | 1651 | RSS: Main: 312999936 12:40:39 INFO - PROCESS | 1651 | 12:40:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:40:43 INFO - PROCESS | 1651 | RSS: Main: 314474496 12:40:43 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49263/tests/kraken/driver.html?testName=ai-astar) 12:40:45 INFO - PROCESS | 1651 | RSS: Main: 358141952 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | __start_tp_report 12:40:45 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 12:40:45 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 12:40:45 INFO - PROCESS | 1651 | |i|pagename|runs| 12:40:45 INFO - PROCESS | 1651 | |0;ai-astar;102;113;120;164;146;114;112;112;114;113 12:40:45 INFO - PROCESS | 1651 | |1;audio-beat-detection;144;107;107;168;133;94;149;132;123;125 12:40:45 INFO - PROCESS | 1651 | |2;audio-dft;184;183;184;182;183;185;184;181;181;182 12:40:45 INFO - PROCESS | 1651 | |3;audio-fft;76;75;56;89;58;58;66;63;83;57 12:40:45 INFO - PROCESS | 1651 | |4;audio-oscillator;81;88;94;71;71;69;112;98;93;112 12:40:45 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;88;117;87;87;87;84;94;86;89;83 12:40:45 INFO - PROCESS | 1651 | |6;imaging-darkroom;93;93;93;94;97;93;92;130;133;90 12:40:45 INFO - PROCESS | 1651 | |7;imaging-desaturate;86;83;85;84;82;83;85;84;110;130 12:40:45 INFO - PROCESS | 1651 | |8;json-parse-financial;53;53;55;56;56;57;53;53;55;56 12:40:45 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;63;71;64;71;69;69;69;69;68;70 12:40:45 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;86;88;85;84;83;85;87;83;86;86 12:40:45 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;140;138;138;134;136;139;137;135;162;154 12:40:45 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;170;168;167;168;168;167;166;168;166;166 12:40:45 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;67;69;67;68;66;66;66;67;66;67 12:40:45 INFO - PROCESS | 1651 | __end_tp_report 12:40:45 INFO - PROCESS | 1651 | __start_cc_report 12:40:45 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2020 12:40:45 INFO - PROCESS | 1651 | __end_cc_report 12:40:45 INFO - PROCESS | 1651 | __startTimestamp1471635645359__endTimestamp 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | ------- Summary: start ------- 12:40:45 INFO - PROCESS | 1651 | Number of tests: 14 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:121.00 Median:114.00 stddev:18.93 (16.6%) stddev-sans-first:18.78 12:40:45 INFO - PROCESS | 1651 | Values: 102.0 113.0 120.0 164.0 146.0 114.0 112.0 112.0 114.0 113.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:138.50 stddev:22.13 (16.0%) stddev-sans-first:22.73 12:40:45 INFO - PROCESS | 1651 | Values: 144.0 107.0 107.0 168.0 133.0 94.0 149.0 132.0 123.0 125.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.50 stddev:1.37 (0.7%) stddev-sans-first:1.39 12:40:45 INFO - PROCESS | 1651 | Values: 184.0 183.0 184.0 182.0 183.0 185.0 184.0 181.0 181.0 182.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:68.10 Median:70.50 stddev:11.89 (16.9%) stddev-sans-first:12.27 12:40:45 INFO - PROCESS | 1651 | Values: 76.0 75.0 56.0 89.0 58.0 58.0 66.0 63.0 83.0 57.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:84.50 stddev:15.96 (18.9%) stddev-sans-first:16.67 12:40:45 INFO - PROCESS | 1651 | Values: 81.0 88.0 94.0 71.0 71.0 69.0 112.0 98.0 93.0 112.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:87.00 stddev:9.87 (11.4%) stddev-sans-first:10.44 12:40:45 INFO - PROCESS | 1651 | Values: 88.0 117.0 87.0 87.0 87.0 84.0 94.0 86.0 89.0 83.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:93.00 stddev:16.29 (17.5%) stddev-sans-first:17.03 12:40:45 INFO - PROCESS | 1651 | Values: 93.0 93.0 93.0 94.0 97.0 93.0 92.0 130.0 133.0 90.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:91.20 Median:84.00 stddev:15.94 (19.0%) stddev-sans-first:16.79 12:40:45 INFO - PROCESS | 1651 | Values: 86.0 83.0 85.0 84.0 82.0 83.0 85.0 84.0 110.0 130.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.50 stddev:1.57 (2.8%) stddev-sans-first:1.54 12:40:45 INFO - PROCESS | 1651 | Values: 53.0 53.0 55.0 56.0 56.0 57.0 53.0 53.0 55.0 56.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:69.00 stddev:2.71 (3.9%) stddev-sans-first:2.09 12:40:45 INFO - PROCESS | 1651 | Values: 63.0 71.0 64.0 71.0 69.0 69.0 69.0 69.0 68.0 70.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:86.00 stddev:1.64 (1.9%) stddev-sans-first:1.72 12:40:45 INFO - PROCESS | 1651 | Values: 86.0 88.0 85.0 84.0 83.0 85.0 87.0 83.0 86.0 86.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:138.50 stddev:9.18 (6.6%) stddev-sans-first:9.72 12:40:45 INFO - PROCESS | 1651 | Values: 140.0 138.0 138.0 134.0 136.0 139.0 137.0 135.0 162.0 154.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:168.00 stddev:1.26 (0.8%) stddev-sans-first:0.93 12:40:45 INFO - PROCESS | 1651 | Values: 170.0 168.0 167.0 168.0 168.0 167.0 166.0 168.0 166.0 166.0 12:40:45 INFO - PROCESS | 1651 | 12:40:45 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 12:40:45 INFO - PROCESS | 1651 | Values: 67.0 69.0 67.0 68.0 66.0 66.0 66.0 67.0 66.0 67.0 12:40:45 INFO - PROCESS | 1651 | -------- Summary: end -------- 12:40:45 INFO - PROCESS | 1651 | 12:40:46 INFO - TEST-INFO | 1651: exit 0 12:40:46 INFO - TEST-OK | kraken | took 59593ms 12:40:46 INFO - SUITE-END | took 1176s 12:40:46 INFO - Completed test suite (00:19:36) 12:40:46 INFO - javascript benchmark 12:40:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3358.781679190853, 3361.8151001694896, 3361.8991430257092, 3390.3388839176355, 3429.4512877939533, 5579.05156123459, 5674.177244299584, 5678.034734095203, 5750.971245143779, 5776.797812076578, 5630.436956304367, 5660.433956604337, 5738.254026976487, 5831.979403604363, 5889.440503152194, 5121.28302037715, 5164.483551644843, 5201.713905735183, 5207.505286997748, 5296.8072879610345, 5339.6262261641705, 5364.329458817648, 5375.354957405117, 5378.91931621026, 5387.542058924996, 5120.15517439622, 5163.380394352665, 5177.715225662587, 5235.240890070919, 5269.420363759983, 5230.660007099526, 5282.286891269706, 5282.867928301785, 5289.471052894718, 5299.920501192485, 5217.139172036609, 5231.607629427808, 5260.263563101169, 5270.578353731692, 5276.657017293864, 5606.691631960241, 5693.4875861172695, 5746.109353050284, 5798.7390567424145, 5816.331121920949, 6271.8745625087695, 6301.747930082791, 6310.1481300024825, 6310.9684451577905, 6339.936600633981, 6320.873582528369, 6359.20509936258, 6370.681465926685, 6376.9681151594405, 6382.20222472191, 5653.406392328788, 5686.715664216773, 5755.079187330007, 5756.280464941882, 5860.589758716891, 6305.369463053749, 6308.432241098323, 6335.873282534323, 6344.333844946306, 6348.587341822767, 6126.172966649446, 6133.846653833601, 6144.815655530341, 6147.108669243024, 6149.0, 6087.512998960073, 6195.256569211723, 6236.469900058546, 6250.9998400255745, 6265.4361107500545, 6218.844528886814, 6274.1843560336865, 6276.905846412307, 6290.528210384239, 6294.559380843389, 6225.688715564203, 6306.621602703853, 6316.8420789479715, 6327.3988971047675, 6340.270868850049, 4790.640701947368, 4871.439784424766, 4873.0, 4894.461609222983, 4940.728259945666, 4365.585269399402, 4410.404595379647, 4427.69006169565, 4453.75504347264, 4456.665750068758, 5861.70691465425, 5873.794417195463, 5882.470577647947, 5898.14476900847, 5900.970495147497, 6169.413905678954, 6236.314005459396, 6309.968450157719, 6315.526335524854, 6318.589291696025, 4967.5032496750755, 5818.33089194748, 5915.881682366328, 5916.645001299936, 5937.554683398763, 5423.755930983115, 5446.673199608036, 5478.835634930959, 5491.917621235684, 5501.4223506532035, 5840.970795145997, 6208.0998255253635, 6325.810225693144, 6389.808305750835, 6395.072714456378, 5937.0, 6279.183706118175, 6303.149074874833, 6315.4947604192475, 6329.8734025320155, 5460.972695136499, 6396.42432181106, 6397.976323788172, 6398.616083035033, 6398.872022559616, 5853.8536536585225, 5893.5874488785375, 6041.0, 6277.372262773686, 6285.2457705075685, 5985.760569577168, 6012.699365031818, 6119.296280927751, 6121.296050954197, 6127.571070025056, 5876.265466816648, 5931.377205393372, 5935.465808077293, 5959.7020148991505, 5970.9701451492465, 5901.940980590139, 5924.822255332347, 5947.226860508106, 5963.463288304073, 6022.518198544019, 5891.086881533369, 6004.729787159676, 6011.158437818796, 6032.4570788629235, 6042.607230530001, 6152.538559607958, 6170.259568851767, 6170.5372097092, 6177.104319873503, 6186.505079593711, 5735.971320143372, 5811.53507719373, 5957.0, 5972.462478376793, 5989.940100598938], "value": 5713.894280463862, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.7199488299345, 483.65902039062445, 517.8291163915908, 529.5075579710868, 535.9222912677661, 30163.944261950837, 39806.60193398065, 40912.59087409125, 41031.58968410315, 41089.0, 42169.0, 42442.787786061024, 42460.0, 42551.361729574004, 42583.0, 9857.408555486676, 10027.699169024905, 10031.147352475049, 10048.246381521378, 10055.748606284827, 9950.65172718955, 10066.848997265048, 10091.697249082503, 10107.54516046776, 10114.494275286243, 10046.397216167017, 10071.798564028752, 10114.393136411838, 10139.34094283873, 10142.49287535624, 9989.350692204984, 10047.598096076148, 10080.64717734883, 10099.899001010042, 10115.342502737298, 9895.8515622265, 9983.75040623983, 9989.400635961865, 9991.75020624483, 9999.400035997864, 40115.39826902599, 40365.394519082234, 40380.99047523835, 40434.797826010974, 40546.728925239826, 3025.485667436541, 3428.468587368962, 3447.896563103086, 3452.378571857065, 3457.913552161191, 31170.532442013387, 31487.527687084712, 31534.36931261362, 31753.682463175533, 32059.35881282361, 2792.7506052786325, 3117.547955546433, 3174.5238214267815, 3185.378851124032, 3261.6249131349723, 517956.0, 519405.0, 522104.0, 522760.9999999962, 530197.0, 56382.43617563854, 56638.0, 56669.632947716535, 56746.43253567412, 56834.7158264206, 34385.968420947414, 34892.65107348894, 35155.0, 60746.69626651838, 61094.694526527084, 16340.428085016967, 16872.83127168737, 16873.0, 16883.662326753398, 16902.0, 12883.162594431333, 12993.610191694264, 13003.544875929298, 13006.739865202831, 13010.414531346112, 18782.624347512974, 18815.623687526448, 18903.960282184336, 18945.526361841064, 18945.621087578173, 7680.807979800438, 7748.0, 7775.49459285156, 7805.960970195226, 7857.0, 849.099954048698, 923.7736754495191, 957.4494665567327, 1037.1443559063803, 1094.698957786615, 29915.700842991726, 30274.394512109633, 30574.69425305763, 31152.0, 31761.0, 978.6966040527459, 1024.2267088348249, 1033.4316126130598, 1050.702382557541, 1066.818640831045, 14575.271236438135, 15553.688926221412, 15762.842371576138, 15821.762673559906, 15995.840041599435, 732712.383009358, 739960.0, 742867.0, 743098.0, 743481.0, 58760.41239587549, 59253.703731481066, 59258.70370648119, 59265.703671481366, 59391.0, 1235.5799028330412, 1361.4077876123918, 1372.7803551431923, 1376.078027721449, 1408.091780801357, 30145.576707726763, 31254.062378128692, 31550.211244718146, 31822.681773181972, 32019.6798032026, 48906.75546622386, 49451.258231125845, 49453.01093978172, 49475.0, 49507.25739113988, 555055.0, 557166.0, 557498.0, 558321.0, 558340.0, 23151.0, 25845.0, 26297.553634550284, 26383.472330552897, 26688.599671004562, 47218.76390618025, 47720.522794771605, 48281.27578086261, 48410.0, 48846.0, 38275.42586861143, 38722.03194920037, 38896.80551597337, 40075.39886901757, 40534.0, 35073.08976743207, 37426.8128659355, 39584.20831583295, 40014.39978400384, 40603.390949135195, 37370.0, 38446.80776596099, 38572.421413678254, 39400.802995984835, 39441.0, 31096.378072438878, 32557.837210813796, 33962.66037339595, 34136.0, 34366.0, 22232.666510002688, 25076.498470030863, 25100.24699259025, 25233.747662523874, 25662.486750265263], "value": 20015.63662914376, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.5628221005953, 371.4113130687858, 373.78694144337726, 383.52251446948543, 405.1592944639871, 15729.449469268578, 16099.631531319837, 20921.790782092176, 21127.577448451022, 21297.935103244814, 21021.36935891922, 21288.36134915951, 21764.12943482259, 21886.90565471724, 22127.778722212774, 5462.617616766828, 5880.206172166767, 5976.731047102869, 6065.757369705207, 6152.661603611799, 4149.854755083573, 5960.821175364746, 6058.333583305833, 6077.11881777142, 6408.647524386157, 6651.733930642768, 6657.966710166466, 6692.63190524521, 6699.263081061104, 6773.8983915241315, 5332.840014799543, 6661.233958094833, 6722.226943901465, 6722.83192920176, 6741.359570840811, 5709.942900571024, 5815.371695925148, 5916.822495325147, 5921.881562368729, 5934.080217566284, 12719.17325373857, 13817.447302107903, 13829.79255311161, 13833.308334583331, 17656.646867062715, 2153.3432303147533, 2173.5870184665023, 2198.2416066457045, 2425.50277193175, 2905.680375158731, 10045.949770251174, 10667.466626668713, 10750.569977200903, 11350.886491135065, 16513.587160321087, 1863.1150203653292, 1866.6360059788346, 1887.5469887227105, 1930.7490026296632, 1973.516488460322, 120549.0, 128908.0, 129191.0, 130563.0, 130757.99999999905, 11190.440477976232, 11353.716157095974, 11441.913018263418, 11540.365279909518, 11723.296602203895, 14467.059641123293, 14474.565763027125, 14475.059121157092, 14479.276036198147, 14489.710205796036, 8361.0, 8556.871646925301, 8558.52928088961, 8563.785905352292, 8632.223099921037, 8484.53335066565, 8498.107698691672, 8519.701810436729, 8655.870161947576, 8669.176428239307, 4489.685721999429, 4503.166914120898, 4511.819527218874, 4517.186906356887, 4599.310103484504, 1661.6593598312318, 1717.6221231329087, 1813.963720725578, 1928.7106933959737, 1938.0987840654163, 758.4122305213504, 856.6530555125184, 899.3344924755764, 950.1496160935924, 968.0831952733627, 20460.283890064075, 20954.895225523775, 21583.0, 21628.56742865134, 21658.458538536663, 843.3295530053622, 921.6681994481982, 937.2501998401252, 959.64972784933, 1012.367270455965, 10090.752372837731, 10473.581056757797, 11225.887741122648, 11396.264896424798, 11427.971482566609, 616010.0, 630252.0, 634556.0, 635942.0, 642274.0, 113433.0, 143450.2827485856, 144030.0, 144395.0, 146475.0, 975.11264749078, 983.6458874804921, 998.8951160128228, 1001.0990108902047, 1003.8193125237527, 18894.905525472284, 19188.232470701016, 19251.614967700287, 19306.93811840362, 19331.0, 56563.71718141383, 58225.0, 58354.7082264586, 58788.412115878295, 59316.703416484364, 78447.60776196311, 81091.59454202691, 82256.58871705603, 83139.0, 83836.0, 6092.573519853568, 7932.16712245206, 8010.0, 8217.465864718773, 8451.070382257823, 11393.715157121138, 12944.352782360656, 13018.934905325414, 13335.466581336637, 13347.733045338844, 11304.773904521699, 11438.656840294805, 11465.312081274817, 11580.073594112619, 11636.825447618123, 9846.507674616383, 10737.14102871784, 11391.0, 11442.539853931445, 11562.364069976233, 9721.659741909, 10962.56149754001, 11079.501422436171, 11441.48513316886, 11502.827457587975, 7950.0, 8073.636686349246, 8168.346532277523, 8371.037330707046, 8387.538685372363, 10468.110210632181, 10884.346939183675, 11191.552337906392, 11241.381724005258, 11248.381339026431], "value": 9618.38751123346, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.6924941888083, 1537.7923980262667, 1595.8803089768269, 1750.2911320915025, 1872.5786697992953, 7300.0, 7836.529808211504, 8310.085890552034, 8310.626021829017, 8324.209200125993, 7836.02049743782, 8384.119667434923, 8776.868346974801, 8889.777755556115, 8897.777555561099, 2389.2593296078226, 2414.9396265093424, 2418.2503423938633, 2420.5279970405777, 2430.4652976345265, 2088.7702352741185, 2354.470244195051, 2403.194929698553, 2404.038384646138, 2409.6987876515436, 2374.1334412939327, 2394.580948334043, 2401.8198635102435, 2407.037185125946, 2410.68661074061, 2339.4385347516472, 2366.4557151855083, 2368.2066507719933, 2368.3842201027855, 2386.749391313922, 2338.5206032763244, 2362.4802543440414, 2368.7394386617457, 2372.1223147435385, 2376.9881150594138, 8820.029796722356, 8872.55637218143, 8924.929008518955, 8934.240589549896, 8998.775030624221, 5211.5049070338255, 5253.947460525423, 5386.649867758583, 5392.2181283714035, 5394.595405344576, 3642.453631955201, 3722.5346831646043, 3731.925361492782, 3732.9440058399146, 3736.7757934524016, 1105.6682995101437, 1112.7440688641657, 1135.3698697223037, 1136.0911270983263, 1142.1719253541116, 11664.183507154503, 11702.76594468106, 11840.585579504763, 11840.763184736257, 11880.465379057963, 4492.707973981681, 4531.0, 4667.929981050287, 4671.369365135731, 4720.433547974265, 9263.212626926786, 9271.0, 9276.0, 9276.72169834906, 9276.814463710689, 5307.893842123213, 5308.946910530923, 5312.2297266896085, 5323.733813309319, 5336.573074154136, 4446.555344465528, 4629.768511574475, 4742.596879265301, 4882.804687812448, 4968.652194346361, 3187.713105820441, 3273.4271502486968, 3761.269815884724, 3845.442410850411, 3872.012636777604, 1171.654361963223, 1173.0, 1177.5996161305197, 1183.1422218891407, 1188.5483516263937, 837.3092198935902, 897.9506127163069, 903.1284810158229, 927.9396839205565, 934.5093825741404, 5731.455511726379, 5800.854978625568, 6058.848818724428, 6148.0, 6169.96915015422, 432.0408692702196, 435.6427729262038, 444.38452742951245, 448.96857219994286, 458.33999041380974, 3777.792221427848, 3910.608939106067, 3942.9014274643364, 4028.879133625996, 4032.4354590357375, 10816.351018938889, 11104.000639942444, 11205.103591712643, 11210.21528493014, 11219.887801122048, 11102.167337449724, 11103.666889993314, 11119.666410007712, 11135.33188008722, 11169.329840209613, 6826.692798824065, 8312.667493300321, 8396.160383961555, 8402.579871006425, 8403.033651130198, 5195.480451954774, 5405.189221616632, 6106.9083963739695, 6110.144579758926, 6133.141360209664, 9851.655192068243, 10062.39625622465, 10122.595096196364, 10212.285140040125, 10378.636747713794, 8533.615987280464, 10096.29325947206, 10348.327358721659, 10523.894761052597, 10578.682639520826, 7120.465965052538, 7218.530795498275, 7358.411327093712, 7744.225577442211, 7864.0, 8310.70912518059, 8540.530270835165, 8638.740837774876, 8720.781980450285, 8744.475331480131, 7526.360259378014, 7603.2776886195725, 7672.8849067265155, 7802.414818888493, 7836.764897053097, 7438.367738742051, 7505.0, 7521.172671006294, 7535.73624923125, 7575.659095340833, 6041.426064523863, 7097.503174777716, 7098.893516597152, 7099.290070992859, 7107.680154392961, 4924.8768780780765, 4999.427242346174, 5056.140456122542, 5097.796088156432, 5201.661891977009, 4879.268109783561, 4959.479254678351, 4986.576141028053, 5034.798608055637, 5265.447128051653], "value": 4497.772719326983, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.6116168400238, 303.675067677585, 313.5046626330398, 316.8352456722506, 317.26078237706145, 5237.84286471406, 5247.422783493817, 5257.658252213603, 5261.921071183935, 5270.341207349082, 4929.926051109236, 4987.675801072928, 5085.821996230133, 5176.922346164811, 5196.128883981439, 3405.9318813623713, 3689.0777305673582, 4014.4179094031347, 4021.115354621993, 4108.28105081611, 3458.844352004166, 3911.0, 3992.5408578013466, 4061.1877624475073, 4080.878128231905, 3329.5671562696934, 3618.7105031597407, 3743.8128093595346, 4016.5581786003518, 4056.8377264909514, 3543.167355671415, 3918.45141680165, 3937.5668676445566, 3947.743396679221, 3954.3277642800504, 3251.6910893465083, 3385.2044769479153, 3428.777129486575, 3433.364827506919, 3443.9655603443894, 4205.053862880827, 4501.006830457742, 5158.896822063575, 5170.482951704799, 5187.299714538564, 3964.4251583520227, 4462.7991740371435, 4505.07645932583, 4559.3844830948065, 4568.406107206075, 4738.526147385269, 5506.201600767907, 5512.558995280408, 5522.282103326582, 5547.389787123413, 3182.8249446280447, 3768.9434658480145, 3806.600306967784, 3822.2737679840743, 3838.0, 5136.484737002594, 5287.735613219362, 5441.374241962186, 5752.511036561939, 5815.73829177688, 3075.615548056493, 3807.5050243468727, 3818.560865500503, 3845.115623406632, 3853.3256680081436, 3707.7589956652732, 3910.3156947534067, 3915.040815000343, 3922.2155568886337, 3924.5486769021927, 4344.761038142932, 4497.392851965008, 4588.518205588365, 4592.517785632527, 4593.058423023339, 5531.474509921551, 5534.584906132056, 5539.390667026624, 5543.0, 5557.582816381878, 1152.6053475294987, 1320.5972178485654, 1359.3950691942189, 1365.6244532753374, 1367.4325155060737, 844.7381311793364, 866.6923242249037, 886.3795343259744, 892.4823602310644, 894.9194572488507, 4402.456022901595, 4480.663950203748, 4842.070547318892, 5013.248012798036, 5099.184130539096, 5266.2890509781455, 5366.209571023046, 5444.386694386717, 5475.780968761284, 5775.0, 5221.232746644285, 5221.947780522146, 5226.425093239741, 5233.633645644844, 5264.341957255343, 2767.4465106978687, 2859.6711378191776, 2987.9402411951637, 3002.8798848045835, 3022.727954484063, 5554.611177217556, 5910.586258961831, 5919.911201331984, 5932.436418540232, 5998.460138587461, 4653.930191047137, 5385.569154467633, 5803.129530570448, 5852.502537284378, 5963.493103086286, 5229.0, 5353.170258609922, 5594.104943209067, 5927.1405646181065, 5936.673482958392, 4936.506349365179, 5249.084084309124, 5709.200711900255, 5718.3423906249645, 5731.168980497888, 4990.850274491771, 5129.74321291286, 5205.58355331565, 5272.367315922113, 5277.234800953764, 5451.7001564912725, 5875.882482350244, 5888.4700376966275, 5893.263342082239, 5944.613600115978, 2914.21316244617, 3105.1771280611106, 3262.4127657021013, 3284.5712115229953, 3312.8343582821244, 4148.917021659489, 4250.638695710899, 4477.287975058221, 4624.993754059888, 4714.457837348748, 4541.636669066533, 4576.597286424653, 5438.265834112344, 5440.755166017618, 5448.0, 4524.778309856386, 4700.989287303246, 5217.991073436679, 5565.8051968182535, 5566.972165139148, 4712.0, 5155.252488389237, 5226.425093239664, 5333.386660534088, 5408.07864842991, 4491.393661855608, 4632.606228470483, 4686.531346865286, 4783.354247176587, 5255.763490642854, 4839.395075615548, 5406.297181366397, 5416.106342453451, 5421.484958928895, 5483.0], "value": 3962.0147117014026, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.9890651640226, 730.3135053050132, 776.7630872583863, 812.5206128384252, 836.0803116571772, 15379.077255364686, 16445.0, 16481.917590412046, 16489.91755041226, 16527.50417487477, 14956.0, 15125.773113403307, 15142.318595663193, 15158.166300853449, 15257.38970441181, 680.4998326230217, 707.9150501939748, 833.3583140981441, 847.656699036891, 862.1766213266347, 865.1478293880523, 869.443556124081, 870.0168809245544, 870.6125774030559, 874.5933141089392, 919.6045700348848, 921.2767977137948, 921.9585118669643, 922.6263363337858, 924.2790623313792, 920.1120918313826, 920.2913756407596, 921.7695576105973, 922.4834092907993, 923.7598224461655, 535.9705216213107, 667.2926697700453, 689.7137687859582, 691.6645426967916, 691.9065926099962, 14873.47942822007, 14911.32899019536, 14912.254387280593, 14929.328180231807, 14930.47743328989, 14080.366383512766, 14187.14877107377, 14222.857771422257, 14245.857541424557, 14355.71288574233, 12895.871041289562, 12924.806127908088, 12995.155314904501, 13137.27744974026, 13208.207507549578, 4602.194615942197, 4709.387779588666, 4736.005438857844, 4767.046590681877, 4861.683990540639, 103686.0, 124240.0, 124314.0, 130117.34941325328, 130653.34673326573, 10423.478826058667, 11190.66428007161, 11300.660980170607, 11657.88342116568, 11685.941570292094, 21097.261595844073, 21639.459013524473, 21997.340079797632, 22009.0, 22015.889920550293, 7764.728234511765, 7784.766457006299, 7803.765887023398, 7812.492188007761, 7942.761717148494, 4426.44669416323, 4461.263891457873, 4501.504834468205, 4652.441706995182, 4666.62666986647, 3340.1315657929113, 3469.548958635361, 3596.496490491333, 3599.6580324869096, 3611.368010598135, 562.572444941839, 682.3313153109881, 697.5500801982693, 718.7493760856073, 720.5640587444625, 576.9423057694281, 664.1996394344803, 671.6809515480107, 674.9358810913054, 688.084847153285, 8458.830823383498, 9666.226701863834, 9736.0, 9740.902590974141, 9774.169195618451, 526.5419085395654, 613.9693015349214, 647.1328419917334, 675.9324067593201, 698.2528694297054, 4213.810378532973, 4496.437945256843, 4676.953230467651, 4690.6012988895645, 4810.807567697323, 124863.0, 159638.0, 161117.0, 161659.0, 168316.0, 124469.0, 126486.0, 133691.0, 135484.70956420954, 148378.0, 114914.0, 118563.22155167679, 119164.2543234687, 121982.390088049, 122156.0, 10131.290809643255, 10213.897861021294, 10261.794764104825, 10326.793464130526, 10397.584096636352, 64323.0, 68501.65749171222, 69697.65151174212, 69714.30285697078, 70312.0, 68036.6598167006, 74795.0, 83928.58035709782, 84967.0, 88850.11149888676, 14347.92826035863, 14356.35396407185, 14457.132572045712, 14601.269936503344, 15025.474108406153, 14439.92780036093, 15245.0, 15415.537533874001, 15558.222088895736, 16073.276702548179, 10290.382577045102, 13932.0, 14275.42898284057, 14519.782203266748, 14658.560243192722, 13974.790378144133, 14269.072510286798, 14379.424823006962, 14710.591286565665, 14802.111873287637, 13725.794113088512, 13855.515056972958, 13914.791278130633, 13961.720765584834, 14084.0, 9167.7668451862, 11464.541418343173, 11504.756572468288, 11614.0, 12228.08289378318, 10073.445960472245, 10273.640422585173, 11586.536538538701, 11889.405529723652, 12071.336076515525], "value": 8080.453550312379, "unit": "score"}], "name": "dromaeo_css", "value": 7355.862811106015, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 120.0, 164.0, 146.0, 114.0, 112.0, 112.0, 114.0, 113.0], "value": 121.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 107.0, 168.0, 133.0, 94.0, 149.0, 132.0, 123.0, 125.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 184.0, 182.0, 183.0, 185.0, 184.0, 181.0, 181.0, 182.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 56.0, 89.0, 58.0, 58.0, 66.0, 63.0, 83.0, 57.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 88.0, 94.0, 71.0, 71.0, 69.0, 112.0, 98.0, 93.0, 112.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 117.0, 87.0, 87.0, 87.0, 84.0, 94.0, 86.0, 89.0, 83.0], "value": 90.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 97.0, 93.0, 92.0, 130.0, 133.0, 90.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 84.0, 82.0, 83.0, 85.0, 84.0, 110.0, 130.0], "value": 91.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 56.0, 56.0, 57.0, 53.0, 53.0, 55.0, 56.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 64.0, 71.0, 69.0, 69.0, 69.0, 69.0, 68.0, 70.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 85.0, 84.0, 83.0, 85.0, 87.0, 83.0, 86.0, 86.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 138.0, 134.0, 136.0, 139.0, 137.0, 135.0, 162.0, 154.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 167.0, 168.0, 168.0, 167.0, 166.0, 168.0, 166.0, 166.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 67.0, 68.0, 66.0, 66.0, 66.0, 67.0, 66.0, 67.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1455.2000000000003, "alertThreshold": 2.0}]} 12:40:46 INFO - Return code: 0 12:40:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:40:46 INFO - # TBPL SUCCESS # 12:40:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:40:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:40:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:40:46 INFO - 2016-08-19 12:40:46.693 screenresolution[1655:9709] starting screenresolution argv=screenresolution get 12:40:46 INFO - 2016-08-19 12:40:46.696 screenresolution[1655:9709] Display 0: 1600x1200x32@60 12:40:46 INFO - 2016-08-19 12:40:46.702 screenresolution[1656:9712] starting screenresolution argv=screenresolution list 12:40:46 INFO - Available Modes on Display 0 12:40:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:40:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:40:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:40:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:40:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:40:46 INFO - Intel Iris: 12:40:46 INFO - Chipset Model: Intel Iris 12:40:46 INFO - Type: GPU 12:40:46 INFO - Bus: Built-In 12:40:46 INFO - VRAM (Dynamic, Max): 1536 MB 12:40:46 INFO - Vendor: Intel (0x8086) 12:40:46 INFO - Device ID: 0x0a2e 12:40:46 INFO - Revision ID: 0x0009 12:40:46 INFO - Displays: 12:40:46 INFO - Display: 12:40:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:40:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:40:46 INFO - Display Serial Number: 1600x1200 60 12:40:46 INFO - Main Display: Yes 12:40:46 INFO - Mirror: Off 12:40:46 INFO - Online: Yes 12:40:46 INFO - Rotation: Supported 12:40:46 INFO - Return code: 0 12:40:46 INFO - Running post-action listener: _resource_record_post_action 12:40:46 INFO - [mozharness: 2016-08-19 19:40:46.942254Z] Finished run-tests step (success) 12:40:46 INFO - Running post-run listener: _resource_record_post_run 12:40:46 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37225472; Write bytes: 702334464; Read time: 242; Write time: 1932 12:40:46 INFO - TinderboxPrint: CPU usage
53.8% 12:40:46 INFO - TinderboxPrint: I/O read bytes / time
37,225,472 / 242 12:40:46 INFO - TinderboxPrint: I/O write bytes / time
702,334,464 / 1,932 12:40:47 INFO - TinderboxPrint: CPU idle
2,240.3 (46.2%) 12:40:47 INFO - TinderboxPrint: CPU system
969.8 (20.0%) 12:40:47 INFO - TinderboxPrint: CPU user
1,635.6 (33.8%) 12:40:47 INFO - TinderboxPrint: Swap in / out
442,572,800 / 0 12:40:47 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396943872; Write bytes: 391161344; Read time: 29619; Write time: 777 12:40:47 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35218432; Write bytes: 293261312; Read time: 223; Write time: 1105 12:40:47 INFO - Running post-run listener: _upload_blobber_files 12:40:47 INFO - Blob upload gear active. 12:40:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:40:47 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 12:40:47 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:40:47 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:40:51 INFO - (blobuploader) - INFO - Open directory for files ... 12:40:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:40:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:40:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:52 INFO - (blobuploader) - INFO - Done attempting. 12:40:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:40:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:40:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:58 INFO - (blobuploader) - INFO - Done attempting. 12:40:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:40:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:41:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:41:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:41:09 INFO - (blobuploader) - INFO - Done attempting. 12:41:09 INFO - (blobuploader) - INFO - Iteration through files over. 12:41:09 INFO - Return code: 0 12:41:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:41:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:41:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9839d7074deab279e5e02f37aad3dbcf5f44879ba02ea8aab5f15e140f53167b351373ed7128b33f7397c7c8283b1caee0d172a4cd2780c71d3367af48316e83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a828ce3b1329776dac6ec39b95f1902b7f38a70878ca23daa42373a6d2fff5b27691ef2bbdcfdb506b0045dfb429e2f00c8d116ddb5cab7ed0fbaa068423392c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80451a582539c414273a136740548dfdcee14d1a72dc9d1098885b464dfb053e8637c6d0a4253a9716703adb004c549a18dab3a2428466f9c3a3c0eb2a742e3a"} 12:41:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:41:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:41:09 INFO - Contents: 12:41:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9839d7074deab279e5e02f37aad3dbcf5f44879ba02ea8aab5f15e140f53167b351373ed7128b33f7397c7c8283b1caee0d172a4cd2780c71d3367af48316e83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a828ce3b1329776dac6ec39b95f1902b7f38a70878ca23daa42373a6d2fff5b27691ef2bbdcfdb506b0045dfb429e2f00c8d116ddb5cab7ed0fbaa068423392c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80451a582539c414273a136740548dfdcee14d1a72dc9d1098885b464dfb053e8637c6d0a4253a9716703adb004c549a18dab3a2428466f9c3a3c0eb2a742e3a"} 12:41:09 INFO - Running post-run listener: copy_logs_to_upload_dir 12:41:09 INFO - Copying logs to upload dir... 12:41:09 INFO - mkdir: /builds/slave/test/build/upload/logs 12:41:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.702924 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-08-19 12:41:09.696622) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 12:41:09.699714) ========= 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.JL9N3pTKxO/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.eqrkFM6L98/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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/Firefox/sha512/9839d7074deab279e5e02f37aad3dbcf5f44879ba02ea8aab5f15e140f53167b351373ed7128b33f7397c7c8283b1caee0d172a4cd2780c71d3367af48316e83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a828ce3b1329776dac6ec39b95f1902b7f38a70878ca23daa42373a6d2fff5b27691ef2bbdcfdb506b0045dfb429e2f00c8d116ddb5cab7ed0fbaa068423392c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80451a582539c414273a136740548dfdcee14d1a72dc9d1098885b464dfb053e8637c6d0a4253a9716703adb004c549a18dab3a2428466f9c3a3c0eb2a742e3a"} build_url:https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011299 build_url: 'https://queue.taskcluster.net/v1/task/CGuCutEaR_Kdt9L7lpSJsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9839d7074deab279e5e02f37aad3dbcf5f44879ba02ea8aab5f15e140f53167b351373ed7128b33f7397c7c8283b1caee0d172a4cd2780c71d3367af48316e83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a828ce3b1329776dac6ec39b95f1902b7f38a70878ca23daa42373a6d2fff5b27691ef2bbdcfdb506b0045dfb429e2f00c8d116ddb5cab7ed0fbaa068423392c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80451a582539c414273a136740548dfdcee14d1a72dc9d1098885b464dfb053e8637c6d0a4253a9716703adb004c549a18dab3a2428466f9c3a3c0eb2a742e3a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 12:41:09.739065) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 12:41:09.739831) ========= 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.JL9N3pTKxO/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.eqrkFM6L98/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.005104 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 12:41:09.792852) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 12:41:09.793663) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 12:41:09.794384) ========= ========= Total master_lag: 0.19 =========