builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0019 starttime: 1467661721.27 results: success (0) buildid: 20160704110523 builduid: 4489b67749224ad8bbbbd5759698190e revision: aaf789f8f36624d8a765d63cc1d9ba6fd56e36e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:41.266799) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:41.267294) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:41.267574) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:41.292640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:41.293013) ========= 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.vx4ZA846LE/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.fKDjyngYS6/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-07-04 12:48:41-- 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% 133M=0s 2016-07-04 12:48:41 (133 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:41.433959) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:41.437332) ========= 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.vx4ZA846LE/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.fKDjyngYS6/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.029284 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:41.492341) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-04 12:48:41.492702) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev aaf789f8f36624d8a765d63cc1d9ba6fd56e36e9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev aaf789f8f36624d8a765d63cc1d9ba6fd56e36e9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vx4ZA846LE/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.fKDjyngYS6/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-07-04 12:48:41,570 truncating revision to first 12 chars 2016-07-04 12:48:41,571 Setting DEBUG logging. 2016-07-04 12:48:41,571 attempt 1/10 2016-07-04 12:48:41,571 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/aaf789f8f366?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-04 12:48:42,568 unpacking tar archive at: autoland-aaf789f8f366/testing/mozharness/ program finished with exit code 0 elapsedTime=1.263859 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-04 12:48:42.782224) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:42.782553) ========= script_repo_revision: aaf789f8f36624d8a765d63cc1d9ba6fd56e36e9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:42.782971) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:42.783269) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 12:48:42.801595) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-04 12:48:42.801910) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vx4ZA846LE/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.fKDjyngYS6/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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:48:42 INFO - MultiFileLogger online at 20160704 12:48:42 in /builds/slave/test 12:48:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 12:48:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:48:42 INFO - {'append_to_log': False, 12:48:42 INFO - 'base_work_dir': '/builds/slave/test', 12:48:42 INFO - 'blob_upload_branch': 'Autoland', 12:48:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:48:42 INFO - 'branch': 'Autoland', 12:48:42 INFO - 'buildbot_json_path': 'buildprops.json', 12:48:42 INFO - 'config_files': ('talos/mac_config.py',), 12:48:42 INFO - 'default_actions': ('clobber', 12:48:42 INFO - 'read-buildbot-config', 12:48:42 INFO - 'download-and-extract', 12:48:42 INFO - 'populate-webroot', 12:48:42 INFO - 'create-virtualenv', 12:48:42 INFO - 'install', 12:48:42 INFO - 'run-tests'), 12:48:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:48:42 INFO - 'download_minidump_stackwalk': True, 12:48:42 INFO - 'download_symbols': 'ondemand', 12:48:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:48:42 INFO - 'tooltool.py': '/tools/tooltool.py', 12:48:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:48:42 INFO - '/tools/misc-python/virtualenv.py')}, 12:48:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:48:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:48:42 INFO - 'installer_path': 'installer.exe', 12:48:42 INFO - 'log_level': 'info', 12:48:42 INFO - 'log_name': 'talos', 12:48:42 INFO - 'log_to_console': True, 12:48:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:48:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:48:42 INFO - 'opt_config_files': (), 12:48:42 INFO - 'pip_index': False, 12:48:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:48:42 INFO - 'cmd': ('bash', 12:48:42 INFO - '-c', 12:48:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:48:42 INFO - 'enabled': True, 12:48:42 INFO - 'halt_on_failure': False, 12:48:42 INFO - 'name': 'check_screen_resolution'},), 12:48:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:48:42 INFO - 'cmd': ('bash', 12:48:42 INFO - '-c', 12:48:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:48:42 INFO - 'enabled': True, 12:48:42 INFO - 'halt_on_failure': False, 12:48:42 INFO - 'name': 'check_screen_resolution'},), 12:48:42 INFO - 'run_cmd_checks_enabled': True, 12:48:42 INFO - 'sps_profile': False, 12:48:42 INFO - 'sps_profile_interval': 0, 12:48:42 INFO - 'suite': 'dromaeojs', 12:48:42 INFO - 'system_bits': '32', 12:48:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:48:42 INFO - 'title': 't-yosemite-r7-0019', 12:48:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:48:42 INFO - 'use_talos_json': True, 12:48:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:48:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:48:42 INFO - 'work_dir': 'build'} 12:48:42 INFO - ##### 12:48:42 INFO - ##### Running clobber step. 12:48:42 INFO - ##### 12:48:42 INFO - Running pre-action listener: _resource_record_pre_action 12:48:42 INFO - Running main action method: clobber 12:48:42 INFO - rmtree: /builds/slave/test/build 12:48:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:48:45 INFO - Running post-action listener: _resource_record_post_action 12:48:45 INFO - ##### 12:48:45 INFO - ##### Finished clobber step (success) 12:48:45 INFO - ##### 12:48:45 INFO - ##### 12:48:45 INFO - ##### Running read-buildbot-config step. 12:48:45 INFO - ##### 12:48:45 INFO - Running pre-action listener: _resource_record_pre_action 12:48:45 INFO - Running main action method: read_buildbot_config 12:48:45 INFO - Using buildbot properties: 12:48:45 INFO - { 12:48:45 INFO - "project": "", 12:48:45 INFO - "product": "firefox", 12:48:45 INFO - "script_repo_revision": "production", 12:48:45 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 12:48:45 INFO - "repository": "", 12:48:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 12:48:45 INFO - "buildid": "20160704110523", 12:48:45 INFO - "pgo_build": "False", 12:48:45 INFO - "basedir": "/builds/slave/test", 12:48:45 INFO - "buildnumber": 1, 12:48:45 INFO - "slavename": "t-yosemite-r7-0019", 12:48:45 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:48:45 INFO - "platform": "yosemite_r7", 12:48:45 INFO - "scheduler": "tests-autoland-macosx64-talos", 12:48:45 INFO - "branch": "autoland", 12:48:45 INFO - "repo_path": "integration/autoland", 12:48:45 INFO - "revision": "aaf789f8f36624d8a765d63cc1d9ba6fd56e36e9", 12:48:45 INFO - "stage_platform": "macosx64", 12:48:45 INFO - "builduid": "4489b67749224ad8bbbbd5759698190e", 12:48:45 INFO - "slavebuilddir": "test" 12:48:45 INFO - } 12:48:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 12:48:45 INFO - Running post-action listener: _resource_record_post_action 12:48:45 INFO - ##### 12:48:45 INFO - ##### Finished read-buildbot-config step (success) 12:48:45 INFO - ##### 12:48:45 INFO - ##### 12:48:45 INFO - ##### Running download-and-extract step. 12:48:45 INFO - ##### 12:48:45 INFO - Running pre-action listener: _resource_record_pre_action 12:48:45 INFO - Running main action method: download_and_extract 12:48:45 INFO - mkdir: /builds/slave/test/build/tests 12:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:45 INFO - https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:48:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:48:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:48:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:48:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 12:48:46 INFO - Downloaded 1199 bytes. 12:48:46 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:48:46 INFO - Using the following test package requirements: 12:48:46 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:48:46 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:48:46 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 12:48:46 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:48:46 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 12:48:46 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:48:46 INFO - u'jsshell-mac.zip'], 12:48:46 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:48:46 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 12:48:46 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:48:46 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:48:46 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 12:48:46 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:48:46 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 12:48:46 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:48:46 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 12:48:46 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:48:46 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 12:48:46 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:46 INFO - https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:48:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:48:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:48:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:48:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:48:47 INFO - Downloaded 18795149 bytes. 12:48:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:48:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:48:50 INFO - Return code: 0 12:48:50 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:50 INFO - https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:48:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:48:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:48:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:48:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:48:52 INFO - Downloaded 18795149 bytes. 12:48:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:48:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:48:56 INFO - Return code: 0 12:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:56 INFO - https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:48:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:48:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:48:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:48:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:48:57 INFO - Downloaded 14403333 bytes. 12:48:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:48:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:48:57 INFO - Return code: 0 12:48:57 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:57 INFO - https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:48:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:48:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:48:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 12:48:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 12:49:00 INFO - Downloaded 112413338 bytes. 12:49:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:49:00 INFO - mkdir: /builds/slave/test/properties 12:49:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:49:00 INFO - Writing to file /builds/slave/test/properties/build_url 12:49:00 INFO - Contents: 12:49:00 INFO - build_url:https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:49:01 INFO - Running post-action listener: _resource_record_post_action 12:49:01 INFO - Running post-action listener: set_extra_try_arguments 12:49:01 INFO - ##### 12:49:01 INFO - ##### Finished download-and-extract step (success) 12:49:01 INFO - ##### 12:49:01 INFO - ##### 12:49:01 INFO - ##### Running populate-webroot step. 12:49:01 INFO - ##### 12:49:01 INFO - Running pre-action listener: _resource_record_pre_action 12:49:01 INFO - Running main action method: populate_webroot 12:49:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:49:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:49:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:49:01 INFO - '--tppagecycles', 12:49:01 INFO - '7'], 12:49:01 INFO - 'tests': ['tsvgm']}}, 12:49:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:49:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:49:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:49:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:49:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:49:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:49:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:49:01 INFO - 'talos_options': ['--disable-e10s'], 12:49:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:49:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:49:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:49:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:49:01 INFO - 'talos_options': ['--disable-e10s'], 12:49:01 INFO - 'tests': ['damp', 'tps']}, 12:49:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:49:01 INFO - 'tests': ['damp', 'tps']}, 12:49:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:49:01 INFO - 'tests': ['dromaeo_dom']}, 12:49:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:49:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:49:01 INFO - 'tests': ['basic_compositor_video']}, 12:49:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:49:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:49:01 INFO - 'tests': ['a11yr', 12:49:01 INFO - 'ts_paint', 12:49:01 INFO - 'tpaint', 12:49:01 INFO - 'sessionrestore', 12:49:01 INFO - 'sessionrestore_no_auto_restore', 12:49:01 INFO - 'tabpaint']}, 12:49:01 INFO - 'other-e10s': {'tests': ['a11yr', 12:49:01 INFO - 'ts_paint', 12:49:01 INFO - 'tpaint', 12:49:01 INFO - 'sessionrestore', 12:49:01 INFO - 'sessionrestore_no_auto_restore', 12:49:01 INFO - 'tabpaint']}, 12:49:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:49:01 INFO - 'ts_paint', 12:49:01 INFO - 'tpaint', 12:49:01 INFO - 'sessionrestore', 12:49:01 INFO - 'sessionrestore_no_auto_restore', 12:49:01 INFO - 'tabpaint']}, 12:49:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:49:01 INFO - 'ts_paint', 12:49:01 INFO - 'tpaint', 12:49:01 INFO - 'sessionrestore', 12:49:01 INFO - 'sessionrestore_no_auto_restore', 12:49:01 INFO - 'tabpaint']}, 12:49:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:49:01 INFO - 'tests': ['a11yr', 12:49:01 INFO - 'ts_paint', 12:49:01 INFO - 'tpaint', 12:49:01 INFO - 'sessionrestore', 12:49:01 INFO - 'sessionrestore_no_auto_restore', 12:49:01 INFO - 'tabpaint']}, 12:49:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:49:01 INFO - 'tests': ['a11yr', 12:49:01 INFO - 'ts_paint', 12:49:01 INFO - 'tpaint', 12:49:01 INFO - 'sessionrestore', 12:49:01 INFO - 'sessionrestore_no_auto_restore', 12:49:01 INFO - 'tabpaint']}, 12:49:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:49:01 INFO - 'tests': ['tsvgx', 12:49:01 INFO - 'tsvgr_opacity', 12:49:01 INFO - 'tart', 12:49:01 INFO - 'tscrollx', 12:49:01 INFO - 'cart']}, 12:49:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:49:01 INFO - 'tsvgr_opacity', 12:49:01 INFO - 'tart', 12:49:01 INFO - 'tscrollx', 12:49:01 INFO - 'cart']}, 12:49:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:49:01 INFO - 'talos_options': ['--disable-e10s'], 12:49:01 INFO - 'tests': ['tp5o']}, 12:49:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:49:01 INFO - 'tests': ['tp5o']}, 12:49:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:49:01 INFO - 'talos_options': ['--disable-e10s', 12:49:01 INFO - '--xperf_path', 12:49:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:49:01 INFO - 'tests': ['tp5n']}, 12:49:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:49:01 INFO - 'talos_options': ['--xperf_path', 12:49:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:49:01 INFO - 'tests': ['tp5n']}}, 12:49:01 INFO - 'talos.zip': {'path': '', 12:49:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:49:01 INFO - Running post-action listener: _resource_record_post_action 12:49:01 INFO - ##### 12:49:01 INFO - ##### Finished populate-webroot step (success) 12:49:01 INFO - ##### 12:49:01 INFO - ##### 12:49:01 INFO - ##### Running create-virtualenv step. 12:49:01 INFO - ##### 12:49:01 INFO - Running pre-action listener: _resource_record_pre_action 12:49:01 INFO - Running main action method: create_virtualenv 12:49:01 INFO - Creating virtualenv /builds/slave/test/build/venv 12:49:01 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:49:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:49:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:49:01 INFO - Using real prefix '/tools/python27' 12:49:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:49:02 INFO - Installing distribute.............................................................................................................................................................................................done. 12:49:05 INFO - Installing pip.................done. 12:49:05 INFO - Return code: 0 12:49:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:05 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 0x106f41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6db573350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ea5d30>, '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 0x106c0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e9db20>, '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.vx4ZA846LE/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.fKDjyngYS6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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:49:05 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:49:05 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:49:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vx4ZA846LE/Render', 12:49:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:05 INFO - 'HOME': '/Users/cltbld', 12:49:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:05 INFO - 'LOGNAME': 'cltbld', 12:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:05 INFO - 'MOZ_NO_REMOTE': '1', 12:49:05 INFO - 'NO_EM_RESTART': '1', 12:49:05 INFO - 'PAGER': '/bin/cat', 12:49:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:05 INFO - 'PWD': '/builds/slave/test', 12:49:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:05 INFO - 'SHELL': '/bin/bash', 12:49:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKDjyngYS6/Listeners', 12:49:05 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 12:49:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:05 INFO - 'USER': 'cltbld', 12:49:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:05 INFO - 'XPC_FLAGS': '0x0', 12:49:05 INFO - 'XPC_SERVICE_NAME': '0', 12:49:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:05 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:49:05 INFO - Cleaning up... 12:49:05 INFO - Return code: 0 12:49:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:05 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 0x106f41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6db573350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ea5d30>, '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 0x106c0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e9db20>, '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.vx4ZA846LE/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.fKDjyngYS6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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:49:05 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:49:05 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:49:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vx4ZA846LE/Render', 12:49:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:05 INFO - 'HOME': '/Users/cltbld', 12:49:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:05 INFO - 'LOGNAME': 'cltbld', 12:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:05 INFO - 'MOZ_NO_REMOTE': '1', 12:49:05 INFO - 'NO_EM_RESTART': '1', 12:49:05 INFO - 'PAGER': '/bin/cat', 12:49:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:05 INFO - 'PWD': '/builds/slave/test', 12:49:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:05 INFO - 'SHELL': '/bin/bash', 12:49:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKDjyngYS6/Listeners', 12:49:05 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 12:49:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:05 INFO - 'USER': 'cltbld', 12:49:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:05 INFO - 'XPC_FLAGS': '0x0', 12:49:05 INFO - 'XPC_SERVICE_NAME': '0', 12:49:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:05 INFO - Downloading/unpacking psutil>=3.1.1 12:49:05 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:49:05 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:49:05 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:49:05 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:49:05 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:49:05 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:49:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:49:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:49:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:49:08 INFO - Installing collected packages: psutil 12:49:08 INFO - Running setup.py install for psutil 12:49:08 INFO - building 'psutil._psutil_osx' extension 12:49:08 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:49:09 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:49:09 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:49:09 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:49:09 INFO - building 'psutil._psutil_posix' extension 12:49:09 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:49:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:49:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:49:09 INFO - ^ 12:49:09 INFO - 1 warning generated. 12:49:09 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:49:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:49:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:49:09 INFO - Successfully installed psutil 12:49:09 INFO - Cleaning up... 12:49:09 INFO - Return code: 0 12:49:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:49: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6db573350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ea5d30>, '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 0x106c0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e9db20>, '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.vx4ZA846LE/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.fKDjyngYS6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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:49:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:49:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vx4ZA846LE/Render', 12:49:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:09 INFO - 'HOME': '/Users/cltbld', 12:49:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:09 INFO - 'LOGNAME': 'cltbld', 12:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:09 INFO - 'MOZ_NO_REMOTE': '1', 12:49:09 INFO - 'NO_EM_RESTART': '1', 12:49:09 INFO - 'PAGER': '/bin/cat', 12:49:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:09 INFO - 'PWD': '/builds/slave/test', 12:49:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:09 INFO - 'SHELL': '/bin/bash', 12:49:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKDjyngYS6/Listeners', 12:49:09 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 12:49:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:09 INFO - 'USER': 'cltbld', 12:49:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:09 INFO - 'XPC_FLAGS': '0x0', 12:49:09 INFO - 'XPC_SERVICE_NAME': '0', 12:49:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:49:10 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:49:10 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:49:10 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:49:10 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:49:10 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:49:10 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:49:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:49:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:49:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:49:12 INFO - Installing collected packages: mozsystemmonitor 12:49:12 INFO - Running setup.py install for mozsystemmonitor 12:49:12 INFO - Successfully installed mozsystemmonitor 12:49:12 INFO - Cleaning up... 12:49:12 INFO - Return code: 0 12:49:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:49:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:12 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 0x106f41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6db573350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ea5d30>, '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 0x106c0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e9db20>, '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.vx4ZA846LE/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.fKDjyngYS6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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:49:12 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:49:12 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:49:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vx4ZA846LE/Render', 12:49:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:12 INFO - 'HOME': '/Users/cltbld', 12:49:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:12 INFO - 'LOGNAME': 'cltbld', 12:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:12 INFO - 'MOZ_NO_REMOTE': '1', 12:49:12 INFO - 'NO_EM_RESTART': '1', 12:49:12 INFO - 'PAGER': '/bin/cat', 12:49:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:12 INFO - 'PWD': '/builds/slave/test', 12:49:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:12 INFO - 'SHELL': '/bin/bash', 12:49:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKDjyngYS6/Listeners', 12:49:12 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 12:49:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:12 INFO - 'USER': 'cltbld', 12:49:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:12 INFO - 'XPC_FLAGS': '0x0', 12:49:12 INFO - 'XPC_SERVICE_NAME': '0', 12:49:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:13 INFO - Downloading/unpacking jsonschema==2.5.1 12:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:49:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:49: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:49: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:49: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:49: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:49: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:49: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:49:15 INFO - Downloading functools32-3.2.3-2.tar.gz 12:49:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:49:16 INFO - warning: no files found matching '*.txt' 12:49:16 INFO - no previously-included directories found matching 'build' 12:49:16 INFO - no previously-included directories found matching 'dist' 12:49:16 INFO - no previously-included directories found matching '.git*' 12:49:16 INFO - Installing collected packages: jsonschema, functools32 12:49:16 INFO - Running setup.py install for functools32 12:49:16 INFO - warning: no files found matching '*.txt' 12:49:16 INFO - no previously-included directories found matching 'build' 12:49:16 INFO - no previously-included directories found matching 'dist' 12:49:16 INFO - no previously-included directories found matching '.git*' 12:49:16 INFO - Successfully installed jsonschema functools32 12:49:16 INFO - Cleaning up... 12:49:16 INFO - Return code: 0 12:49:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106f41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6db573350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ea5d30>, '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 0x106c0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e9db20>, '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.vx4ZA846LE/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.fKDjyngYS6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vx4ZA846LE/Render', 12:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:16 INFO - 'HOME': '/Users/cltbld', 12:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:16 INFO - 'LOGNAME': 'cltbld', 12:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:16 INFO - 'MOZ_NO_REMOTE': '1', 12:49:16 INFO - 'NO_EM_RESTART': '1', 12:49:16 INFO - 'PAGER': '/bin/cat', 12:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:16 INFO - 'PWD': '/builds/slave/test', 12:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:16 INFO - 'SHELL': '/bin/bash', 12:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKDjyngYS6/Listeners', 12:49:16 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 12:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:16 INFO - 'USER': 'cltbld', 12:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:16 INFO - 'XPC_FLAGS': '0x0', 12:49:16 INFO - 'XPC_SERVICE_NAME': '0', 12:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:16 INFO - Downloading/unpacking blobuploader==1.2.4 12:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:18 INFO - Downloading blobuploader-1.2.4.tar.gz 12:49:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:49:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:49:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:20 INFO - Downloading docopt-0.6.1.tar.gz 12:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:49:20 INFO - Installing collected packages: blobuploader, requests, docopt 12:49:20 INFO - Running setup.py install for blobuploader 12:49:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:49:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:49:20 INFO - Running setup.py install for requests 12:49:20 INFO - Running setup.py install for docopt 12:49:21 INFO - Successfully installed blobuploader requests docopt 12:49:21 INFO - Cleaning up... 12:49:21 INFO - Return code: 0 12:49:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:49:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:21 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 0x106f41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6db573350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ea5d30>, '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 0x106c0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e9db20>, '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.vx4ZA846LE/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.fKDjyngYS6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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:49:21 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:49:21 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:49:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vx4ZA846LE/Render', 12:49:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:21 INFO - 'HOME': '/Users/cltbld', 12:49:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:21 INFO - 'LOGNAME': 'cltbld', 12:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:21 INFO - 'MOZ_NO_REMOTE': '1', 12:49:21 INFO - 'NO_EM_RESTART': '1', 12:49:21 INFO - 'PAGER': '/bin/cat', 12:49:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:21 INFO - 'PWD': '/builds/slave/test', 12:49:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:21 INFO - 'SHELL': '/bin/bash', 12:49:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKDjyngYS6/Listeners', 12:49:21 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 12:49:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:21 INFO - 'USER': 'cltbld', 12:49:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:21 INFO - 'XPC_FLAGS': '0x0', 12:49:21 INFO - 'XPC_SERVICE_NAME': '0', 12:49:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:49:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-5REpiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:49:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-yrfEJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:49:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-5v5p4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:49:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-MmKSNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:49:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-AfOryX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:49:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-4px3Kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:49:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-kLltiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:49:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ot7eO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:49:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-OfZS3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:49:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-QuvCHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:49:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-nsRg1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:49:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-5LTf5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:49:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-jHKuRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:49:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-_KB5G1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-8fTNPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:49:23 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-fqVsIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:49:23 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-6H4ZMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:49:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:49:23 INFO - Running setup.py install for manifestparser 12:49:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:49:23 INFO - Running setup.py install for mozcrash 12:49:23 INFO - Running setup.py install for mozdebug 12:49:23 INFO - Running setup.py install for mozdevice 12:49:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:49:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:49:23 INFO - Running setup.py install for mozfile 12:49:24 INFO - Running setup.py install for mozhttpd 12:49:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:49:24 INFO - Running setup.py install for mozinfo 12:49:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:49:24 INFO - Running setup.py install for mozInstall 12:49:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:49:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:49:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:49:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:49:24 INFO - Running setup.py install for mozleak 12:49:24 INFO - Running setup.py install for mozlog 12:49:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:49:24 INFO - Running setup.py install for moznetwork 12:49:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:49:24 INFO - Running setup.py install for mozprocess 12:49:25 INFO - Running setup.py install for mozprofile 12:49:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:49:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:49:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:49:25 INFO - Running setup.py install for mozrunner 12:49:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:49:25 INFO - Running setup.py install for mozscreenshot 12:49:25 INFO - Running setup.py install for moztest 12:49:25 INFO - Running setup.py install for mozversion 12:49:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:49:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:49:25 INFO - Cleaning up... 12:49:25 INFO - Return code: 0 12:49:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:25 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 0x106f41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6db573350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ea5d30>, '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 0x106c0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e9db20>, '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.vx4ZA846LE/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.fKDjyngYS6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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:49:25 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:49:25 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:49:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vx4ZA846LE/Render', 12:49:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:25 INFO - 'HOME': '/Users/cltbld', 12:49:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:25 INFO - 'LOGNAME': 'cltbld', 12:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:25 INFO - 'MOZ_NO_REMOTE': '1', 12:49:25 INFO - 'NO_EM_RESTART': '1', 12:49:25 INFO - 'PAGER': '/bin/cat', 12:49:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:25 INFO - 'PWD': '/builds/slave/test', 12:49:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:25 INFO - 'SHELL': '/bin/bash', 12:49:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKDjyngYS6/Listeners', 12:49:25 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 12:49:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:25 INFO - 'USER': 'cltbld', 12:49:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:25 INFO - 'XPC_FLAGS': '0x0', 12:49:25 INFO - 'XPC_SERVICE_NAME': '0', 12:49:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:49:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-XCVpwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:49:26 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:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:49:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-gWXvUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:49:26 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:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:49:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Qkw0NP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:49:26 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:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:49:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-e19V6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:49:26 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:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:49:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-H5rhyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:49:26 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:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:49:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-GLzBE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:49:26 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:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:49:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-uPyB5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:49:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:49:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-aKba1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:49:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:49:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-iuHcTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:49:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ntaVJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:49:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-9ejp1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:49:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-XbszZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:49:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-U0PBe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:49:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-zKI34Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-p7ngO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:49:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-IeI_B_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:49:27 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:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:49:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-V62uRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:49:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:49:30 INFO - Downloading blessings-1.6.tar.gz 12:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:49:30 INFO - Installing collected packages: blessings 12:49:30 INFO - Running setup.py install for blessings 12:49:30 INFO - Successfully installed blessings 12:49:30 INFO - Cleaning up... 12:49:30 INFO - Return code: 0 12:49:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:49:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:49:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:49:31 INFO - Reading from file tmpfile_stdout 12:49:31 INFO - Current package versions: 12:49:31 INFO - blessings == 1.6 12:49:31 INFO - blobuploader == 1.2.4 12:49:31 INFO - docopt == 0.6.1 12:49:31 INFO - functools32 == 3.2.3-2 12:49:31 INFO - jsonschema == 2.5.1 12:49:31 INFO - manifestparser == 1.1 12:49:31 INFO - mozInstall == 1.12 12:49:31 INFO - mozcrash == 0.17 12:49:31 INFO - mozdebug == 0.1 12:49:31 INFO - mozdevice == 0.48 12:49:31 INFO - mozfile == 1.2 12:49:31 INFO - mozhttpd == 0.7 12:49:31 INFO - mozinfo == 0.9 12:49:31 INFO - mozleak == 0.1 12:49:31 INFO - mozlog == 3.2 12:49:31 INFO - moznetwork == 0.27 12:49:31 INFO - mozprocess == 0.22 12:49:31 INFO - mozprofile == 0.28 12:49:31 INFO - mozrunner == 6.11 12:49:31 INFO - mozscreenshot == 0.1 12:49:31 INFO - mozsystemmonitor == 0.3 12:49:31 INFO - moztest == 0.7 12:49:31 INFO - mozversion == 1.4 12:49:31 INFO - psutil == 3.1.1 12:49:31 INFO - requests == 1.2.3 12:49:31 INFO - wsgiref == 0.1.2 12:49:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:31 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 0x106f41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6db573350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ea5d30>, '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 0x106c0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e9db20>, '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.vx4ZA846LE/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.fKDjyngYS6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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:49:31 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:49:31 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:49:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vx4ZA846LE/Render', 12:49:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:31 INFO - 'HOME': '/Users/cltbld', 12:49:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:31 INFO - 'LOGNAME': 'cltbld', 12:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:31 INFO - 'MOZ_NO_REMOTE': '1', 12:49:31 INFO - 'NO_EM_RESTART': '1', 12:49:31 INFO - 'PAGER': '/bin/cat', 12:49:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:31 INFO - 'PWD': '/builds/slave/test', 12:49:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:31 INFO - 'SHELL': '/bin/bash', 12:49:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKDjyngYS6/Listeners', 12:49:31 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 12:49:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:31 INFO - 'USER': 'cltbld', 12:49:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:31 INFO - 'XPC_FLAGS': '0x0', 12:49:31 INFO - 'XPC_SERVICE_NAME': '0', 12:49:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:31 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:49:31 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:49:31 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:49:31 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:49:31 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:49:31 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:49:31 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:49:31 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:49:31 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:49:31 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:49:31 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:49:31 INFO - Cleaning up... 12:49:31 INFO - Return code: 0 12:49:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:49:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:49:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:49:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:49:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f41a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fd6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6db573350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ea5d30>, '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 0x106c0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e9db20>, '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.vx4ZA846LE/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.fKDjyngYS6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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:49:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:49:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vx4ZA846LE/Render', 12:49:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:31 INFO - 'HOME': '/Users/cltbld', 12:49:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:31 INFO - 'LOGNAME': 'cltbld', 12:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:31 INFO - 'MOZ_NO_REMOTE': '1', 12:49:31 INFO - 'NO_EM_RESTART': '1', 12:49:31 INFO - 'PAGER': '/bin/cat', 12:49:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:31 INFO - 'PWD': '/builds/slave/test', 12:49:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:31 INFO - 'SHELL': '/bin/bash', 12:49:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKDjyngYS6/Listeners', 12:49:31 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 12:49:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:31 INFO - 'USER': 'cltbld', 12:49:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:31 INFO - 'XPC_FLAGS': '0x0', 12:49:31 INFO - 'XPC_SERVICE_NAME': '0', 12:49:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:49:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:49:31 INFO - Cleaning up... 12:49:31 INFO - Return code: 0 12:49:31 INFO - Running post-action listener: _resource_record_post_action 12:49:31 INFO - Running post-action listener: _start_resource_monitoring 12:49:31 INFO - Starting resource monitoring. 12:49:31 INFO - ##### 12:49:31 INFO - ##### Finished create-virtualenv step (success) 12:49:31 INFO - ##### 12:49:31 INFO - ##### 12:49:31 INFO - ##### Running install step. 12:49:31 INFO - ##### 12:49:31 INFO - Running pre-action listener: _resource_record_pre_action 12:49:31 INFO - Running main action method: install 12:49:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:49:31 INFO - Reading from file tmpfile_stdout 12:49:31 INFO - Detecting whether we're running mozinstall >=1.0... 12:49:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:49:31 INFO - Reading from file tmpfile_stdout 12:49:31 INFO - Output received: 12:49:31 INFO - Usage: mozinstall [options] installer 12:49:31 INFO - Options: 12:49:31 INFO - -h, --help show this help message and exit 12:49:31 INFO - -d DEST, --destination=DEST 12:49:31 INFO - Directory to install application into. [default: 12:49:31 INFO - "/builds/slave/test"] 12:49:31 INFO - --app=APP Application being installed. [default: firefox] 12:49:31 INFO - mkdir: /builds/slave/test/build/application 12:49:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:50:06 INFO - Reading from file tmpfile_stdout 12:50:06 INFO - Output received: 12:50:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:50:06 INFO - Running post-action listener: _resource_record_post_action 12:50:06 INFO - ##### 12:50:06 INFO - ##### Finished install step (success) 12:50:06 INFO - ##### 12:50:06 INFO - ##### 12:50:06 INFO - ##### Running run-tests step. 12:50:06 INFO - ##### 12:50:06 INFO - Running pre-action listener: _resource_record_pre_action 12:50:06 INFO - Running main action method: run_tests 12:50:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:50:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:50:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:50:06 INFO - 2016-07-04 12:50:06.343 screenresolution[1640:7478] starting screenresolution argv=screenresolution get 12:50:06 INFO - 2016-07-04 12:50:06.346 screenresolution[1640:7478] Display 0: 1600x1200x32@60 12:50:06 INFO - 2016-07-04 12:50:06.351 screenresolution[1641:7480] starting screenresolution argv=screenresolution list 12:50:06 INFO - Available Modes on Display 0 12:50:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:50:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:50:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:50:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:50:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:50:06 INFO - Intel Iris: 12:50:06 INFO - Chipset Model: Intel Iris 12:50:06 INFO - Type: GPU 12:50:06 INFO - Bus: Built-In 12:50:06 INFO - VRAM (Dynamic, Max): 1536 MB 12:50:06 INFO - Vendor: Intel (0x8086) 12:50:06 INFO - Device ID: 0x0a2e 12:50:06 INFO - Revision ID: 0x0009 12:50:06 INFO - Displays: 12:50:06 INFO - Display: 12:50:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:50:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:50:06 INFO - Display Serial Number: 1600x1200 60 12:50:06 INFO - Main Display: Yes 12:50:06 INFO - Mirror: Off 12:50:06 INFO - Online: Yes 12:50:06 INFO - Rotation: Supported 12:50:06 INFO - Return code: 0 12:50:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:50:06 INFO - Python 2.7.3 12:50:06 INFO - Return code: 0 12:50:06 INFO - grabbing minidump binary from tooltool 12:50:06 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:50:06 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 0x106ea5d30>, '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 0x106c0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e9db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:50:06 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:50:06 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:50:06 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:50:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:50:06 INFO - Return code: 0 12:50:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:50:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:50:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:50:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:50:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:50:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--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:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0019 --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:50:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vx4ZA846LE/Render', 12:50:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:06 INFO - 'HOME': '/Users/cltbld', 12:50:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:06 INFO - 'LOGNAME': 'cltbld', 12:50:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:50:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:06 INFO - 'MOZ_NO_REMOTE': '1', 12:50:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:50:06 INFO - 'NO_EM_RESTART': '1', 12:50:06 INFO - 'PAGER': '/bin/cat', 12:50:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:06 INFO - 'PWD': '/builds/slave/test', 12:50:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:50:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:06 INFO - 'SHELL': '/bin/bash', 12:50:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKDjyngYS6/Listeners', 12:50:06 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 12:50:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:06 INFO - 'USER': 'cltbld', 12:50:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:06 INFO - 'XPC_FLAGS': '0x0', 12:50:06 INFO - 'XPC_SERVICE_NAME': '0', 12:50:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--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:50:06 INFO - mozversion application_buildid: 20160704110523 12:50:06 INFO - mozversion application_changeset: aaf789f8f36624d8a765d63cc1d9ba6fd56e36e9 12:50:06 INFO - mozversion application_display_name: Nightly 12:50:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:50:06 INFO - mozversion application_name: Firefox 12:50:06 INFO - mozversion application_remotingname: firefox 12:50:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 12:50:06 INFO - mozversion application_vendor: Mozilla 12:50:06 INFO - mozversion application_version: 50.0a1 12:50:06 INFO - mozversion platform_buildid: 20160704110523 12:50:06 INFO - mozversion platform_changeset: aaf789f8f36624d8a765d63cc1d9ba6fd56e36e9 12:50:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 12:50:06 INFO - mozversion platform_version: 50.0a1 12:50:06 INFO - using testdate: 1467661806 12:50:06 INFO - actual date: 1467661806 12:50:06 INFO - starting webserver on 'localhost:49280' 12:50:06 INFO - SUITE-START | Running 2 tests 12:50:06 INFO - TEST-START | dromaeo_css 12:50:06 INFO - operating with platform_type : mac_ 12:50:06 INFO - Initialising browser for dromaeo_css test... 12:50:06 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpbJmYvp/profile http://localhost:49280/getInfo.html) 12:50:09 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 12:50:09 INFO - PROCESS | 1651 | colorDepth:24 12:50:09 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 12:50:09 INFO - PROCESS | 1651 | __metrics 12:50:09 INFO - PROCESS | 1651 | 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:50:09 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:50:10 INFO - TEST-INFO | 1651: exit 0 12:50:10 INFO - Browser initialized. 12:50:10 INFO - Running cycle 1/1 for dromaeo_css test... 12:50:10 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpbJmYvp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:50:22 INFO - PROCESS | 1653 | RSS: Main: 243953664 12:50:22 INFO - PROCESS | 1653 | 12:51:13 INFO - PROCESS | 1653 | 1467661873964 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:53:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 12:53:25 INFO - PROCESS | 1653 | RSS: Main: 349601792 12:53:25 INFO - PROCESS | 1653 | 12:56:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 12:56:29 INFO - PROCESS | 1653 | RSS: Main: 378159104 12:56:29 INFO - PROCESS | 1653 | 12:59:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 12:59:32 INFO - PROCESS | 1653 | RSS: Main: 374517760 12:59:32 INFO - PROCESS | 1653 | 13:02:34 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 13:02:35 INFO - PROCESS | 1653 | RSS: Main: 371691520 13:02:35 INFO - PROCESS | 1653 | 13:05:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 13:05:39 INFO - PROCESS | 1653 | RSS: Main: 340668416 13:05:39 INFO - PROCESS | 1653 | 13:08:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 13:08:42 INFO - PROCESS | 1653 | RSS: Main: 381415424 13:08:42 INFO - PROCESS | 1653 | 13:08:42 INFO - PROCESS | 1653 | __start_tp_report 13:08:42 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 13:08:42 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 13:08:42 INFO - PROCESS | 1653 | |i|pagename|runs| 13:08:42 INFO - PROCESS | 1653 | |0;dojo.html;3324.6675332466757;3333.3499967506345;3353.2790450053235;3363;3405.0465869556533;5514.255575497306;5565.749541270642;5651.2370829937945;5677.0632845580485;5743;5648.4633959773755;5685;5690.7723691052415;5692.57305702072;5709.4290570942885;5144.588432925367;5189;5201.869953251161;5214.817481388152;5255.421903590602;5234.5027222414;5239.607029472786;5250.7112108834;5256.973715131419;5262.079136151177;5163.81926632568;5188.16989281715;5197.246399272103;5210.635255532115;5250.31745873036;5292.364916210079;5293.86765330866;5302.310699609065;5318.22885681574;5361.5978801589645;5076.44159142494;5112.079825631385;5134.383873935151;5185.351831021122;5192.7403629818355;5556.361018482886;5561.860953476155;5625.71871406428;5664.405237450091;5675.631083979528;6203.782867599657;6218.968905155491;6220.813375598739;6237.719302631393;6259.092431597438;4780.521947805191;6169.259688837324;6250.906091434026;6284.811455656338;6289.496840252768;5797.420257974169;5800.187973683687;5845.181674565564;5845.590808643439;5849.590528662995;6268.749250029948;6282.057691346335;6282.089097080898;6299.6535190564955;6301.8109456716375;6056.3338032816355;6100.664463454553;6123.4351415035735;6129.019356902874;6177.722002509898;6237.719302631393;6246.281677607133;6271.466925311308;6273.027680709497;6286.15136956505;6175.567710260329;6216.6270023797815;6218.129461875341;6223.595466294676;6226.283977342573;6104.76233045773;6154.230721159855;6180.103884936659;6192.59748116371;6217.595856269328;4931.975340123348;4949.82675606352;4963.8262660806695;4969.652124351261;4997.775100120503;4373.190959672471;4434.157510073076;4445.244308467568;4454.643628509712;4485.641148708096;5642.153676948408;5654.85862853432;5690.231818704505;5705.286839145107;5709.143628455765;6202.875942481215;6228.096925945712;6240.375962403814;6243.375662433811;6258.405451482102;5621.606487545914;5697.116946873241;5699;5712.571557133148;5721.284839395075;5334.879675268237;5371.597130215249;5388.568914486832;5404.621676482687;5415.323084614423;6344.206974128327;6355.046742988588;6363.236411630634;6369.713362898588;6380.776672816429;6141.078838174131;6172.567920245539;6175.938240617536;6264.467520260647;6264.59280146789;6223.502119830312;6270.7491700331475;6301;6308.27454842699;6338.239411270677;6155.876882462236;6175.84560385994;6185.165002724574;6186.19579454668;6188.783392581238;6134.2332208473945;6149.0776383542825;6172.845678858064;6242.344553821783;6260.186175797117;5844.503217226584;6129.448349648553;6139.815805525842;6142.293636231891;6147.600405973598;6111.083337499411;6128.540359473147;6131.877362452637;6134.171886795226;6145.692715364304;5766.308043034863;5935.970320148372;5980.760769569169;5991.011483105413;6006.669633170218;6111.1361034884785;6137.3248942617165;6139.969300153471;6157.89157951573;6172.598781079216;5012;6050.697465126814;6054.54590905675;6058.818235452944;6070.7875224366935 13:08:42 INFO - PROCESS | 1653 | |1;ext.html;494.9703017818929;509.2412305664556;516.9715665638389;529.9046171689095;535.6063293479292;30587.847060764692;32608;42029.36955945663;43709;43716.3442548362;45582.54417455824;45588.77205613976;45832.77083614577;45834.083318333614;46100.53899461004;9697.321187516876;9886.555105020292;9897.4556399398;9907.356021858592;9910.851337229948;8918.955405222965;9140.268778497737;9141.725748227564;9168.991410944791;9186.908130918706;11839.171258011942;12057.397130143501;12079.214851034654;12331.26012439252;12342.93828530856;12162.08784341178;12247.571335003233;12269.202501837352;12274.324912129829;12279.754404911851;8800.647974081028;11651.708707282301;11955.40222988847;12033.157678962476;12111.939440302742;43265.56734432647;43534.78232608816;43583.564164358584;43585.56414435879;43632.56367436317;3535.169235229719;3581.212133330663;3642.271545690846;4019.216252830699;4099.85650502234;32325.191870203198;33618.83190584056;33747.98756037323;33923.6607633923;34131.65868341334;3330.8667653293837;3356.1777364545596;3369.5619569456057;3411.3518431497937;3425.9828700856337;522509.9999999962;549099;551161;551987.2400637971;552387.4761252336;63634.6818265915;64032;64440.03339949904;64453.35546644474;64608.35391646117;66671.33328666652;66684.6665766668;66766.33233667698;66924;67046.66476667683;16325.183740812912;16790.16049197535;16801.327946882233;16838.24227909747;16863.072531010668;12936;12983.02627302956;12990.350482475838;12999.610011699664;13013.609591712264;13771.449142034206;14733.705325893636;15251.694966100617;16609.335626575044;17275.308987640605;6091.177691011745;6490.578112422678;6633.635150066696;6763.492738044666;6765.695543700545;718.353481866314;999.01097913067;1090.9781804364027;1094.5621751299543;1151.2056680890325;31295.53056704151;31311;31382.52926206109;31596;32146.357072858413;1071.4589132488043;1095.3482677806692;1097.6871591596357;1137.3460260350166;1220.097128125181;16542.255598498097;16552;16554.42059527911;16697.749533757004;16988.405405810736;604051.9797401073;780499;949155;952138;952672;54905.72547137239;63770.68114659397;65029;65069.34930650633;65348;1460.6859525202133;1471.1834931613057;1472.057882954931;1488.397199134374;1492.149474799354;32181.517277240393;32216.19459513531;32250.677493225707;32568.20874851906;32886.67113328932;54400;54539.18191227056;54964.45035549593;55051.724741376034;55160.72419638037;629209;633942;634029;635595;636841;21721.674174887707;23992.520149596563;24314.75685243125;26925.865370673022;27359;47475.525244747114;49151.8456334283;49380.50619493759;49788.502114978386;49908.750456247486;38897.416538752506;40968.79515602403;41001.75764376439;41196.38205426981;41972.95067623334;40092.59907400889;41027.97430064272;41412.585874140874;42049;42366.78816605897;39688.40467392933;39858.601413986646;40253.79873100616;40422.39366409565;40661.59338406578;34563.82718086393;34664;36251.912442626766;36885.81557092197;37624.62375376211;26583.468330633666;26754.732452675224;26796;26985.1904442867;27525.587116193674 13:08:42 INFO - PROCESS | 1653 | |2;jquery.html;380.56425392925104;380.85337145199105;381.4335711468469;399.35504160780346;403.5077205808914;19591.804081959195;21383.251586194485;22236.444088897784;22252.443688907784;22615.208467703633;22154.003069861854;22201.2229571965;22245.44386390341;22549.985250663758;22631.207907723234;4125.814338354774;4779.761011949406;5540.806071787489;5576.721163941807;5585.134304182858;4005.218982298453;4215.683823713219;5460.972695136519;5884.2056322396575;6175.66033868137;6194.194754681885;6198.194234749476;6199.380061993809;6202.9689851550675;6214.5960512566535;4677.789499472532;6227.003679411295;6232.345603711613;6236.750529978795;6239.064140378934;3679.9264014719824;5198.882240318349;5575.637583557056;6052.21321228242;6065.180445866266;13576;13587.796183057164;13750.243736594583;13817.723645527134;13828.239446830421;2025.7771645119026;2193.1337121836787;2389.988050059756;2413.843100198499;2940.985360050779;10231.130353919925;10252.128569071638;10544.420056896868;10617.681469555926;13950.51173208943;1792.9462116136538;1869.6073824496873;1887.811218878115;1909.7230901519201;2011.6077364913847;87821.56089219578;113766.43116784446;116495.25257121066;122308;124969.12546311812;11603.6518904433;11656;11678;11713.88286117128;11841.934225919536;14778.039427437183;14832.703345933021;14855.8858085644;14865.405383784528;14927.179005154603;7935.285824275843;7950.165232650604;8499;8652.307815374756;8726.69456569017;8560.914390856136;8590.926134233221;8620.181082797124;8724.607392667345;8744.737657870273;4538.72767633943;4580.564846339593;4635.281531362644;4663.417072865892;4671.27595222741;1475.2844870237925;1791.6654521428945;1825.4118916542693;1884.6701827180189;1900.7814101378242;844.4004756622867;913.8400779863497;1003.2575893838507;1010.5755582655303;1016.0136932297341;18491.075446227635;20974.370768876957;21848.714460212293;21852.45368865759;21895.797348890213;864.3430992445786;946.1437399153776;1008.5007921079113;1008.6973907827769;1017.3743147963949;10940.726481837859;11190.720231994264;11210.775784484427;11253.774924501628;11284.605038823602;613118;614697;623837;628184;629215;126145;129590;131053.68946310606;131104.344478277;133196.33401832927;985.1232403161192;1002.2583288366702;1021.7905329407603;1023.5240613114937;1023.9539220735085;18186.909065454587;18465.261389544266;18506.907465462587;18625.44123676292;18988.3354082612;35724;39042.41436378514;49219.01561968812;53395.73302133464;55206.44793552013;78612.60693696495;79753.20246797458;80348;81173.59413203133;81341.18658813337;7880.684772609032;7892.68429262846;8057.43597948138;8105.311048560939;8267.875981860398;11883.405829708307;12548.623541293775;12654.936725316315;12787.296698681661;12836.871631283568;8508.319334453352;10472.581096756045;10833.187510936554;10899.618513351994;10963.945180274048;10589.258751887293;10678.519466624177;10700.30448020876;10882.782344353227;10902.400367979837;10975.231733778559;11002.669919902415;11115.166362523005;11118.38848863288;11120.61077862271;7877.291043806085;7992.9200707994505;8000.919990800017;8021.077576078826;8180.345572354078;10474.214433917334;10759.193060520336;10917.344959302467;10945.726356840823;10992.890071099506 13:08:42 INFO - PROCESS | 1653 | |3;mootools.html;1550.620098075971;1582.944596939107;1782.6345599152176;1932.65212261793;1937.3606709785763;8559.657613695452;8871.556422178897;9042.140996605312;9046.728598142057;9057.547122643875;7789.143194248628;8031.558264295461;8480.703175388848;8771.605277762501;8786.033536311;2330.300909727079;2382.8451150675237;2385.2367242482414;2386.3914701751114;2391.151141344824;2215.966760498594;2255.23322070496;2370.3481542575755;2372.715274167094;2416.3838221253645;2098.4963608733947;2176.967345489819;2310.849794763335;2386.6658667786523;2392.557376885282;2364.243442098529;2370.466645004869;2392.3301475586863;2396.604560247557;2404.675368825204;2389.2712722619594;2390.2829151254555;2394.2697477269426;2398.78410943016;2402.1712509184304;8821.691240806605;8869.55652217393;8873.467591944504;8889.111088891124;8894.86657700135;5312.442193569658;5428.4300148484235;5468.644538105011;5488.121900495901;5513.917291240635;3550.4141816600486;3643.9796856880116;3720.2373513429684;3723.2181241938956;3741.158239396141;1108.6673997800708;1116.542217690743;1132.869719982196;1133.7449073958376;1796.2365994452437;11280.661580152608;11688.649340519798;11699.181057326075;11708.53165873364;11719.296842189497;4695.487967750939;4785.856424307276;4792.568668819753;4884.4871288514905;4896.5103489651465;10089.949550252202;10175;10186.592536298613;10194.184465242763;10202.64290749835;5424.267723857229;5426.8914621707345;5431.646942948696;5437.891242175135;5455.699936503531;4880.731559764247;4917.0903382874285;4955.331030310934;4959.628027897922;4974.403071631355;3915.5497117831246;3938.4289278055094;3982.1438390745548;4061.411095391152;4085.4484644573195;1198.6523908066738;1243.241622610207;1246.3518970135478;1261.3819228577931;1273.8853503184573;796.1640277708383;899.9235065019416;917.554985831871;960.6973803251954;1004.6634377483479;5460.34475862899;5478.780848766005;5963.910541341883;5999.370066143079;6028.216331876828;497.0555943706913;656.4912193050328;927.596495524449;1028.141501845952;1061.2995423020923;3994.6205110515034;4036.979815100906;4040.2727509048063;4108.4453598764385;4164.916701665949;10576.36541807494;10812.459377031117;10829.891701083046;10916.890831091747;10957;8472.534010629352;9753.317267791186;10868.836967445495;10895.291806032583;10902.836457453144;8089.150639182919;8386.8322633547;8399.664013439517;8402.159784021671;8421.789455263544;6025.337212906489;6107.541934354852;6256.843578910563;6258.311585725475;6318.589291696025;9845.113939745457;9862.063104005108;10212;10216.897831021892;10259.17926565858;9903.356281841658;10255.897441025494;10453.425061621683;10480.318779279323;10481.633142839964;6852.246252912076;7626.923730762844;7631;7746.457747957589;7753.767386978399;8267.545285009382;8343.66625335004;8433;8438.620262088098;8446.535440550582;6609.867802644017;6743.561668491532;7347.448941329529;7414.369778568882;7542.245775422414;7318.3047610476915;7337.559746415019;7376.0411146550605;7422.517536360119;7488;7064.858702826017;7077.150741911004;7118.501704880816;7128.429725622042;7156.284371562802;5031.647784655039;5035.571976382049;5054.21659642756;5171.663841850268;5227.712475813867;5092.516210959953;5208.8437346879655;5233.476652334736;5286.20706893969;5399.325084364455 13:08:42 INFO - PROCESS | 1653 | |4;prototype.html;303.7554768411429;307.0726406253116;316.89542450991166;321.9050380137859;322.77889645592757;4749.952500474994;5186.170212765958;5458.645188062773;5532.1467308451665;5533.253010843535;4995.52542508462;5005.624578156644;5156.819511317105;5367.221752845835;5369.9463005369935;3211.646718860935;3857.958351245169;4107.589241075899;4151.564085770996;4153.460050193471;3519.665631764991;3803.8668646597375;3885.203533275672;4161.25097482453;4191.874243772691;4023.2154729827694;4093.5087789465306;4106.589341065885;4109.609587089237;4109.876703698879;4063.97968010161;4081.530623978251;4083.795810209507;4090.202410529948;4132.896677583054;3566.5185199997927;3577.4991501189593;3587.4080776671817;3607.7654952428006;3626.1478552540134;4297.312430011214;4637.397138372024;4981.850544483671;5160.716160611165;5226.346706661668;4680.742559159245;4719.528047195287;4728.905421891577;4733.33733277338;4739.810407583693;4234.195502854448;5203.817866374697;5603.971980140073;5662.858428539278;5669.461401166924;3822.4266360045935;3918.2555314490155;3933.8819835404984;3945.1912357966835;3954.0625093704166;5825.038868586678;5829.621074630136;5852.707364631751;5878.853028674274;5906.114082887515;4033.5361433435532;4037.2437989474674;4051.905985383989;4053.128577355824;4062.2687916175373;3634.873189311269;3746.0073080633656;4031.9596804031585;4072.0227145485464;4091.202215567995;4215.178040282116;4616.099860527165;4617.976910115428;4625.398698169284;4651.651126165551;5359.517643412111;5656.773729050792;5664.291963504562;5678.290213723285;5747.39652336507;1158.7856246594238;1278.8209650648917;1358.5463553997129;1377.827771528559;1393.5924716036875;925.8149568552129;931.5202670624634;952.8142012307673;953.7281874665687;955.6750704760415;5229.816956406507;5241.475852414728;5272.261883336336;5277.155655095166;5302.151655735064;5569.215392303848;5687.772489100473;5719.971400142973;5749.798757043483;5756;4015.5984401560195;4454.354118652777;4776.874290940567;4970.042824519405;5115.0792857285305;2447.3358116480963;2594.545954458;2637.5252454558363;2659.4415172813924;2663.933401664935;4833.806647734122;5074.644774865724;5087.311699018256;5111.846644600668;5167.560757335668;4559.954400456019;4756.524347565216;4908.901821963541;5006.924896126561;5059.519345662156;4372.497162826252;4884.778805298675;4933.457319694902;4961.950380496221;5051.358308549809;4375;4463.268934928986;4819.469858315512;4854.441739199896;4879.487653796442;4561.703489273186;4724.669273150984;4788.449328327287;4789.544993225638;4932.249051586635;4528.841490547815;4707.9642478656015;4767.4517430495935;4933.728644924564;4980;3071.6928307169105;3451.2407270401477;3509.4034014218155;3557.928841423105;3573.8927832165077;4211.789410529523;4475.216837053437;4785.473597904158;4814.975925120352;5427.348718153847;4643.791029403753;5677.501899164355;5708.600397972102;5744.5978781486565;5749.8787736391005;4870.00164966174;5501.4223506532835;5637.210790489253;5732.340780810094;5737.397573254915;4932.975335123445;5232.3982741985155;5591.161325801162;5624.85937851557;5641.351244606759;4795.985686011813;5717.5997680163655;5736.368999410145;5742.856428589319;5755.079187330007;4959.504049595012;5648.661080335193;5662.603617746719;5666.376698563237;5669.943300566942 13:08:42 INFO - PROCESS | 1653 | |5;yui.html;727.9417646588272;779.8752199648054;807.9900124843945;835.0480452284393;839.9706010289641;15127.773083403745;16310.673786524263;16370.672586548262;16379;16383.508494745147;14799.55601331959;15014.699706005873;15053.473128440479;15054.548363549084;15059.322330495126;722.9385502232303;746.9775906722807;901.7204666553389;926.8100039491922;940.3323640215456;913.8674892140635;924.075924075924;927.7402327348353;927.7958849053198;929.7350255177275;976.5800705696547;977.4477420257532;979.7207795778205;980.9846808553149;981.40134517944;765.1239330966041;945.0455040409202;974.5224839828504;975.9024097590184;977.4086677560044;722.9096362954631;725.7750097469801;726.3245181980756;727.0729819480152;728.3262981741867;14962.925185374112;14996.925015374962;15018.924905375512;15043.172625505593;15052.247387630574;14048.016638835286;14079.436822527086;14111.506097286649;14130.22283774392;14225;11661;11708.180427370087;11748.588799392066;11876.881231187665;11919.940400297943;4521.480029796549;4552.977235113836;4565.452145742499;4711.646626503026;4730.952690473086;107954.46022769915;110131.44934275358;111508.21229469246;111875;112540;11092.889071109346;11141.498632561554;11146.498407571678;11213.495392707347;11655.650330490098;21910.342689719335;22063.11747530113;22118.778812211993;22122;22152.778472215396;7899.842003159904;7983.12185659577;7993.200679931961;8171.06032806223;8215.917840821634;4479.843205487792;4571.405717256735;4604.355390245369;4684.156851766715;4723.0081682846985;3467.514547963287;3552.378333791576;3582.946255806165;3583.140046388848;3600.927981440409;633.5343522510951;660.5937348530715;662.4402379988902;673.7439772886272;679.6533767778468;551.41274542612;556.41854262296;581.7062383496308;589.1016200294499;641.8908785506475;8242.826511793002;8786.736397908073;9108.271338292921;9118.498482583522;9232.67685631013;575.8733078722674;584.4389386189221;586.9442402971796;642.7396904253785;646.1632186318635;4343.457067866517;4373.081652852937;4426.44669416323;4458;4484.215262329079;94175.11649766966;123202.38398807948;139813;146849.7972530413;155588;121678;124045.13932291023;127348;130641;131899;105817;108970.82058358789;112507.43746281216;114270;116989.41505292419;8262.669493220204;8321.542315172723;8348.540830254395;8356.582170891552;8513.531755753493;52893;61678.69160654168;64162.67918660377;64860.67569662121;64941.35058649353;78535;90116;91507;91562.62656060298;93435.53282233546;11537.769244615229;11629;11645.184837061663;11684.474198660911;11838.112141589243;12166;12297.93851030775;12500.437480313589;12563.560275390317;12693.42879570403;11562;11895.167338286237;12201.20692155007;12301.876981230072;12321.383930803604;10670.679879603314;11705.180637355645;11799.35103569312;11806.881931180922;12286.508539658313;11746.119041072125;11843.644690659294;11912.463939122934;12182;12251.75496490083;9742.512874356113;10023;10077.19382449417;10166.796664066529;10276.691699249035;9912.405255684682;10122.557664697471;10343.327683700536;10378.377297362182;10556.524956376828 13:08:42 INFO - PROCESS | 1653 | __end_tp_report 13:08:42 INFO - PROCESS | 1653 | __start_cc_report 13:08:42 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1142 13:08:42 INFO - PROCESS | 1653 | __end_cc_report 13:08:42 INFO - PROCESS | 1653 | __startTimestamp1467662922928__endTimestamp 13:08:42 INFO - PROCESS | 1653 | 13:08:42 INFO - PROCESS | 1653 | ------- Summary: start ------- 13:08:42 INFO - PROCESS | 1653 | Number of tests: 6 13:08:42 INFO - PROCESS | 1653 | 13:08:42 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5733.59 Median:6006.67 stddev:642.12 (10.7%) stddev-sans-first:615.65 13:08:42 INFO - PROCESS | 1653 | Values: 3324.7 3333.3 3353.3 3363.0 3405.0 5514.3 5565.7 5651.2 5677.1 5743.0 5648.5 5685.0 5690.8 5692.6 5709.4 5144.6 5189.0 5201.9 5214.8 5255.4 5234.5 5239.6 5250.7 5257.0 5262.1 5163.8 5188.2 5197.2 5210.6 5250.3 5292.4 5293.9 5302.3 5318.2 5361.6 5076.4 5112.1 5134.4 5185.4 5192.7 5556.4 5561.9 5625.7 5664.4 5675.6 6203.8 6219.0 6220.8 6237.7 6259.1 4780.5 6169.3 6250.9 6284.8 6289.5 5797.4 5800.2 5845.2 5845.6 5849.6 6268.7 6282.1 6282.1 6299.7 6301.8 6056.3 6100.7 6123.4 6129.0 6177.7 6237.7 6246.3 6271.5 6273.0 6286.2 6175.6 6216.6 6218.1 6223.6 6226.3 6104.8 6154.2 6180.1 6192.6 6217.6 4932.0 4949.8 4963.8 4969.7 4997.8 4373.2 4434.2 4445.2 4454.6 4485.6 5642.2 5654.9 5690.2 5705.3 5709.1 6202.9 6228.1 6240.4 6243.4 6258.4 5621.6 5697.1 5699.0 5712.6 5721.3 5334.9 5371.6 5388.6 5404.6 5415.3 6344.2 6355.0 6363.2 6369.7 6380.8 6141.1 6172.6 6175.9 6264.5 6264.6 6223.5 6270.7 6301.0 6308.3 6338.2 6155.9 6175.8 6185.2 6186.2 6188.8 6134.2 6149.1 6172.8 6242.3 6260.2 5844.5 6129.4 6139.8 6142.3 6147.6 6111.1 6128.5 6131.9 6134.2 6145.7 5766.3 5936.0 5980.8 5991.0 6006.7 6111.1 6137.3 6140.0 6157.9 6172.6 5012.0 6050.7 6054.5 6058.8 6070.8 13:08:42 INFO - PROCESS | 1653 | 13:08:42 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:80411.38 Median:22055.91 stddev:186145.84 (844.0%) stddev-sans-first:186571.32 13:08:42 INFO - PROCESS | 1653 | Values: 495.0 509.2 517.0 529.9 535.6 30587.8 32608.0 42029.4 43709.0 43716.3 45582.5 45588.8 45832.8 45834.1 46100.5 9697.3 9886.6 9897.5 9907.4 9910.9 8919.0 9140.3 9141.7 9169.0 9186.9 11839.2 12057.4 12079.2 12331.3 12342.9 12162.1 12247.6 12269.2 12274.3 12279.8 8800.6 11651.7 11955.4 12033.2 12111.9 43265.6 43534.8 43583.6 43585.6 43632.6 3535.2 3581.2 3642.3 4019.2 4099.9 32325.2 33618.8 33748.0 33923.7 34131.7 3330.9 3356.2 3369.6 3411.4 3426.0 522510.0 549099.0 551161.0 551987.2 552387.5 63634.7 64032.0 64440.0 64453.4 64608.4 66671.3 66684.7 66766.3 66924.0 67046.7 16325.2 16790.2 16801.3 16838.2 16863.1 12936.0 12983.0 12990.4 12999.6 13013.6 13771.4 14733.7 15251.7 16609.3 17275.3 6091.2 6490.6 6633.6 6763.5 6765.7 718.4 999.0 1091.0 1094.6 1151.2 31295.5 31311.0 31382.5 31596.0 32146.4 1071.5 1095.3 1097.7 1137.3 1220.1 16542.3 16552.0 16554.4 16697.7 16988.4 604052.0 780499.0 949155.0 952138.0 952672.0 54905.7 63770.7 65029.0 65069.3 65348.0 1460.7 1471.2 1472.1 1488.4 1492.1 32181.5 32216.2 32250.7 32568.2 32886.7 54400.0 54539.2 54964.5 55051.7 55160.7 629209.0 633942.0 634029.0 635595.0 636841.0 21721.7 23992.5 24314.8 26925.9 27359.0 47475.5 49151.8 49380.5 49788.5 49908.8 38897.4 40968.8 41001.8 41196.4 41973.0 40092.6 41028.0 41412.6 42049.0 42366.8 39688.4 39858.6 40253.8 40422.4 40661.6 34563.8 34664.0 36251.9 36885.8 37624.6 26583.5 26754.7 26796.0 26985.2 27525.6 13:08:42 INFO - PROCESS | 1653 | 13:08:42 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:35229.05 Median:12044.47 stddev:103600.14 (860.1%) stddev-sans-first:103857.72 13:08:42 INFO - PROCESS | 1653 | Values: 380.6 380.9 381.4 399.4 403.5 19591.8 21383.3 22236.4 22252.4 22615.2 22154.0 22201.2 22245.4 22550.0 22631.2 4125.8 4779.8 5540.8 5576.7 5585.1 4005.2 4215.7 5461.0 5884.2 6175.7 6194.2 6198.2 6199.4 6203.0 6214.6 4677.8 6227.0 6232.3 6236.8 6239.1 3679.9 5198.9 5575.6 6052.2 6065.2 13576.0 13587.8 13750.2 13817.7 13828.2 2025.8 2193.1 2390.0 2413.8 2941.0 10231.1 10252.1 10544.4 10617.7 13950.5 1792.9 1869.6 1887.8 1909.7 2011.6 87821.6 113766.4 116495.3 122308.0 124969.1 11603.7 11656.0 11678.0 11713.9 11841.9 14778.0 14832.7 14855.9 14865.4 14927.2 7935.3 7950.2 8499.0 8652.3 8726.7 8560.9 8590.9 8620.2 8724.6 8744.7 4538.7 4580.6 4635.3 4663.4 4671.3 1475.3 1791.7 1825.4 1884.7 1900.8 844.4 913.8 1003.3 1010.6 1016.0 18491.1 20974.4 21848.7 21852.5 21895.8 864.3 946.1 1008.5 1008.7 1017.4 10940.7 11190.7 11210.8 11253.8 11284.6 613118.0 614697.0 623837.0 628184.0 629215.0 126145.0 129590.0 131053.7 131104.3 133196.3 985.1 1002.3 1021.8 1023.5 1024.0 18186.9 18465.3 18506.9 18625.4 18988.3 35724.0 39042.4 49219.0 53395.7 55206.4 78612.6 79753.2 80348.0 81173.6 81341.2 7880.7 7892.7 8057.4 8105.3 8267.9 11883.4 12548.6 12654.9 12787.3 12836.9 8508.3 10472.6 10833.2 10899.6 10963.9 10589.3 10678.5 10700.3 10882.8 10902.4 10975.2 11002.7 11115.2 11118.4 11120.6 7877.3 7992.9 8000.9 8021.1 8180.3 10474.2 10759.2 10917.3 10945.7 10992.9 13:08:42 INFO - PROCESS | 1653 | 13:08:42 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5768.71 Median:4957.48 stddev:3193.41 (64.4%) stddev-sans-first:3186.64 13:08:42 INFO - PROCESS | 1653 | Values: 1550.6 1582.9 1782.6 1932.7 1937.4 8559.7 8871.6 9042.1 9046.7 9057.5 7789.1 8031.6 8480.7 8771.6 8786.0 2330.3 2382.8 2385.2 2386.4 2391.2 2216.0 2255.2 2370.3 2372.7 2416.4 2098.5 2177.0 2310.8 2386.7 2392.6 2364.2 2370.5 2392.3 2396.6 2404.7 2389.3 2390.3 2394.3 2398.8 2402.2 8821.7 8869.6 8873.5 8889.1 8894.9 5312.4 5428.4 5468.6 5488.1 5513.9 3550.4 3644.0 3720.2 3723.2 3741.2 1108.7 1116.5 1132.9 1133.7 1796.2 11280.7 11688.6 11699.2 11708.5 11719.3 4695.5 4785.9 4792.6 4884.5 4896.5 10089.9 10175.0 10186.6 10194.2 10202.6 5424.3 5426.9 5431.6 5437.9 5455.7 4880.7 4917.1 4955.3 4959.6 4974.4 3915.5 3938.4 3982.1 4061.4 4085.4 1198.7 1243.2 1246.4 1261.4 1273.9 796.2 899.9 917.6 960.7 1004.7 5460.3 5478.8 5963.9 5999.4 6028.2 497.1 656.5 927.6 1028.1 1061.3 3994.6 4037.0 4040.3 4108.4 4164.9 10576.4 10812.5 10829.9 10916.9 10957.0 8472.5 9753.3 10868.8 10895.3 10902.8 8089.2 8386.8 8399.7 8402.2 8421.8 6025.3 6107.5 6256.8 6258.3 6318.6 9845.1 9862.1 10212.0 10216.9 10259.2 9903.4 10255.9 10453.4 10480.3 10481.6 6852.2 7626.9 7631.0 7746.5 7753.8 8267.5 8343.7 8433.0 8438.6 8446.5 6609.9 6743.6 7347.4 7414.4 7542.2 7318.3 7337.6 7376.0 7422.5 7488.0 7064.9 7077.2 7118.5 7128.4 7156.3 5031.6 5035.6 5054.2 5171.7 5227.7 5092.5 5208.8 5233.5 5286.2 5399.3 13:08:42 INFO - PROCESS | 1653 | 13:08:42 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4395.50 Median:4786.96 stddev:1317.83 (27.5%) stddev-sans-first:1285.25 13:08:42 INFO - PROCESS | 1653 | Values: 303.8 307.1 316.9 321.9 322.8 4750.0 5186.2 5458.6 5532.1 5533.3 4995.5 5005.6 5156.8 5367.2 5369.9 3211.6 3858.0 4107.6 4151.6 4153.5 3519.7 3803.9 3885.2 4161.3 4191.9 4023.2 4093.5 4106.6 4109.6 4109.9 4064.0 4081.5 4083.8 4090.2 4132.9 3566.5 3577.5 3587.4 3607.8 3626.1 4297.3 4637.4 4981.9 5160.7 5226.3 4680.7 4719.5 4728.9 4733.3 4739.8 4234.2 5203.8 5604.0 5662.9 5669.5 3822.4 3918.3 3933.9 3945.2 3954.1 5825.0 5829.6 5852.7 5878.9 5906.1 4033.5 4037.2 4051.9 4053.1 4062.3 3634.9 3746.0 4032.0 4072.0 4091.2 4215.2 4616.1 4618.0 4625.4 4651.7 5359.5 5656.8 5664.3 5678.3 5747.4 1158.8 1278.8 1358.5 1377.8 1393.6 925.8 931.5 952.8 953.7 955.7 5229.8 5241.5 5272.3 5277.2 5302.2 5569.2 5687.8 5720.0 5749.8 5756.0 4015.6 4454.4 4776.9 4970.0 5115.1 2447.3 2594.5 2637.5 2659.4 2663.9 4833.8 5074.6 5087.3 5111.8 5167.6 4560.0 4756.5 4908.9 5006.9 5059.5 4372.5 4884.8 4933.5 4962.0 5051.4 4375.0 4463.3 4819.5 4854.4 4879.5 4561.7 4724.7 4788.4 4789.5 4932.2 4528.8 4708.0 4767.5 4933.7 4980.0 3071.7 3451.2 3509.4 3557.9 3573.9 4211.8 4475.2 4785.5 4815.0 5427.3 4643.8 5677.5 5708.6 5744.6 5749.9 4870.0 5501.4 5637.2 5732.3 5737.4 4933.0 5232.4 5591.2 5624.9 5641.4 4796.0 5717.6 5736.4 5742.9 5755.1 4959.5 5648.7 5662.6 5666.4 5669.9 13:08:42 INFO - PROCESS | 1653 | 13:08:42 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:24093.04 Median:3592.03 stddev:38327.26 (1067.0%) stddev-sans-first:38394.63 13:08:42 INFO - PROCESS | 1653 | Values: 727.9 779.9 808.0 835.0 840.0 15127.8 16310.7 16370.7 16379.0 16383.5 14799.6 15014.7 15053.5 15054.5 15059.3 722.9 747.0 901.7 926.8 940.3 913.9 924.1 927.7 927.8 929.7 976.6 977.4 979.7 981.0 981.4 765.1 945.0 974.5 975.9 977.4 722.9 725.8 726.3 727.1 728.3 14962.9 14996.9 15018.9 15043.2 15052.2 14048.0 14079.4 14111.5 14130.2 14225.0 11661.0 11708.2 11748.6 11876.9 11919.9 4521.5 4553.0 4565.5 4711.6 4731.0 107954.5 110131.4 111508.2 111875.0 112540.0 11092.9 11141.5 11146.5 11213.5 11655.7 21910.3 22063.1 22118.8 22122.0 22152.8 7899.8 7983.1 7993.2 8171.1 8215.9 4479.8 4571.4 4604.4 4684.2 4723.0 3467.5 3552.4 3582.9 3583.1 3600.9 633.5 660.6 662.4 673.7 679.7 551.4 556.4 581.7 589.1 641.9 8242.8 8786.7 9108.3 9118.5 9232.7 575.9 584.4 586.9 642.7 646.2 4343.5 4373.1 4426.4 4458.0 4484.2 94175.1 123202.4 139813.0 146849.8 155588.0 121678.0 124045.1 127348.0 130641.0 131899.0 105817.0 108970.8 112507.4 114270.0 116989.4 8262.7 8321.5 8348.5 8356.6 8513.5 52893.0 61678.7 64162.7 64860.7 64941.4 78535.0 90116.0 91507.0 91562.6 93435.5 11537.8 11629.0 11645.2 11684.5 11838.1 12166.0 12297.9 12500.4 12563.6 12693.4 11562.0 11895.2 12201.2 12301.9 12321.4 10670.7 11705.2 11799.4 11806.9 12286.5 11746.1 11843.6 11912.5 12182.0 12251.8 9742.5 10023.0 10077.2 10166.8 10276.7 9912.4 10122.6 10343.3 10378.4 10556.5 13:08:42 INFO - PROCESS | 1653 | -------- Summary: end -------- 13:08:42 INFO - PROCESS | 1653 | 13:08:43 INFO - TEST-INFO | 1653: exit 0 13:08:43 INFO - TEST-OK | dromaeo_css | took 1116636ms 13:08:43 INFO - TEST-START | kraken 13:08:43 INFO - operating with platform_type : mac_ 13:08:43 INFO - Initialising browser for kraken test... 13:08:43 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpRhl7Oj/profile http://localhost:49280/getInfo.html) 13:08:45 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 13:08:45 INFO - PROCESS | 1663 | colorDepth:24 13:08:45 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 13:08:45 INFO - PROCESS | 1663 | __metrics 13:08:45 INFO - PROCESS | 1663 | 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" 13:08:45 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:08:46 INFO - TEST-INFO | 1663: exit 0 13:08:46 INFO - Browser initialized. 13:08:46 INFO - Running cycle 1/1 for kraken test... 13:08:46 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpRhl7Oj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:08:58 INFO - PROCESS | 1664 | RSS: Main: 244830208 13:08:58 INFO - PROCESS | 1664 | 13:09:01 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 13:09:02 INFO - PROCESS | 1664 | RSS: Main: 293986304 13:09:02 INFO - PROCESS | 1664 | 13:09:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 13:09:05 INFO - PROCESS | 1664 | RSS: Main: 304238592 13:09:05 INFO - PROCESS | 1664 | 13:09:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 13:09:09 INFO - PROCESS | 1664 | RSS: Main: 390823936 13:09:09 INFO - PROCESS | 1664 | 13:09:11 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 13:09:12 INFO - PROCESS | 1664 | RSS: Main: 292163584 13:09:12 INFO - PROCESS | 1664 | 13:09:14 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:09:14 INFO - PROCESS | 1664 | RSS: Main: 296243200 13:09:14 INFO - PROCESS | 1664 | 13:09:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 13:09:18 INFO - PROCESS | 1664 | RSS: Main: 333864960 13:09:18 INFO - PROCESS | 1664 | 13:09:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 13:09:21 INFO - PROCESS | 1664 | RSS: Main: 335126528 13:09:21 INFO - PROCESS | 1664 | 13:09:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 13:09:25 INFO - PROCESS | 1664 | RSS: Main: 331866112 13:09:25 INFO - PROCESS | 1664 | 13:09:26 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:09:27 INFO - PROCESS | 1664 | RSS: Main: 311685120 13:09:27 INFO - PROCESS | 1664 | 13:09:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:09:29 INFO - PROCESS | 1664 | RSS: Main: 318844928 13:09:29 INFO - PROCESS | 1664 | 13:09:32 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:09:32 INFO - PROCESS | 1664 | RSS: Main: 321966080 13:09:32 INFO - PROCESS | 1664 | 13:09:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:09:36 INFO - PROCESS | 1664 | RSS: Main: 323784704 13:09:36 INFO - PROCESS | 1664 | 13:09:39 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:09:39 INFO - PROCESS | 1664 | RSS: Main: 324108288 13:09:39 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 13:09:41 INFO - PROCESS | 1664 | RSS: Main: 368001024 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | __start_tp_report 13:09:41 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 13:09:41 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 13:09:41 INFO - PROCESS | 1664 | |i|pagename|runs| 13:09:41 INFO - PROCESS | 1664 | |0;ai-astar;101;111;113;112;112;112;112;112;111;112 13:09:41 INFO - PROCESS | 1664 | |1;audio-beat-detection;145;107;106;160;105;106;128;103;139;104 13:09:41 INFO - PROCESS | 1664 | |2;audio-dft;185;184;187;197;265;184;183;180;185;183 13:09:41 INFO - PROCESS | 1664 | |3;audio-fft;78;75;58;106;57;57;62;85;72;71 13:09:41 INFO - PROCESS | 1664 | |4;audio-oscillator;84;85;92;70;70;67;94;69;65;92 13:09:41 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;89;86;89;85;88;85;89;88;90;85 13:09:41 INFO - PROCESS | 1664 | |6;imaging-darkroom;94;94;95;94;94;95;95;105;149;109 13:09:41 INFO - PROCESS | 1664 | |7;imaging-desaturate;82;87;83;85;83;83;83;86;83;84 13:09:41 INFO - PROCESS | 1664 | |8;json-parse-financial;54;53;53;55;55;53;54;55;53;54 13:09:41 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;66;72;80;74;72;69;70;69;70;71 13:09:41 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;87;82;84;84;83;84;83;84;86;104 13:09:41 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;138;149;146;135;134;136;139;146;153;136 13:09:41 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;161;162;162;162;164;162;166;161;163;162 13:09:41 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;66;67;65;65;66;66;65;65;66;64 13:09:41 INFO - PROCESS | 1664 | __end_tp_report 13:09:41 INFO - PROCESS | 1664 | __start_cc_report 13:09:41 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1997 13:09:41 INFO - PROCESS | 1664 | __end_cc_report 13:09:41 INFO - PROCESS | 1664 | __startTimestamp1467662981573__endTimestamp 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | ------- Summary: start ------- 13:09:41 INFO - PROCESS | 1664 | Number of tests: 14 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:3.49 (3.1%) stddev-sans-first:0.60 13:09:41 INFO - PROCESS | 1664 | Values: 101.0 111.0 113.0 112.0 112.0 112.0 112.0 112.0 111.0 112.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:120.30 Median:117.50 stddev:21.03 (17.9%) stddev-sans-first:20.32 13:09:41 INFO - PROCESS | 1664 | Values: 145.0 107.0 106.0 160.0 105.0 106.0 128.0 103.0 139.0 104.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:193.30 Median:185.00 stddev:25.59 (13.8%) stddev-sans-first:26.97 13:09:41 INFO - PROCESS | 1664 | Values: 185.0 184.0 187.0 197.0 265.0 184.0 183.0 180.0 185.0 183.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:72.10 Median:71.50 stddev:15.34 (21.4%) stddev-sans-first:16.12 13:09:41 INFO - PROCESS | 1664 | Values: 78.0 75.0 58.0 106.0 57.0 57.0 62.0 85.0 72.0 71.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:84.50 stddev:11.67 (13.8%) stddev-sans-first:12.22 13:09:41 INFO - PROCESS | 1664 | Values: 84.0 85.0 92.0 70.0 70.0 67.0 94.0 69.0 65.0 92.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.50 stddev:1.96 (2.2%) stddev-sans-first:1.99 13:09:41 INFO - PROCESS | 1664 | Values: 89.0 86.0 89.0 85.0 88.0 85.0 89.0 88.0 90.0 85.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:102.40 Median:94.00 stddev:17.22 (18.3%) stddev-sans-first:17.99 13:09:41 INFO - PROCESS | 1664 | Values: 94.0 94.0 95.0 94.0 94.0 95.0 95.0 105.0 149.0 109.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:83.50 stddev:1.60 (1.9%) stddev-sans-first:1.54 13:09:41 INFO - PROCESS | 1664 | Values: 82.0 87.0 83.0 85.0 83.0 83.0 83.0 86.0 83.0 84.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.88 (1.6%) stddev-sans-first:0.93 13:09:41 INFO - PROCESS | 1664 | Values: 54.0 53.0 53.0 55.0 55.0 53.0 54.0 55.0 53.0 54.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:3.74 (5.2%) stddev-sans-first:3.44 13:09:41 INFO - PROCESS | 1664 | Values: 66.0 72.0 80.0 74.0 72.0 69.0 70.0 69.0 70.0 71.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:84.00 stddev:6.45 (7.7%) stddev-sans-first:6.84 13:09:41 INFO - PROCESS | 1664 | Values: 87.0 82.0 84.0 84.0 83.0 84.0 83.0 84.0 86.0 104.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:142.50 stddev:6.71 (4.7%) stddev-sans-first:7.02 13:09:41 INFO - PROCESS | 1664 | Values: 138.0 149.0 146.0 135.0 134.0 136.0 139.0 146.0 153.0 136.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:162.00 stddev:1.51 (0.9%) stddev-sans-first:1.50 13:09:41 INFO - PROCESS | 1664 | Values: 161.0 162.0 162.0 162.0 164.0 162.0 166.0 161.0 163.0 162.0 13:09:41 INFO - PROCESS | 1664 | 13:09:41 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 13:09:41 INFO - PROCESS | 1664 | Values: 66.0 67.0 65.0 65.0 66.0 66.0 65.0 65.0 66.0 64.0 13:09:41 INFO - PROCESS | 1664 | -------- Summary: end -------- 13:09:41 INFO - PROCESS | 1664 | 13:09:42 INFO - TEST-INFO | 1664: exit 0 13:09:42 INFO - TEST-OK | kraken | took 58842ms 13:09:42 INFO - SUITE-END | took 1175s 13:09:42 INFO - Completed test suite (00:19:35) 13:09:42 INFO - javascript benchmark 13:09:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3324.6675332466757, 3333.3499967506345, 3353.2790450053235, 3363.0, 3405.0465869556533, 5514.255575497306, 5565.749541270642, 5651.2370829937945, 5677.0632845580485, 5743.0, 5648.4633959773755, 5685.0, 5690.7723691052415, 5692.57305702072, 5709.4290570942885, 5144.588432925367, 5189.0, 5201.869953251161, 5214.817481388152, 5255.421903590602, 5234.5027222414, 5239.607029472786, 5250.7112108834, 5256.973715131419, 5262.079136151177, 5163.81926632568, 5188.16989281715, 5197.246399272103, 5210.635255532115, 5250.31745873036, 5292.364916210079, 5293.86765330866, 5302.310699609065, 5318.22885681574, 5361.5978801589645, 5076.44159142494, 5112.079825631385, 5134.383873935151, 5185.351831021122, 5192.7403629818355, 5556.361018482886, 5561.860953476155, 5625.71871406428, 5664.405237450091, 5675.631083979528, 6203.782867599657, 6218.968905155491, 6220.813375598739, 6237.719302631393, 6259.092431597438, 4780.521947805191, 6169.259688837324, 6250.906091434026, 6284.811455656338, 6289.496840252768, 5797.420257974169, 5800.187973683687, 5845.181674565564, 5845.590808643439, 5849.590528662995, 6268.749250029948, 6282.057691346335, 6282.089097080898, 6299.6535190564955, 6301.8109456716375, 6056.3338032816355, 6100.664463454553, 6123.4351415035735, 6129.019356902874, 6177.722002509898, 6237.719302631393, 6246.281677607133, 6271.466925311308, 6273.027680709497, 6286.15136956505, 6175.567710260329, 6216.6270023797815, 6218.129461875341, 6223.595466294676, 6226.283977342573, 6104.76233045773, 6154.230721159855, 6180.103884936659, 6192.59748116371, 6217.595856269328, 4931.975340123348, 4949.82675606352, 4963.8262660806695, 4969.652124351261, 4997.775100120503, 4373.190959672471, 4434.157510073076, 4445.244308467568, 4454.643628509712, 4485.641148708096, 5642.153676948408, 5654.85862853432, 5690.231818704505, 5705.286839145107, 5709.143628455765, 6202.875942481215, 6228.096925945712, 6240.375962403814, 6243.375662433811, 6258.405451482102, 5621.606487545914, 5697.116946873241, 5699.0, 5712.571557133148, 5721.284839395075, 5334.879675268237, 5371.597130215249, 5388.568914486832, 5404.621676482687, 5415.323084614423, 6344.206974128327, 6355.046742988588, 6363.236411630634, 6369.713362898588, 6380.776672816429, 6141.078838174131, 6172.567920245539, 6175.938240617536, 6264.467520260647, 6264.59280146789, 6223.502119830312, 6270.7491700331475, 6301.0, 6308.27454842699, 6338.239411270677, 6155.876882462236, 6175.84560385994, 6185.165002724574, 6186.19579454668, 6188.783392581238, 6134.2332208473945, 6149.0776383542825, 6172.845678858064, 6242.344553821783, 6260.186175797117, 5844.503217226584, 6129.448349648553, 6139.815805525842, 6142.293636231891, 6147.600405973598, 6111.083337499411, 6128.540359473147, 6131.877362452637, 6134.171886795226, 6145.692715364304, 5766.308043034863, 5935.970320148372, 5980.760769569169, 5991.011483105413, 6006.669633170218, 6111.1361034884785, 6137.3248942617165, 6139.969300153471, 6157.89157951573, 6172.598781079216, 5012.0, 6050.697465126814, 6054.54590905675, 6058.818235452944, 6070.7875224366935], "value": 5692.741794121799, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.9703017818929, 509.2412305664556, 516.9715665638389, 529.9046171689095, 535.6063293479292, 30587.847060764692, 32608.0, 42029.36955945663, 43709.0, 43716.3442548362, 45582.54417455824, 45588.77205613976, 45832.77083614577, 45834.083318333614, 46100.53899461004, 9697.321187516876, 9886.555105020292, 9897.4556399398, 9907.356021858592, 9910.851337229948, 8918.955405222965, 9140.268778497737, 9141.725748227564, 9168.991410944791, 9186.908130918706, 11839.171258011942, 12057.397130143501, 12079.214851034654, 12331.26012439252, 12342.93828530856, 12162.08784341178, 12247.571335003233, 12269.202501837352, 12274.324912129829, 12279.754404911851, 8800.647974081028, 11651.708707282301, 11955.40222988847, 12033.157678962476, 12111.939440302742, 43265.56734432647, 43534.78232608816, 43583.564164358584, 43585.56414435879, 43632.56367436317, 3535.169235229719, 3581.212133330663, 3642.271545690846, 4019.216252830699, 4099.85650502234, 32325.191870203198, 33618.83190584056, 33747.98756037323, 33923.6607633923, 34131.65868341334, 3330.8667653293837, 3356.1777364545596, 3369.5619569456057, 3411.3518431497937, 3425.9828700856337, 522509.9999999962, 549099.0, 551161.0, 551987.2400637971, 552387.4761252336, 63634.6818265915, 64032.0, 64440.03339949904, 64453.35546644474, 64608.35391646117, 66671.33328666652, 66684.6665766668, 66766.33233667698, 66924.0, 67046.66476667683, 16325.183740812912, 16790.16049197535, 16801.327946882233, 16838.24227909747, 16863.072531010668, 12936.0, 12983.02627302956, 12990.350482475838, 12999.610011699664, 13013.609591712264, 13771.449142034206, 14733.705325893636, 15251.694966100617, 16609.335626575044, 17275.308987640605, 6091.177691011745, 6490.578112422678, 6633.635150066696, 6763.492738044666, 6765.695543700545, 718.353481866314, 999.01097913067, 1090.9781804364027, 1094.5621751299543, 1151.2056680890325, 31295.53056704151, 31311.0, 31382.52926206109, 31596.0, 32146.357072858413, 1071.4589132488043, 1095.3482677806692, 1097.6871591596357, 1137.3460260350166, 1220.097128125181, 16542.255598498097, 16552.0, 16554.42059527911, 16697.749533757004, 16988.405405810736, 604051.9797401073, 780499.0, 949155.0, 952138.0, 952672.0, 54905.72547137239, 63770.68114659397, 65029.0, 65069.34930650633, 65348.0, 1460.6859525202133, 1471.1834931613057, 1472.057882954931, 1488.397199134374, 1492.149474799354, 32181.517277240393, 32216.19459513531, 32250.677493225707, 32568.20874851906, 32886.67113328932, 54400.0, 54539.18191227056, 54964.45035549593, 55051.724741376034, 55160.72419638037, 629209.0, 633942.0, 634029.0, 635595.0, 636841.0, 21721.674174887707, 23992.520149596563, 24314.75685243125, 26925.865370673022, 27359.0, 47475.525244747114, 49151.8456334283, 49380.50619493759, 49788.502114978386, 49908.750456247486, 38897.416538752506, 40968.79515602403, 41001.75764376439, 41196.38205426981, 41972.95067623334, 40092.59907400889, 41027.97430064272, 41412.585874140874, 42049.0, 42366.78816605897, 39688.40467392933, 39858.601413986646, 40253.79873100616, 40422.39366409565, 40661.59338406578, 34563.82718086393, 34664.0, 36251.912442626766, 36885.81557092197, 37624.62375376211, 26583.468330633666, 26754.732452675224, 26796.0, 26985.1904442867, 27525.587116193674], "value": 21207.70797891877, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.56425392925104, 380.85337145199105, 381.4335711468469, 399.35504160780346, 403.5077205808914, 19591.804081959195, 21383.251586194485, 22236.444088897784, 22252.443688907784, 22615.208467703633, 22154.003069861854, 22201.2229571965, 22245.44386390341, 22549.985250663758, 22631.207907723234, 4125.814338354774, 4779.761011949406, 5540.806071787489, 5576.721163941807, 5585.134304182858, 4005.218982298453, 4215.683823713219, 5460.972695136519, 5884.2056322396575, 6175.66033868137, 6194.194754681885, 6198.194234749476, 6199.380061993809, 6202.9689851550675, 6214.5960512566535, 4677.789499472532, 6227.003679411295, 6232.345603711613, 6236.750529978795, 6239.064140378934, 3679.9264014719824, 5198.882240318349, 5575.637583557056, 6052.21321228242, 6065.180445866266, 13576.0, 13587.796183057164, 13750.243736594583, 13817.723645527134, 13828.239446830421, 2025.7771645119026, 2193.1337121836787, 2389.988050059756, 2413.843100198499, 2940.985360050779, 10231.130353919925, 10252.128569071638, 10544.420056896868, 10617.681469555926, 13950.51173208943, 1792.9462116136538, 1869.6073824496873, 1887.811218878115, 1909.7230901519201, 2011.6077364913847, 87821.56089219578, 113766.43116784446, 116495.25257121066, 122308.0, 124969.12546311812, 11603.6518904433, 11656.0, 11678.0, 11713.88286117128, 11841.934225919536, 14778.039427437183, 14832.703345933021, 14855.8858085644, 14865.405383784528, 14927.179005154603, 7935.285824275843, 7950.165232650604, 8499.0, 8652.307815374756, 8726.69456569017, 8560.914390856136, 8590.926134233221, 8620.181082797124, 8724.607392667345, 8744.737657870273, 4538.72767633943, 4580.564846339593, 4635.281531362644, 4663.417072865892, 4671.27595222741, 1475.2844870237925, 1791.6654521428945, 1825.4118916542693, 1884.6701827180189, 1900.7814101378242, 844.4004756622867, 913.8400779863497, 1003.2575893838507, 1010.5755582655303, 1016.0136932297341, 18491.075446227635, 20974.370768876957, 21848.714460212293, 21852.45368865759, 21895.797348890213, 864.3430992445786, 946.1437399153776, 1008.5007921079113, 1008.6973907827769, 1017.3743147963949, 10940.726481837859, 11190.720231994264, 11210.775784484427, 11253.774924501628, 11284.605038823602, 613118.0, 614697.0, 623837.0, 628184.0, 629215.0, 126145.0, 129590.0, 131053.68946310606, 131104.344478277, 133196.33401832927, 985.1232403161192, 1002.2583288366702, 1021.7905329407603, 1023.5240613114937, 1023.9539220735085, 18186.909065454587, 18465.261389544266, 18506.907465462587, 18625.44123676292, 18988.3354082612, 35724.0, 39042.41436378514, 49219.01561968812, 53395.73302133464, 55206.44793552013, 78612.60693696495, 79753.20246797458, 80348.0, 81173.59413203133, 81341.18658813337, 7880.684772609032, 7892.68429262846, 8057.43597948138, 8105.311048560939, 8267.875981860398, 11883.405829708307, 12548.623541293775, 12654.936725316315, 12787.296698681661, 12836.871631283568, 8508.319334453352, 10472.581096756045, 10833.187510936554, 10899.618513351994, 10963.945180274048, 10589.258751887293, 10678.519466624177, 10700.30448020876, 10882.782344353227, 10902.400367979837, 10975.231733778559, 11002.669919902415, 11115.166362523005, 11118.38848863288, 11120.61077862271, 7877.291043806085, 7992.9200707994505, 8000.919990800017, 8021.077576078826, 8180.345572354078, 10474.214433917334, 10759.193060520336, 10917.344959302467, 10945.726356840823, 10992.890071099506], "value": 9390.779081649485, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1550.620098075971, 1582.944596939107, 1782.6345599152176, 1932.65212261793, 1937.3606709785763, 8559.657613695452, 8871.556422178897, 9042.140996605312, 9046.728598142057, 9057.547122643875, 7789.143194248628, 8031.558264295461, 8480.703175388848, 8771.605277762501, 8786.033536311, 2330.300909727079, 2382.8451150675237, 2385.2367242482414, 2386.3914701751114, 2391.151141344824, 2215.966760498594, 2255.23322070496, 2370.3481542575755, 2372.715274167094, 2416.3838221253645, 2098.4963608733947, 2176.967345489819, 2310.849794763335, 2386.6658667786523, 2392.557376885282, 2364.243442098529, 2370.466645004869, 2392.3301475586863, 2396.604560247557, 2404.675368825204, 2389.2712722619594, 2390.2829151254555, 2394.2697477269426, 2398.78410943016, 2402.1712509184304, 8821.691240806605, 8869.55652217393, 8873.467591944504, 8889.111088891124, 8894.86657700135, 5312.442193569658, 5428.4300148484235, 5468.644538105011, 5488.121900495901, 5513.917291240635, 3550.4141816600486, 3643.9796856880116, 3720.2373513429684, 3723.2181241938956, 3741.158239396141, 1108.6673997800708, 1116.542217690743, 1132.869719982196, 1133.7449073958376, 1796.2365994452437, 11280.661580152608, 11688.649340519798, 11699.181057326075, 11708.53165873364, 11719.296842189497, 4695.487967750939, 4785.856424307276, 4792.568668819753, 4884.4871288514905, 4896.5103489651465, 10089.949550252202, 10175.0, 10186.592536298613, 10194.184465242763, 10202.64290749835, 5424.267723857229, 5426.8914621707345, 5431.646942948696, 5437.891242175135, 5455.699936503531, 4880.731559764247, 4917.0903382874285, 4955.331030310934, 4959.628027897922, 4974.403071631355, 3915.5497117831246, 3938.4289278055094, 3982.1438390745548, 4061.411095391152, 4085.4484644573195, 1198.6523908066738, 1243.241622610207, 1246.3518970135478, 1261.3819228577931, 1273.8853503184573, 796.1640277708383, 899.9235065019416, 917.554985831871, 960.6973803251954, 1004.6634377483479, 5460.34475862899, 5478.780848766005, 5963.910541341883, 5999.370066143079, 6028.216331876828, 497.0555943706913, 656.4912193050328, 927.596495524449, 1028.141501845952, 1061.2995423020923, 3994.6205110515034, 4036.979815100906, 4040.2727509048063, 4108.4453598764385, 4164.916701665949, 10576.36541807494, 10812.459377031117, 10829.891701083046, 10916.890831091747, 10957.0, 8472.534010629352, 9753.317267791186, 10868.836967445495, 10895.291806032583, 10902.836457453144, 8089.150639182919, 8386.8322633547, 8399.664013439517, 8402.159784021671, 8421.789455263544, 6025.337212906489, 6107.541934354852, 6256.843578910563, 6258.311585725475, 6318.589291696025, 9845.113939745457, 9862.063104005108, 10212.0, 10216.897831021892, 10259.17926565858, 9903.356281841658, 10255.897441025494, 10453.425061621683, 10480.318779279323, 10481.633142839964, 6852.246252912076, 7626.923730762844, 7631.0, 7746.457747957589, 7753.767386978399, 8267.545285009382, 8343.66625335004, 8433.0, 8438.620262088098, 8446.535440550582, 6609.867802644017, 6743.561668491532, 7347.448941329529, 7414.369778568882, 7542.245775422414, 7318.3047610476915, 7337.559746415019, 7376.0411146550605, 7422.517536360119, 7488.0, 7064.858702826017, 7077.150741911004, 7118.501704880816, 7128.429725622042, 7156.284371562802, 5031.647784655039, 5035.571976382049, 5054.21659642756, 5171.663841850268, 5227.712475813867, 5092.516210959953, 5208.8437346879655, 5233.476652334736, 5286.20706893969, 5399.325084364455], "value": 4650.929484887452, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.7554768411429, 307.0726406253116, 316.89542450991166, 321.9050380137859, 322.77889645592757, 4749.952500474994, 5186.170212765958, 5458.645188062773, 5532.1467308451665, 5533.253010843535, 4995.52542508462, 5005.624578156644, 5156.819511317105, 5367.221752845835, 5369.9463005369935, 3211.646718860935, 3857.958351245169, 4107.589241075899, 4151.564085770996, 4153.460050193471, 3519.665631764991, 3803.8668646597375, 3885.203533275672, 4161.25097482453, 4191.874243772691, 4023.2154729827694, 4093.5087789465306, 4106.589341065885, 4109.609587089237, 4109.876703698879, 4063.97968010161, 4081.530623978251, 4083.795810209507, 4090.202410529948, 4132.896677583054, 3566.5185199997927, 3577.4991501189593, 3587.4080776671817, 3607.7654952428006, 3626.1478552540134, 4297.312430011214, 4637.397138372024, 4981.850544483671, 5160.716160611165, 5226.346706661668, 4680.742559159245, 4719.528047195287, 4728.905421891577, 4733.33733277338, 4739.810407583693, 4234.195502854448, 5203.817866374697, 5603.971980140073, 5662.858428539278, 5669.461401166924, 3822.4266360045935, 3918.2555314490155, 3933.8819835404984, 3945.1912357966835, 3954.0625093704166, 5825.038868586678, 5829.621074630136, 5852.707364631751, 5878.853028674274, 5906.114082887515, 4033.5361433435532, 4037.2437989474674, 4051.905985383989, 4053.128577355824, 4062.2687916175373, 3634.873189311269, 3746.0073080633656, 4031.9596804031585, 4072.0227145485464, 4091.202215567995, 4215.178040282116, 4616.099860527165, 4617.976910115428, 4625.398698169284, 4651.651126165551, 5359.517643412111, 5656.773729050792, 5664.291963504562, 5678.290213723285, 5747.39652336507, 1158.7856246594238, 1278.8209650648917, 1358.5463553997129, 1377.827771528559, 1393.5924716036875, 925.8149568552129, 931.5202670624634, 952.8142012307673, 953.7281874665687, 955.6750704760415, 5229.816956406507, 5241.475852414728, 5272.261883336336, 5277.155655095166, 5302.151655735064, 5569.215392303848, 5687.772489100473, 5719.971400142973, 5749.798757043483, 5756.0, 4015.5984401560195, 4454.354118652777, 4776.874290940567, 4970.042824519405, 5115.0792857285305, 2447.3358116480963, 2594.545954458, 2637.5252454558363, 2659.4415172813924, 2663.933401664935, 4833.806647734122, 5074.644774865724, 5087.311699018256, 5111.846644600668, 5167.560757335668, 4559.954400456019, 4756.524347565216, 4908.901821963541, 5006.924896126561, 5059.519345662156, 4372.497162826252, 4884.778805298675, 4933.457319694902, 4961.950380496221, 5051.358308549809, 4375.0, 4463.268934928986, 4819.469858315512, 4854.441739199896, 4879.487653796442, 4561.703489273186, 4724.669273150984, 4788.449328327287, 4789.544993225638, 4932.249051586635, 4528.841490547815, 4707.9642478656015, 4767.4517430495935, 4933.728644924564, 4980.0, 3071.6928307169105, 3451.2407270401477, 3509.4034014218155, 3557.928841423105, 3573.8927832165077, 4211.789410529523, 4475.216837053437, 4785.473597904158, 4814.975925120352, 5427.348718153847, 4643.791029403753, 5677.501899164355, 5708.600397972102, 5744.5978781486565, 5749.8787736391005, 4870.00164966174, 5501.4223506532835, 5637.210790489253, 5732.340780810094, 5737.397573254915, 4932.975335123445, 5232.3982741985155, 5591.161325801162, 5624.85937851557, 5641.351244606759, 4795.985686011813, 5717.5997680163655, 5736.368999410145, 5742.856428589319, 5755.079187330007, 4959.504049595012, 5648.661080335193, 5662.603617746719, 5666.376698563237, 5669.943300566942], "value": 3985.6448510296514, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.9417646588272, 779.8752199648054, 807.9900124843945, 835.0480452284393, 839.9706010289641, 15127.773083403745, 16310.673786524263, 16370.672586548262, 16379.0, 16383.508494745147, 14799.55601331959, 15014.699706005873, 15053.473128440479, 15054.548363549084, 15059.322330495126, 722.9385502232303, 746.9775906722807, 901.7204666553389, 926.8100039491922, 940.3323640215456, 913.8674892140635, 924.075924075924, 927.7402327348353, 927.7958849053198, 929.7350255177275, 976.5800705696547, 977.4477420257532, 979.7207795778205, 980.9846808553149, 981.40134517944, 765.1239330966041, 945.0455040409202, 974.5224839828504, 975.9024097590184, 977.4086677560044, 722.9096362954631, 725.7750097469801, 726.3245181980756, 727.0729819480152, 728.3262981741867, 14962.925185374112, 14996.925015374962, 15018.924905375512, 15043.172625505593, 15052.247387630574, 14048.016638835286, 14079.436822527086, 14111.506097286649, 14130.22283774392, 14225.0, 11661.0, 11708.180427370087, 11748.588799392066, 11876.881231187665, 11919.940400297943, 4521.480029796549, 4552.977235113836, 4565.452145742499, 4711.646626503026, 4730.952690473086, 107954.46022769915, 110131.44934275358, 111508.21229469246, 111875.0, 112540.0, 11092.889071109346, 11141.498632561554, 11146.498407571678, 11213.495392707347, 11655.650330490098, 21910.342689719335, 22063.11747530113, 22118.778812211993, 22122.0, 22152.778472215396, 7899.842003159904, 7983.12185659577, 7993.200679931961, 8171.06032806223, 8215.917840821634, 4479.843205487792, 4571.405717256735, 4604.355390245369, 4684.156851766715, 4723.0081682846985, 3467.514547963287, 3552.378333791576, 3582.946255806165, 3583.140046388848, 3600.927981440409, 633.5343522510951, 660.5937348530715, 662.4402379988902, 673.7439772886272, 679.6533767778468, 551.41274542612, 556.41854262296, 581.7062383496308, 589.1016200294499, 641.8908785506475, 8242.826511793002, 8786.736397908073, 9108.271338292921, 9118.498482583522, 9232.67685631013, 575.8733078722674, 584.4389386189221, 586.9442402971796, 642.7396904253785, 646.1632186318635, 4343.457067866517, 4373.081652852937, 4426.44669416323, 4458.0, 4484.215262329079, 94175.11649766966, 123202.38398807948, 139813.0, 146849.7972530413, 155588.0, 121678.0, 124045.13932291023, 127348.0, 130641.0, 131899.0, 105817.0, 108970.82058358789, 112507.43746281216, 114270.0, 116989.41505292419, 8262.669493220204, 8321.542315172723, 8348.540830254395, 8356.582170891552, 8513.531755753493, 52893.0, 61678.69160654168, 64162.67918660377, 64860.67569662121, 64941.35058649353, 78535.0, 90116.0, 91507.0, 91562.62656060298, 93435.53282233546, 11537.769244615229, 11629.0, 11645.184837061663, 11684.474198660911, 11838.112141589243, 12166.0, 12297.93851030775, 12500.437480313589, 12563.560275390317, 12693.42879570403, 11562.0, 11895.167338286237, 12201.20692155007, 12301.876981230072, 12321.383930803604, 10670.679879603314, 11705.180637355645, 11799.35103569312, 11806.881931180922, 12286.508539658313, 11746.119041072125, 11843.644690659294, 11912.463939122934, 12182.0, 12251.75496490083, 9742.512874356113, 10023.0, 10077.19382449417, 10166.796664066529, 10276.691699249035, 9912.405255684682, 10122.557664697471, 10343.327683700536, 10378.377297362182, 10556.524956376828], "value": 7706.742159364589, "unit": "score"}], "name": "dromaeo_css", "value": 7383.219815939487, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 113.0, 112.0, 112.0, 112.0, 112.0, 112.0, 111.0, 112.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 107.0, 106.0, 160.0, 105.0, 106.0, 128.0, 103.0, 139.0, 104.0], "value": 120.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 187.0, 197.0, 265.0, 184.0, 183.0, 180.0, 185.0, 183.0], "value": 193.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 58.0, 106.0, 57.0, 57.0, 62.0, 85.0, 72.0, 71.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 92.0, 70.0, 70.0, 67.0, 94.0, 69.0, 65.0, 92.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 85.0, 88.0, 85.0, 89.0, 88.0, 90.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 105.0, 149.0, 109.0], "value": 102.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 87.0, 83.0, 85.0, 83.0, 83.0, 83.0, 86.0, 83.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 55.0, 55.0, 53.0, 54.0, 55.0, 53.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 80.0, 74.0, 72.0, 69.0, 70.0, 69.0, 70.0, 71.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 84.0, 84.0, 83.0, 84.0, 83.0, 84.0, 86.0, 104.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 149.0, 146.0, 135.0, 134.0, 136.0, 139.0, 146.0, 153.0, 136.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 162.0, 164.0, 162.0, 166.0, 161.0, 163.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 65.0, 66.0, 66.0, 65.0, 65.0, 66.0, 64.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1429.4999999999998, "alertThreshold": 2.0}]} 13:09:42 INFO - Return code: 0 13:09:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:09:42 INFO - # TBPL SUCCESS # 13:09:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:09:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:09:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:09:42 INFO - 2016-07-04 13:09:42.918 screenresolution[1668:9722] starting screenresolution argv=screenresolution get 13:09:42 INFO - 2016-07-04 13:09:42.921 screenresolution[1668:9722] Display 0: 1600x1200x32@60 13:09:42 INFO - 2016-07-04 13:09:42.927 screenresolution[1669:9725] starting screenresolution argv=screenresolution list 13:09:42 INFO - Available Modes on Display 0 13:09:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:09:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:09:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:09:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:09:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:09:43 INFO - Intel Iris: 13:09:43 INFO - Chipset Model: Intel Iris 13:09:43 INFO - Type: GPU 13:09:43 INFO - Bus: Built-In 13:09:43 INFO - VRAM (Dynamic, Max): 1536 MB 13:09:43 INFO - Vendor: Intel (0x8086) 13:09:43 INFO - Device ID: 0x0a2e 13:09:43 INFO - Revision ID: 0x0009 13:09:43 INFO - Displays: 13:09:43 INFO - Display: 13:09:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:09:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:09:43 INFO - Display Serial Number: 1600x1200 60 13:09:43 INFO - Main Display: Yes 13:09:43 INFO - Mirror: Off 13:09:43 INFO - Online: Yes 13:09:43 INFO - Rotation: Supported 13:09:43 INFO - Return code: 0 13:09:43 INFO - Running post-action listener: _resource_record_post_action 13:09:43 INFO - ##### 13:09:43 INFO - ##### Finished run-tests step (success) 13:09:43 INFO - ##### 13:09:43 INFO - Running post-run listener: _resource_record_post_run 13:09:43 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38348288; Write bytes: 705533952; Read time: 386; Write time: 1905 13:09:43 INFO - TinderboxPrint: CPU usage
53.6% 13:09:43 INFO - TinderboxPrint: I/O read bytes / time
38,348,288 / 386 13:09:43 INFO - TinderboxPrint: I/O write bytes / time
705,533,952 / 1,905 13:09:43 INFO - TinderboxPrint: CPU idle
2,249.0 (46.4%) 13:09:43 INFO - TinderboxPrint: CPU system
964.3 (19.9%) 13:09:43 INFO - TinderboxPrint: CPU user
1,632.4 (33.7%) 13:09:43 INFO - TinderboxPrint: Swap in / out
445,210,624 / 0 13:09:43 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 394359296; Write bytes: 388572672; Read time: 29271; Write time: 731 13:09:43 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 37393920; Write bytes: 295846400; Read time: 370; Write time: 1133 13:09:43 INFO - Running post-run listener: _upload_blobber_files 13:09:43 INFO - Blob upload gear active. 13:09:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:09:43 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 13:09:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:09:44 INFO - (blobuploader) - INFO - Open directory for files ... 13:09:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:09:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:09:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:09:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:09:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:09:45 INFO - (blobuploader) - INFO - Done attempting. 13:09:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:09:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:09:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:09:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:09:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:09:46 INFO - (blobuploader) - INFO - Done attempting. 13:09:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:09:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:09:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:09:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:09:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:09:48 INFO - (blobuploader) - INFO - Done attempting. 13:09:48 INFO - (blobuploader) - INFO - Iteration through files over. 13:09:48 INFO - Return code: 0 13:09:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:09:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:09:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a239affe8de5055ffa1697de089be84bcf9c4a062418900d5b2d05188f6195da72f7f2452354fc040a6fc8e69e7336bcb8bef4a32010cc409f5d6263dfe60d40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dcdbf0e8a2abfccbac3972bfaefaa14d7b925a4d8ec8c329f620aba69c3c8364a2791e2dd945e93534b2ed4d3db411049795b85a01598ebd558a21d1f2c4d45f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/be69d33f1738ea9785083505ef987ee235939683d95462eec220e05daaa6ae09c3be1edff584205a505b7d8bb1e8ecbfcb10655792aa39b32642f798252e0404"} 13:09:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:09:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:09:48 INFO - Contents: 13:09:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a239affe8de5055ffa1697de089be84bcf9c4a062418900d5b2d05188f6195da72f7f2452354fc040a6fc8e69e7336bcb8bef4a32010cc409f5d6263dfe60d40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dcdbf0e8a2abfccbac3972bfaefaa14d7b925a4d8ec8c329f620aba69c3c8364a2791e2dd945e93534b2ed4d3db411049795b85a01598ebd558a21d1f2c4d45f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/be69d33f1738ea9785083505ef987ee235939683d95462eec220e05daaa6ae09c3be1edff584205a505b7d8bb1e8ecbfcb10655792aa39b32642f798252e0404"} 13:09:48 INFO - Running post-run listener: copy_logs_to_upload_dir 13:09:48 INFO - Copying logs to upload dir... 13:09:48 INFO - mkdir: /builds/slave/test/build/upload/logs 13:09:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.659560 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-04 13:09:48.502156) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 13:09:48.505474) ========= 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.vx4ZA846LE/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.fKDjyngYS6/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a239affe8de5055ffa1697de089be84bcf9c4a062418900d5b2d05188f6195da72f7f2452354fc040a6fc8e69e7336bcb8bef4a32010cc409f5d6263dfe60d40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dcdbf0e8a2abfccbac3972bfaefaa14d7b925a4d8ec8c329f620aba69c3c8364a2791e2dd945e93534b2ed4d3db411049795b85a01598ebd558a21d1f2c4d45f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/be69d33f1738ea9785083505ef987ee235939683d95462eec220e05daaa6ae09c3be1edff584205a505b7d8bb1e8ecbfcb10655792aa39b32642f798252e0404"} build_url:https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011126 build_url: 'https://queue.taskcluster.net/v1/task/RtmrWdDZSIyPmQmJfTUJnA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a239affe8de5055ffa1697de089be84bcf9c4a062418900d5b2d05188f6195da72f7f2452354fc040a6fc8e69e7336bcb8bef4a32010cc409f5d6263dfe60d40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dcdbf0e8a2abfccbac3972bfaefaa14d7b925a4d8ec8c329f620aba69c3c8364a2791e2dd945e93534b2ed4d3db411049795b85a01598ebd558a21d1f2c4d45f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/be69d33f1738ea9785083505ef987ee235939683d95462eec220e05daaa6ae09c3be1edff584205a505b7d8bb1e8ecbfcb10655792aa39b32642f798252e0404"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 13:09:48.538460) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 13:09:48.538819) ========= 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.vx4ZA846LE/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.fKDjyngYS6/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005199 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 13:09:48.588128) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 13:09:48.588479) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 13:09:48.589058) ========= ========= Total master_lag: 0.18 =========