builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0344 starttime: 1467232597.76 results: success (0) buildid: 20160629114329 builduid: 4d7c04883c4243bda03eadbc98f338ba revision: 60999c6bc40ac97478bb216182d02cd857e3e206 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:37.760079) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:37.760901) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:37.761452) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:37.788916) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:37.789282) ========= 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.dBNAyFE8ex/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.ErqkudnvtV/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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-06-29 13:36:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 58.5M=0s 2016-06-29 13:36:38 (58.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.394268 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:38.207430) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:38.207802) ========= 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.dBNAyFE8ex/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.ErqkudnvtV/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.026170 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:38.257347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 13:36:38.257751) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 60999c6bc40ac97478bb216182d02cd857e3e206 --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 60999c6bc40ac97478bb216182d02cd857e3e206 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dBNAyFE8ex/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.ErqkudnvtV/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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-06-29 13:36:38,337 truncating revision to first 12 chars 2016-06-29 13:36:38,337 Setting DEBUG logging. 2016-06-29 13:36:38,337 attempt 1/10 2016-06-29 13:36:38,337 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/60999c6bc40a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 13:36:39,952 unpacking tar archive at: autoland-60999c6bc40a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.896766 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 13:36:40.178046) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:40.178491) ========= script_repo_revision: 60999c6bc40ac97478bb216182d02cd857e3e206 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:40.178950) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:40.179282) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 13:36:40.198718) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-06-29 13:36:40.199194) ========= /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.dBNAyFE8ex/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.ErqkudnvtV/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 13:36:40 INFO - MultiFileLogger online at 20160629 13:36:40 in /builds/slave/test 13:36:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 13:36:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:36:40 INFO - {'append_to_log': False, 13:36:40 INFO - 'base_work_dir': '/builds/slave/test', 13:36:40 INFO - 'blob_upload_branch': 'Autoland', 13:36:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:36:40 INFO - 'branch': 'Autoland', 13:36:40 INFO - 'buildbot_json_path': 'buildprops.json', 13:36:40 INFO - 'config_files': ('talos/mac_config.py',), 13:36:40 INFO - 'default_actions': ('clobber', 13:36:40 INFO - 'read-buildbot-config', 13:36:40 INFO - 'download-and-extract', 13:36:40 INFO - 'populate-webroot', 13:36:40 INFO - 'create-virtualenv', 13:36:40 INFO - 'install', 13:36:40 INFO - 'run-tests'), 13:36:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:36:40 INFO - 'download_minidump_stackwalk': True, 13:36:40 INFO - 'download_symbols': 'ondemand', 13:36:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:36:40 INFO - 'tooltool.py': '/tools/tooltool.py', 13:36:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:36:40 INFO - '/tools/misc-python/virtualenv.py')}, 13:36:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:36:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:36:40 INFO - 'installer_path': 'installer.exe', 13:36:40 INFO - 'log_level': 'info', 13:36:40 INFO - 'log_name': 'talos', 13:36:40 INFO - 'log_to_console': True, 13:36:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:36:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:36:40 INFO - 'opt_config_files': (), 13:36:40 INFO - 'pip_index': False, 13:36:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:36:40 INFO - 'cmd': ('bash', 13:36:40 INFO - '-c', 13:36:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:36:40 INFO - 'enabled': True, 13:36:40 INFO - 'halt_on_failure': False, 13:36:40 INFO - 'name': 'check_screen_resolution'},), 13:36:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:36:40 INFO - 'cmd': ('bash', 13:36:40 INFO - '-c', 13:36:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:36:40 INFO - 'enabled': True, 13:36:40 INFO - 'halt_on_failure': False, 13:36:40 INFO - 'name': 'check_screen_resolution'},), 13:36:40 INFO - 'run_cmd_checks_enabled': True, 13:36:40 INFO - 'sps_profile': False, 13:36:40 INFO - 'sps_profile_interval': 0, 13:36:40 INFO - 'suite': 'dromaeojs', 13:36:40 INFO - 'system_bits': '32', 13:36:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:36:40 INFO - 'title': 't-yosemite-r7-0344', 13:36:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:36:40 INFO - 'use_talos_json': True, 13:36:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:36:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:36:40 INFO - 'work_dir': 'build'} 13:36:40 INFO - ##### 13:36:40 INFO - ##### Running clobber step. 13:36:40 INFO - ##### 13:36:40 INFO - Running pre-action listener: _resource_record_pre_action 13:36:40 INFO - Running main action method: clobber 13:36:40 INFO - rmtree: /builds/slave/test/build 13:36:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:36:42 INFO - Running post-action listener: _resource_record_post_action 13:36:42 INFO - ##### 13:36:42 INFO - ##### Finished clobber step (success) 13:36:42 INFO - ##### 13:36:42 INFO - ##### 13:36:42 INFO - ##### Running read-buildbot-config step. 13:36:42 INFO - ##### 13:36:42 INFO - Running pre-action listener: _resource_record_pre_action 13:36:42 INFO - Running main action method: read_buildbot_config 13:36:42 INFO - Using buildbot properties: 13:36:42 INFO - { 13:36:42 INFO - "project": "", 13:36:42 INFO - "product": "firefox", 13:36:42 INFO - "script_repo_revision": "production", 13:36:42 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 13:36:42 INFO - "repository": "", 13:36:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 13:36:42 INFO - "buildid": "20160629114329", 13:36:42 INFO - "pgo_build": "False", 13:36:42 INFO - "basedir": "/builds/slave/test", 13:36:42 INFO - "buildnumber": 5, 13:36:42 INFO - "slavename": "t-yosemite-r7-0344", 13:36:42 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:36:42 INFO - "platform": "yosemite_r7", 13:36:42 INFO - "scheduler": "tests-autoland-macosx64-talos", 13:36:42 INFO - "branch": "autoland", 13:36:42 INFO - "repo_path": "integration/autoland", 13:36:42 INFO - "revision": "60999c6bc40ac97478bb216182d02cd857e3e206", 13:36:42 INFO - "stage_platform": "macosx64", 13:36:42 INFO - "builduid": "4d7c04883c4243bda03eadbc98f338ba", 13:36:42 INFO - "slavebuilddir": "test" 13:36:42 INFO - } 13:36:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 13:36:42 INFO - Running post-action listener: _resource_record_post_action 13:36:42 INFO - ##### 13:36:42 INFO - ##### Finished read-buildbot-config step (success) 13:36:42 INFO - ##### 13:36:42 INFO - ##### 13:36:42 INFO - ##### Running download-and-extract step. 13:36:42 INFO - ##### 13:36:42 INFO - Running pre-action listener: _resource_record_pre_action 13:36:42 INFO - Running main action method: download_and_extract 13:36:42 INFO - mkdir: /builds/slave/test/build/tests 13:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:36:42 INFO - https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:36:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 13:36:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 13:36:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/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 13:36:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/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 13:36:43 INFO - Downloaded 1199 bytes. 13:36:43 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 13:36:43 INFO - Using the following test package requirements: 13:36:43 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 13:36:43 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:36:43 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 13:36:43 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:36:43 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 13:36:43 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:36:43 INFO - u'jsshell-mac.zip'], 13:36:43 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:36:43 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 13:36:43 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 13:36:43 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:36:43 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 13:36:43 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:36:43 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 13:36:43 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:36:43 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 13:36:43 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:36:43 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 13:36:43 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:36:43 INFO - https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:36:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:36:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:36:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/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 13:36:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/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 13:36:44 INFO - Downloaded 18788464 bytes. 13:36:44 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'] 13:36:44 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 13:36:47 INFO - Return code: 0 13:36:47 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 13:36:47 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')]} 13:36:47 INFO - https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:36:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:36:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:36:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/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 13:36:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/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 13:36:50 INFO - Downloaded 18788464 bytes. 13:36:50 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'] 13:36:50 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 13:36:54 INFO - Return code: 0 13:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:36:54 INFO - https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:36:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 13:36:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 13:36:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/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 13:36:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/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 13:36:55 INFO - Downloaded 14405245 bytes. 13:36:55 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'] 13:36:55 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 13:36:55 INFO - Return code: 0 13:36:55 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')]} 13:36:55 INFO - https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:36:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:36:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:36:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 13:36:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yg2cWUtsQWqUkktEsA76cw/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 13:36:58 INFO - Downloaded 112467269 bytes. 13:36:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:36:58 INFO - mkdir: /builds/slave/test/properties 13:36:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:36:58 INFO - Writing to file /builds/slave/test/properties/build_url 13:36:58 INFO - Contents: 13:36:58 INFO - build_url:https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:36:59 INFO - Running post-action listener: _resource_record_post_action 13:36:59 INFO - Running post-action listener: set_extra_try_arguments 13:36:59 INFO - ##### 13:36:59 INFO - ##### Finished download-and-extract step (success) 13:36:59 INFO - ##### 13:36:59 INFO - ##### 13:36:59 INFO - ##### Running populate-webroot step. 13:36:59 INFO - ##### 13:36:59 INFO - Running pre-action listener: _resource_record_pre_action 13:36:59 INFO - Running main action method: populate_webroot 13:36:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:36:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:36:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:36:59 INFO - '--tppagecycles', 13:36:59 INFO - '7'], 13:36:59 INFO - 'tests': ['tsvgm']}}, 13:36:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:36:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:36:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:36:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:36:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:36:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:36:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:36:59 INFO - 'talos_options': ['--disable-e10s'], 13:36:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:36:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:36:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:36:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:36:59 INFO - 'talos_options': ['--disable-e10s'], 13:36:59 INFO - 'tests': ['damp', 'tps']}, 13:36:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:36:59 INFO - 'tests': ['damp', 'tps']}, 13:36:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:36:59 INFO - 'tests': ['dromaeo_dom']}, 13:36:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:36:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:36:59 INFO - 'tests': ['basic_compositor_video']}, 13:36:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:36:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:36:59 INFO - 'tests': ['a11yr', 13:36:59 INFO - 'ts_paint', 13:36:59 INFO - 'tpaint', 13:36:59 INFO - 'sessionrestore', 13:36:59 INFO - 'sessionrestore_no_auto_restore', 13:36:59 INFO - 'tabpaint']}, 13:36:59 INFO - 'other-e10s': {'tests': ['a11yr', 13:36:59 INFO - 'ts_paint', 13:36:59 INFO - 'tpaint', 13:36:59 INFO - 'sessionrestore', 13:36:59 INFO - 'sessionrestore_no_auto_restore', 13:36:59 INFO - 'tabpaint']}, 13:36:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:36:59 INFO - 'ts_paint', 13:36:59 INFO - 'tpaint', 13:36:59 INFO - 'sessionrestore', 13:36:59 INFO - 'sessionrestore_no_auto_restore', 13:36:59 INFO - 'tabpaint']}, 13:36:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:36:59 INFO - 'ts_paint', 13:36:59 INFO - 'tpaint', 13:36:59 INFO - 'sessionrestore', 13:36:59 INFO - 'sessionrestore_no_auto_restore', 13:36:59 INFO - 'tabpaint']}, 13:36:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:36:59 INFO - 'tests': ['a11yr', 13:36:59 INFO - 'ts_paint', 13:36:59 INFO - 'tpaint', 13:36:59 INFO - 'sessionrestore', 13:36:59 INFO - 'sessionrestore_no_auto_restore', 13:36:59 INFO - 'tabpaint']}, 13:36:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:36:59 INFO - 'tests': ['a11yr', 13:36:59 INFO - 'ts_paint', 13:36:59 INFO - 'tpaint', 13:36:59 INFO - 'sessionrestore', 13:36:59 INFO - 'sessionrestore_no_auto_restore', 13:36:59 INFO - 'tabpaint']}, 13:36:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:36:59 INFO - 'tests': ['tsvgx', 13:36:59 INFO - 'tsvgr_opacity', 13:36:59 INFO - 'tart', 13:36:59 INFO - 'tscrollx', 13:36:59 INFO - 'cart']}, 13:36:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:36:59 INFO - 'tsvgr_opacity', 13:36:59 INFO - 'tart', 13:36:59 INFO - 'tscrollx', 13:36:59 INFO - 'cart']}, 13:36:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:36:59 INFO - 'talos_options': ['--disable-e10s'], 13:36:59 INFO - 'tests': ['tp5o']}, 13:36:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:36:59 INFO - 'tests': ['tp5o']}, 13:36:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:36:59 INFO - 'talos_options': ['--disable-e10s', 13:36:59 INFO - '--xperf_path', 13:36:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:36:59 INFO - 'tests': ['tp5n']}, 13:36:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:36:59 INFO - 'talos_options': ['--xperf_path', 13:36:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:36:59 INFO - 'tests': ['tp5n']}}, 13:36:59 INFO - 'talos.zip': {'path': '', 13:36:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:36:59 INFO - Running post-action listener: _resource_record_post_action 13:36:59 INFO - ##### 13:36:59 INFO - ##### Finished populate-webroot step (success) 13:36:59 INFO - ##### 13:36:59 INFO - ##### 13:36:59 INFO - ##### Running create-virtualenv step. 13:36:59 INFO - ##### 13:36:59 INFO - Running pre-action listener: _resource_record_pre_action 13:36:59 INFO - Running main action method: create_virtualenv 13:36:59 INFO - Creating virtualenv /builds/slave/test/build/venv 13:36:59 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 13:36:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:36:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:36:59 INFO - Using real prefix '/tools/python27' 13:36:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:37:00 INFO - Installing distribute.............................................................................................................................................................................................done. 13:37:03 INFO - Installing pip.................done. 13:37:03 INFO - Return code: 0 13:37:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:37:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105239a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d4149fa80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10519dd30>, '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 0x104f02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105195b20>, '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.dBNAyFE8ex/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.ErqkudnvtV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 13:37:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:37:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:37:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBNAyFE8ex/Render', 13:37:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:03 INFO - 'HOME': '/Users/cltbld', 13:37:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:03 INFO - 'LOGNAME': 'cltbld', 13:37:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:03 INFO - 'MOZ_NO_REMOTE': '1', 13:37:03 INFO - 'NO_EM_RESTART': '1', 13:37:03 INFO - 'PAGER': '/bin/cat', 13:37:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:03 INFO - 'PWD': '/builds/slave/test', 13:37:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:03 INFO - 'SHELL': '/bin/bash', 13:37:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ErqkudnvtV/Listeners', 13:37:03 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 13:37:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:03 INFO - 'USER': 'cltbld', 13:37:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:03 INFO - 'XPC_FLAGS': '0x0', 13:37:03 INFO - 'XPC_SERVICE_NAME': '0', 13:37:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:03 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 13:37:03 INFO - Cleaning up... 13:37:03 INFO - Return code: 0 13:37:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:37:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105239a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d4149fa80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10519dd30>, '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 0x104f02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105195b20>, '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.dBNAyFE8ex/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.ErqkudnvtV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 13:37:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:37:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:37:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBNAyFE8ex/Render', 13:37:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:03 INFO - 'HOME': '/Users/cltbld', 13:37:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:03 INFO - 'LOGNAME': 'cltbld', 13:37:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:03 INFO - 'MOZ_NO_REMOTE': '1', 13:37:03 INFO - 'NO_EM_RESTART': '1', 13:37:03 INFO - 'PAGER': '/bin/cat', 13:37:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:03 INFO - 'PWD': '/builds/slave/test', 13:37:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:03 INFO - 'SHELL': '/bin/bash', 13:37:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ErqkudnvtV/Listeners', 13:37:03 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 13:37:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:03 INFO - 'USER': 'cltbld', 13:37:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:03 INFO - 'XPC_FLAGS': '0x0', 13:37:03 INFO - 'XPC_SERVICE_NAME': '0', 13:37:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:03 INFO - Downloading/unpacking psutil>=3.1.1 13:37:03 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 13:37:03 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 13:37:03 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 13:37:03 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 13:37:03 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 13:37:03 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 13:37:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:37:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:37:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:37:06 INFO - Installing collected packages: psutil 13:37:06 INFO - Running setup.py install for psutil 13:37:06 INFO - building 'psutil._psutil_osx' extension 13:37:06 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 13:37:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:37:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:37:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:37:07 INFO - building 'psutil._psutil_posix' extension 13:37:07 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 13:37:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:37:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:37:07 INFO - ^ 13:37:07 INFO - 1 warning generated. 13:37:07 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 13:37:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:37:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:37:07 INFO - Successfully installed psutil 13:37:07 INFO - Cleaning up... 13:37:07 INFO - Return code: 0 13:37:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105239a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d4149fa80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10519dd30>, '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 0x104f02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105195b20>, '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.dBNAyFE8ex/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.ErqkudnvtV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 13:37:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:37:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBNAyFE8ex/Render', 13:37:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:07 INFO - 'HOME': '/Users/cltbld', 13:37:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:07 INFO - 'LOGNAME': 'cltbld', 13:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:07 INFO - 'MOZ_NO_REMOTE': '1', 13:37:07 INFO - 'NO_EM_RESTART': '1', 13:37:07 INFO - 'PAGER': '/bin/cat', 13:37:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:07 INFO - 'PWD': '/builds/slave/test', 13:37:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:07 INFO - 'SHELL': '/bin/bash', 13:37:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ErqkudnvtV/Listeners', 13:37:07 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 13:37:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:07 INFO - 'USER': 'cltbld', 13:37:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:07 INFO - 'XPC_FLAGS': '0x0', 13:37:07 INFO - 'XPC_SERVICE_NAME': '0', 13:37:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:37:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:37:10 INFO - Installing collected packages: mozsystemmonitor 13:37:10 INFO - Running setup.py install for mozsystemmonitor 13:37:10 INFO - Successfully installed mozsystemmonitor 13:37:10 INFO - Cleaning up... 13:37:10 INFO - Return code: 0 13:37:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:37:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:10 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 0x105239a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d4149fa80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10519dd30>, '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 0x104f02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105195b20>, '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.dBNAyFE8ex/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.ErqkudnvtV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 13:37:10 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 13:37:10 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 13:37:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBNAyFE8ex/Render', 13:37:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:10 INFO - 'HOME': '/Users/cltbld', 13:37:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:10 INFO - 'LOGNAME': 'cltbld', 13:37:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:10 INFO - 'MOZ_NO_REMOTE': '1', 13:37:10 INFO - 'NO_EM_RESTART': '1', 13:37:10 INFO - 'PAGER': '/bin/cat', 13:37:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:10 INFO - 'PWD': '/builds/slave/test', 13:37:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:10 INFO - 'SHELL': '/bin/bash', 13:37:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ErqkudnvtV/Listeners', 13:37:10 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 13:37:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:10 INFO - 'USER': 'cltbld', 13:37:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:10 INFO - 'XPC_FLAGS': '0x0', 13:37:10 INFO - 'XPC_SERVICE_NAME': '0', 13:37:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:11 INFO - Downloading/unpacking jsonschema==2.5.1 13:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:37:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:37: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 13:37: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 13:37: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 13:37: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 13:37: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 13:37: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 13:37:13 INFO - Downloading functools32-3.2.3-2.tar.gz 13:37:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:37:14 INFO - warning: no files found matching '*.txt' 13:37:14 INFO - no previously-included directories found matching 'build' 13:37:14 INFO - no previously-included directories found matching 'dist' 13:37:14 INFO - no previously-included directories found matching '.git*' 13:37:14 INFO - Installing collected packages: jsonschema, functools32 13:37:14 INFO - Running setup.py install for functools32 13:37:14 INFO - warning: no files found matching '*.txt' 13:37:14 INFO - no previously-included directories found matching 'build' 13:37:14 INFO - no previously-included directories found matching 'dist' 13:37:14 INFO - no previously-included directories found matching '.git*' 13:37:14 INFO - Successfully installed jsonschema functools32 13:37:14 INFO - Cleaning up... 13:37:14 INFO - Return code: 0 13:37:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105239a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d4149fa80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10519dd30>, '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 0x104f02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105195b20>, '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.dBNAyFE8ex/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.ErqkudnvtV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 13:37:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:37:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:37:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBNAyFE8ex/Render', 13:37:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:14 INFO - 'HOME': '/Users/cltbld', 13:37:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:14 INFO - 'LOGNAME': 'cltbld', 13:37:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:14 INFO - 'MOZ_NO_REMOTE': '1', 13:37:14 INFO - 'NO_EM_RESTART': '1', 13:37:14 INFO - 'PAGER': '/bin/cat', 13:37:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:14 INFO - 'PWD': '/builds/slave/test', 13:37:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:14 INFO - 'SHELL': '/bin/bash', 13:37:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ErqkudnvtV/Listeners', 13:37:14 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 13:37:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:14 INFO - 'USER': 'cltbld', 13:37:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:14 INFO - 'XPC_FLAGS': '0x0', 13:37:14 INFO - 'XPC_SERVICE_NAME': '0', 13:37:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:14 INFO - Downloading/unpacking blobuploader==1.2.4 13:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:16 INFO - Downloading blobuploader-1.2.4.tar.gz 13:37:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:37:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:37:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:37:18 INFO - Downloading docopt-0.6.1.tar.gz 13:37:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:37:18 INFO - Installing collected packages: blobuploader, requests, docopt 13:37:18 INFO - Running setup.py install for blobuploader 13:37:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:37:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:37:18 INFO - Running setup.py install for requests 13:37:18 INFO - Running setup.py install for docopt 13:37:19 INFO - Successfully installed blobuploader requests docopt 13:37:19 INFO - Cleaning up... 13:37:19 INFO - Return code: 0 13:37:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:37:19 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')]} 13:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:19 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')]} 13:37:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:19 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 0x105239a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d4149fa80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10519dd30>, '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 0x104f02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105195b20>, '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.dBNAyFE8ex/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.ErqkudnvtV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 13:37:19 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 13:37:19 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 13:37:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBNAyFE8ex/Render', 13:37:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:19 INFO - 'HOME': '/Users/cltbld', 13:37:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:19 INFO - 'LOGNAME': 'cltbld', 13:37:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:19 INFO - 'MOZ_NO_REMOTE': '1', 13:37:19 INFO - 'NO_EM_RESTART': '1', 13:37:19 INFO - 'PAGER': '/bin/cat', 13:37:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:19 INFO - 'PWD': '/builds/slave/test', 13:37:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:19 INFO - 'SHELL': '/bin/bash', 13:37:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ErqkudnvtV/Listeners', 13:37:19 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 13:37:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:19 INFO - 'USER': 'cltbld', 13:37:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:19 INFO - 'XPC_FLAGS': '0x0', 13:37:19 INFO - 'XPC_SERVICE_NAME': '0', 13:37:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:37:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-fO5nml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:37:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-05J51Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:37:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-Kv1lTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:37:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-DCpiyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:37:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-ayEVkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:37:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-1_8fQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:37:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-CD2Vvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:37:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-czzQLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:37:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-oiqKN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:37:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-P_b0te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:37:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-MnXxg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:37:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-jOzL0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:37:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-_OPe_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:37:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-56PSOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-FYiXBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:37:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-zCaLQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:37:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-ra_pXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:37:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:37:21 INFO - Running setup.py install for manifestparser 13:37:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:37:21 INFO - Running setup.py install for mozcrash 13:37:21 INFO - Running setup.py install for mozdebug 13:37:21 INFO - Running setup.py install for mozdevice 13:37:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:37:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:37:21 INFO - Running setup.py install for mozfile 13:37:21 INFO - Running setup.py install for mozhttpd 13:37:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:37:22 INFO - Running setup.py install for mozinfo 13:37:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:37:22 INFO - Running setup.py install for mozInstall 13:37:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:37:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:37:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:37:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:37:22 INFO - Running setup.py install for mozleak 13:37:22 INFO - Running setup.py install for mozlog 13:37:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:37:22 INFO - Running setup.py install for moznetwork 13:37:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:37:22 INFO - Running setup.py install for mozprocess 13:37:22 INFO - Running setup.py install for mozprofile 13:37:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:37:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:37:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:37:23 INFO - Running setup.py install for mozrunner 13:37:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:37:23 INFO - Running setup.py install for mozscreenshot 13:37:23 INFO - Running setup.py install for moztest 13:37:23 INFO - Running setup.py install for mozversion 13:37:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:37:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:37:23 INFO - Cleaning up... 13:37:23 INFO - Return code: 0 13:37:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:23 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 0x105239a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d4149fa80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10519dd30>, '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 0x104f02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105195b20>, '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.dBNAyFE8ex/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.ErqkudnvtV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 13:37:23 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 13:37:23 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 13:37:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBNAyFE8ex/Render', 13:37:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:23 INFO - 'HOME': '/Users/cltbld', 13:37:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:23 INFO - 'LOGNAME': 'cltbld', 13:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:23 INFO - 'MOZ_NO_REMOTE': '1', 13:37:23 INFO - 'NO_EM_RESTART': '1', 13:37:23 INFO - 'PAGER': '/bin/cat', 13:37:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:23 INFO - 'PWD': '/builds/slave/test', 13:37:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:23 INFO - 'SHELL': '/bin/bash', 13:37:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ErqkudnvtV/Listeners', 13:37:23 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 13:37:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:23 INFO - 'USER': 'cltbld', 13:37:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:23 INFO - 'XPC_FLAGS': '0x0', 13:37:23 INFO - 'XPC_SERVICE_NAME': '0', 13:37:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:37:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-G6DBAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:37:24 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)) 13:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:37:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-WHZsNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:37:24 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)) 13:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:37:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-tL_bPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:37:24 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)) 13:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:37:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-Xx92RZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:37:24 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)) 13:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:37:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-2z5vNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:37:24 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)) 13:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:37:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-unkqK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:37:24 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)) 13:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:37:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-cUyscg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:37:24 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)) 13:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:37:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-zp4TAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:37:24 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)) 13:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:37:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-i6GvS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:37:24 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)) 13:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:37:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-JG4Bel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:37:25 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)) 13:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:37:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-6Nukc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:37:25 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)) 13:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:37:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-1kdotk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:37:25 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)) 13:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:37:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-gM8Zf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:37:25 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)) 13:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:37:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-rVibNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:37:25 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)) 13:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-_Nb65Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:37:25 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)) 13:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:37:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-TeFD47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:37:25 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)) 13:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:37:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-mwyIH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:37:25 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)) 13:37:25 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)) 13:37:25 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)) 13:37:25 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)) 13:37:25 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)) 13:37:25 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)) 13:37:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:37:25 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 13:37:25 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 13:37:25 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 13:37:25 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 13:37:25 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 13:37:25 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 13:37:28 INFO - Downloading blessings-1.6.tar.gz 13:37:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:37:28 INFO - Installing collected packages: blessings 13:37:28 INFO - Running setup.py install for blessings 13:37:28 INFO - Successfully installed blessings 13:37:28 INFO - Cleaning up... 13:37:28 INFO - Return code: 0 13:37:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:37:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:37:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:37:28 INFO - Reading from file tmpfile_stdout 13:37:28 INFO - Current package versions: 13:37:28 INFO - blessings == 1.6 13:37:28 INFO - blobuploader == 1.2.4 13:37:28 INFO - docopt == 0.6.1 13:37:28 INFO - functools32 == 3.2.3-2 13:37:28 INFO - jsonschema == 2.5.1 13:37:28 INFO - manifestparser == 1.1 13:37:28 INFO - mozInstall == 1.12 13:37:28 INFO - mozcrash == 0.17 13:37:28 INFO - mozdebug == 0.1 13:37:28 INFO - mozdevice == 0.48 13:37:28 INFO - mozfile == 1.2 13:37:28 INFO - mozhttpd == 0.7 13:37:28 INFO - mozinfo == 0.9 13:37:28 INFO - mozleak == 0.1 13:37:28 INFO - mozlog == 3.2 13:37:28 INFO - moznetwork == 0.27 13:37:28 INFO - mozprocess == 0.22 13:37:28 INFO - mozprofile == 0.28 13:37:28 INFO - mozrunner == 6.11 13:37:28 INFO - mozscreenshot == 0.1 13:37:28 INFO - mozsystemmonitor == 0.3 13:37:28 INFO - moztest == 0.7 13:37:28 INFO - mozversion == 1.4 13:37:28 INFO - psutil == 3.1.1 13:37:28 INFO - requests == 1.2.3 13:37:28 INFO - wsgiref == 0.1.2 13:37:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:28 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 0x105239a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d4149fa80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10519dd30>, '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 0x104f02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105195b20>, '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.dBNAyFE8ex/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.ErqkudnvtV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 13:37:28 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 13:37:28 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 13:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBNAyFE8ex/Render', 13:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:28 INFO - 'HOME': '/Users/cltbld', 13:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:28 INFO - 'LOGNAME': 'cltbld', 13:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:28 INFO - 'MOZ_NO_REMOTE': '1', 13:37:28 INFO - 'NO_EM_RESTART': '1', 13:37:28 INFO - 'PAGER': '/bin/cat', 13:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:28 INFO - 'PWD': '/builds/slave/test', 13:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:28 INFO - 'SHELL': '/bin/bash', 13:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ErqkudnvtV/Listeners', 13:37:28 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 13:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:28 INFO - 'USER': 'cltbld', 13:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:28 INFO - 'XPC_FLAGS': '0x0', 13:37:28 INFO - 'XPC_SERVICE_NAME': '0', 13:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:29 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)) 13:37:29 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)) 13:37:29 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)) 13:37:29 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)) 13:37:29 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)) 13:37:29 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)) 13:37:29 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)) 13:37:29 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)) 13:37:29 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)) 13:37:29 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)) 13:37:29 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)) 13:37:29 INFO - Cleaning up... 13:37:29 INFO - Return code: 0 13:37:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:37:29 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 0x105239a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d4149fa80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10519dd30>, '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 0x104f02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105195b20>, '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.dBNAyFE8ex/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.ErqkudnvtV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 13:37:29 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 13:37:29 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 13:37:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBNAyFE8ex/Render', 13:37:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:37:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:37:29 INFO - 'HOME': '/Users/cltbld', 13:37:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:37:29 INFO - 'LOGNAME': 'cltbld', 13:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:37:29 INFO - 'MOZ_NO_REMOTE': '1', 13:37:29 INFO - 'NO_EM_RESTART': '1', 13:37:29 INFO - 'PAGER': '/bin/cat', 13:37:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:37:29 INFO - 'PWD': '/builds/slave/test', 13:37:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:37:29 INFO - 'SHELL': '/bin/bash', 13:37:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ErqkudnvtV/Listeners', 13:37:29 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 13:37:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:37:29 INFO - 'USER': 'cltbld', 13:37:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:37:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:37:29 INFO - 'XPC_FLAGS': '0x0', 13:37:29 INFO - 'XPC_SERVICE_NAME': '0', 13:37:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:37:29 INFO - Cleaning up... 13:37:29 INFO - Return code: 0 13:37:29 INFO - Running post-action listener: _resource_record_post_action 13:37:29 INFO - Running post-action listener: _start_resource_monitoring 13:37:29 INFO - Starting resource monitoring. 13:37:29 INFO - ##### 13:37:29 INFO - ##### Finished create-virtualenv step (success) 13:37:29 INFO - ##### 13:37:29 INFO - ##### 13:37:29 INFO - ##### Running install step. 13:37:29 INFO - ##### 13:37:29 INFO - Running pre-action listener: _resource_record_pre_action 13:37:29 INFO - Running main action method: install 13:37:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:37:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:37:29 INFO - Reading from file tmpfile_stdout 13:37:29 INFO - Detecting whether we're running mozinstall >=1.0... 13:37:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:37:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:37:29 INFO - Reading from file tmpfile_stdout 13:37:29 INFO - Output received: 13:37:29 INFO - Usage: mozinstall [options] installer 13:37:29 INFO - Options: 13:37:29 INFO - -h, --help show this help message and exit 13:37:29 INFO - -d DEST, --destination=DEST 13:37:29 INFO - Directory to install application into. [default: 13:37:29 INFO - "/builds/slave/test"] 13:37:29 INFO - --app=APP Application being installed. [default: firefox] 13:37:29 INFO - mkdir: /builds/slave/test/build/application 13:37:29 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'] 13:37:29 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 13:38:04 INFO - Reading from file tmpfile_stdout 13:38:04 INFO - Output received: 13:38:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:38:04 INFO - Running post-action listener: _resource_record_post_action 13:38:04 INFO - ##### 13:38:04 INFO - ##### Finished install step (success) 13:38:04 INFO - ##### 13:38:04 INFO - ##### 13:38:04 INFO - ##### Running run-tests step. 13:38:04 INFO - ##### 13:38:04 INFO - Running pre-action listener: _resource_record_pre_action 13:38:04 INFO - Running main action method: run_tests 13:38:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:38:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:38:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:38:04 INFO - 2016-06-29 13:38:04.671 screenresolution[1637:7579] starting screenresolution argv=screenresolution get 13:38:04 INFO - 2016-06-29 13:38:04.673 screenresolution[1637:7579] Display 0: 1600x1200x32@60 13:38:04 INFO - 2016-06-29 13:38:04.679 screenresolution[1638:7581] starting screenresolution argv=screenresolution list 13:38:04 INFO - Available Modes on Display 0 13:38:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:38:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:38:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:38:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:38:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:38:04 INFO - Intel Iris: 13:38:04 INFO - Chipset Model: Intel Iris 13:38:04 INFO - Type: GPU 13:38:04 INFO - Bus: Built-In 13:38:04 INFO - VRAM (Dynamic, Max): 1536 MB 13:38:04 INFO - Vendor: Intel (0x8086) 13:38:04 INFO - Device ID: 0x0a2e 13:38:04 INFO - Revision ID: 0x0009 13:38:04 INFO - Displays: 13:38:04 INFO - Display: 13:38:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:38:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:38:04 INFO - Display Serial Number: 1600x1200 60 13:38:04 INFO - Main Display: Yes 13:38:04 INFO - Mirror: Off 13:38:04 INFO - Online: Yes 13:38:04 INFO - Rotation: Supported 13:38:04 INFO - Return code: 0 13:38:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:38:04 INFO - Python 2.7.3 13:38:04 INFO - Return code: 0 13:38:04 INFO - grabbing minidump binary from tooltool 13:38:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:38:04 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 0x10519dd30>, '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 0x104f02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105195b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:38:04 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 13:38:04 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 13:38:04 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 13:38:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:38:05 INFO - Return code: 0 13:38:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:38:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:38:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:38:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:38:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:38:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0344', '--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 13:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0344 --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 13:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dBNAyFE8ex/Render', 13:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:38:05 INFO - 'HOME': '/Users/cltbld', 13:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:38:05 INFO - 'LOGNAME': 'cltbld', 13:38:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:38:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:05 INFO - 'MOZ_NO_REMOTE': '1', 13:38:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:38:05 INFO - 'NO_EM_RESTART': '1', 13:38:05 INFO - 'PAGER': '/bin/cat', 13:38:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:05 INFO - 'PWD': '/builds/slave/test', 13:38:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:38:05 INFO - 'SHELL': '/bin/bash', 13:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ErqkudnvtV/Listeners', 13:38:05 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 13:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:38:05 INFO - 'USER': 'cltbld', 13:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:05 INFO - 'XPC_FLAGS': '0x0', 13:38:05 INFO - 'XPC_SERVICE_NAME': '0', 13:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:38:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0344', '--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 13:38:05 INFO - mozversion application_buildid: 20160629114329 13:38:05 INFO - mozversion application_changeset: 60999c6bc40ac97478bb216182d02cd857e3e206 13:38:05 INFO - mozversion application_display_name: Nightly 13:38:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:38:05 INFO - mozversion application_name: Firefox 13:38:05 INFO - mozversion application_remotingname: firefox 13:38:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 13:38:05 INFO - mozversion application_vendor: Mozilla 13:38:05 INFO - mozversion application_version: 50.0a1 13:38:05 INFO - mozversion platform_buildid: 20160629114329 13:38:05 INFO - mozversion platform_changeset: 60999c6bc40ac97478bb216182d02cd857e3e206 13:38:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 13:38:05 INFO - mozversion platform_version: 50.0a1 13:38:05 INFO - using testdate: 1467232685 13:38:05 INFO - actual date: 1467232685 13:38:05 INFO - starting webserver on 'localhost:49268' 13:38:05 INFO - SUITE-START | Running 2 tests 13:38:05 INFO - TEST-START | dromaeo_css 13:38:05 INFO - operating with platform_type : mac_ 13:38:05 INFO - Initialising browser for dromaeo_css test... 13:38:05 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpTK0AH9/profile http://localhost:49268/getInfo.html) 13:38:07 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 13:38:07 INFO - PROCESS | 1648 | colorDepth:24 13:38:07 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 13:38:07 INFO - PROCESS | 1648 | __metrics 13:38:07 INFO - PROCESS | 1648 | 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:38:07 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:38:08 INFO - PROCESS | 1648 | ************************* 13:38:08 INFO - PROCESS | 1648 | A coding exception was thrown in a Promise resolution callback. 13:38:08 INFO - PROCESS | 1648 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 13:38:08 INFO - PROCESS | 1648 | 13:38:08 ERROR - PROCESS | 1648 | Full message: TypeError: win is null 13:38:08 INFO - PROCESS | 1648 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:858:17 13:38:08 INFO - PROCESS | 1648 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 13:38:08 INFO - PROCESS | 1648 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 13:38:08 INFO - PROCESS | 1648 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 13:38:08 INFO - PROCESS | 1648 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 13:38:08 INFO - PROCESS | 1648 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 13:38:08 INFO - PROCESS | 1648 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 13:38:08 INFO - PROCESS | 1648 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 13:38:08 INFO - PROCESS | 1648 | 13:38:08 INFO - PROCESS | 1648 | ************************* 13:38:08 INFO - TEST-INFO | 1648: exit 0 13:38:08 INFO - Browser initialized. 13:38:08 INFO - Running cycle 1/1 for dromaeo_css test... 13:38:08 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpTK0AH9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:38:20 INFO - PROCESS | 1650 | RSS: Main: 260206592 13:38:20 INFO - PROCESS | 1650 | 13:39:11 INFO - PROCESS | 1650 | 1467232751740 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] 13:41:23 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 13:41:24 INFO - PROCESS | 1650 | RSS: Main: 360800256 13:41:24 INFO - PROCESS | 1650 | 13:44:27 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 13:44:28 INFO - PROCESS | 1650 | RSS: Main: 391376896 13:44:28 INFO - PROCESS | 1650 | 13:47:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 13:47:31 INFO - PROCESS | 1650 | RSS: Main: 387379200 13:47:31 INFO - PROCESS | 1650 | 13:50:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 13:50:34 INFO - PROCESS | 1650 | RSS: Main: 387641344 13:50:34 INFO - PROCESS | 1650 | 13:53:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 13:53:38 INFO - PROCESS | 1650 | RSS: Main: 353988608 13:53:38 INFO - PROCESS | 1650 | 13:56:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 13:56:41 INFO - PROCESS | 1650 | RSS: Main: 413986816 13:56:41 INFO - PROCESS | 1650 | 13:56:41 INFO - PROCESS | 1650 | __start_tp_report 13:56:41 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 13:56:41 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 13:56:41 INFO - PROCESS | 1650 | |i|pagename|runs| 13:56:41 INFO - PROCESS | 1650 | |0;dojo.html;2773.9722602773977;3329.2842038961635;3342.314825460781;3358.0261724100014;3398.6601339866015;4704.847312408456;5585.581081418894;5653.321601407837;5660.858478538039;5711.457411545896;4800.49594792547;5650.773969041234;5668.68822214778;5797.478226959573;5815.621984571;5047.848564543051;5081.695098294114;5138.897222055557;5146.279520867081;5280.287161233223;5199.038177937075;5209;5233.21501774733;5263.236830659552;5266.210068489738;5066.8733281668065;5097.7196254206;5118;5171;5175.482451754832;4222.9577704222875;5148.665336753136;5150.407703114152;5153;5160.096983027993;4906.509349065101;5087.262346959707;5095;5119.923201151949;5121.897562048775;5566.2207290979295;5590.496855283044;5648.03983322832;5651.802186923438;5773.509251713567;6126.464448728089;6177.814665559996;6199.070139479114;6203.348648391899;6213.5339849511465;6202.131701561785;6267.937320626781;6276.623402595859;6286.937130628681;6302.590331628476;5612.85967850807;5663.999999999959;5683;5716.371199168088;5733.799317023884;5290.444503327172;6264.436200741955;6280.560360774702;6300.21247344082;6311.431971122621;6141.385861413823;6156.938430615726;6175.814725558241;6177;6179.073139029181;6052.346229221198;6069.332373438918;6113.052476866093;6128.2033335666965;6150.261968563801;5169.870753231199;6170.56806023583;6178.382161783786;6190.845228869224;6197.25632924052;5815.447532484407;5856.297244330707;6159.3224745277985;6171.84570385744;6182.041783523561;4799.784009719571;4845.3216549683075;4847.321375007502;4909.950900491021;4910.558049775537;4395.868123956287;4425.291953287458;4431.135928493977;4441.2227860124995;4444.533324000998;5275.997560463499;5582.91625625616;5639.915401268984;5655.4344565543115;5687.744051517691;6234.1583886174785;6245.406686364788;6259.436650701458;6265.498760099181;6285.214348206474;5515.062439385314;5575.721213939368;5625.971870140623;5646.2942132233475;5662.17898404729;5283.656562323436;5312.256284120226;5325.6272060956135;5331.733413329318;5332.253484512172;6299.212598425197;6308.589941653778;6320;6335.303116657256;6338;6109.175261339662;6116.480099191618;6119.045604113885;6142.262928448615;6173.259208894961;6226.59527130735;6230.376962303914;6280.9685951569945;6296.275928268308;6297.433231009231;5095.847124586268;6129.938700612936;6143.109249158758;6183.5671502994355;6183.598066125687;5903.380145084704;6193.256809182927;6216.2540495140875;6216.968915155395;6231.40801623845;5913.46778789911;5978.3423823378525;6031.638101713911;6038.969805150946;6059.09113632941;5950.077737950624;6011.549133815069;6023.156758053964;6037.758489660365;6049.848753781191;5726.685032323262;5726.713664316684;5827.184194212899;5895.351511333664;5925.970370148121;5923.229980102731;6000.579959402975;6006.429389208019;6009.9699501503965;6043.697815109315;5843.29880414353;5852.824415267549;5858.531317494505;5860.501857342174;5867.765289388377 13:56:41 INFO - PROCESS | 1650 | |1;ext.html;501.71903733908994;508.20465970755765;528.2393353570857;540.410952062252;548.7859734703467;30180.698193018063;34378.828105859495;40977.79511102444;41571.58428415719;41685.37471937924;43368.13263734723;43578.78210608943;43605.345919811225;43607.69176924697;43609.56390436095;8820.603072861704;9486.47824369661;9717.125458708715;9727.56225969833;9739.853902191473;8794.86807697885;11656.883431165706;11826.58606948757;11850.229735067236;12084.214526055823;11818.822717659243;11884.881151188463;11895.226810257349;11900.880991190108;11908.940455297712;8710.99823520297;8911.376203665746;8920.464772113693;11588.304701717881;11838.230515016583;8837.513936733478;11479.655610331703;11740.354280514568;11767.764644707058;12015;40266.798666006776;40556.39165412521;40567.7971610143;40624.39063414051;40636;3455.861765529401;3476.9304613907834;3870.6903447724117;4151.735945214208;4243.893902652459;31198.37603247945;32291.031269061965;32466.026019219462;32503.862364817356;32609;3266.2324353776844;3412.1128506588216;3426.5031570422148;3432.3650124726755;3466.254755227613;522802;525377;526108;526292;528160;61447;61627.38372616217;61966.0705089424;62030.68984655138;62200.066998995055;65465.672671636334;65534;65553;65553.01670474946;65607.67196163989;16157.434489793035;16330.67338653244;16360.918195408947;16372;16397.59006024859;11712.590059347882;11927.343996080126;12752.936235318764;12923.612291631267;12999.350032498338;17525.386611468537;17558.29766809313;17588.648227035388;17589.944603323842;17656.82343176552;6309.747610095545;6325.841853953596;6331.651759153198;6401.615903045832;6456.451201647818;865.3855762408634;1002.5187909803335;1034.5603118674533;1078.0997866781324;1155.358775879395;26683.399817727914;29637.407251855275;30285.242868928013;30466.695333046828;30955.226119346746;1079.8704155501391;1086.2613422872364;1128.2948157401624;1139.0830381542771;1149.689583812366;15727.44953926607;15765.68468630621;15906.60233494172;16087.678246435005;16469.88699863405;573246;732069;743738;744153;976189;59652;60048.69975650181;60567;60602.39397606056;60789.480262992896;1243.2726854790048;1278.8081787731915;1376.215557132445;1393.9442422303198;1409.105218186459;27585.448291034467;30580;30930.845345773127;30979.225519362197;32559;57494;57685.84628307494;57980.13029804472;58135.70932145312;58188.70905645445;622749;625437;629106;630536;630887;22138.003789829818;23302.01793832901;23800.88099559491;26446.86776566105;26649.600255996564;34322.97031089071;45216.77391613021;46017;46562.53437465582;46632.7668361656;32407.675923241408;35817.283654326246;38766;38811.6118838808;39336.803315983234;32502;37438;37527.24945501129;37905.62094379021;38536.80731596324;30139;36672.26655466929;37242.44136337903;37441;37573;32517;32951.17622059468;33793.66206337905;33810;33965.811196608;24707.505849882542;24737.628935565623;24918.75081249237;25264.873675631505;25425 13:56:41 INFO - PROCESS | 1650 | |2;jquery.html;318.8852013275221;353.4627366403069;363.6745113123755;377.1852797956413;405.76262886211566;15207.695846083072;15418.460353887616;19333.516662083453;20672.862992535403;20702.896485517587;17341.393051243176;19854.801451985477;20835.791642083575;21263.36209913701;21267.255646052392;5322.387925388572;5504.944950550504;5517.117261238202;5517.69652669103;5538.833834984957;5539.196816461611;5903;5921.289445266552;5953.791617293396;5959.284885813709;5830.44610761979;5836.824895253128;5928.02187639039;5987.790427335044;5993.0710739835395;5865.853353666171;5915.349311575723;5921.555883308769;5947.316058653267;5955.8213253602025;5716.3426205986425;5804.651720896717;5871.6183448075735;5882.55880808941;5882.735276912506;14301.713965720628;24988.125415610368;25982.700864956867;25985;26066.087686930867;1920.4910698664864;2177.205320058183;2481.9751802481924;2563.9487210255875;2903.3757742085554;8479.728040793869;9887.752806179904;9890.752731181778;9963.15313198379;10025.899741002642;1764.938227162056;1812.8549716022687;1818.5180927054341;1825.954351141219;1907.4468404162776;115624;120025.39987300007;126461.99999999908;127897.72102279044;129019.7098029017;11267.380294083903;11441.485133169028;11467.885321146849;11524.366159861289;11622.825657615143;14120.152790832582;14255;14292.42830286777;14304.141751494944;14323.427062917575;8551.444156129955;8559.614817333235;8605.182507661762;8605.440646358093;8695.826083478294;8603.354748393771;8615;8616.569171541523;8722.825543489094;8742.562871856382;4527.230370836965;4553.954460455419;4572.74849883253;4573.382593349856;4615.838445654386;1837.6967800313066;1890.432870138953;1911.665458544749;1934.5067007912892;1937.273522429089;720.8051483233567;785.6660919109356;799.2007992007992;891.2558014058334;902.1700035966926;17640.735388969177;18695.065246737882;21428;22104;22107.447313816963;939.4992319966498;944.6693657219918;948.9478078705738;950.4107453378949;1051.6476980211562;11393.772124557629;11400.543978240943;11572.826407603892;11587.362695051854;11593.014593759457;619026;621122;629614;633553;634458;129499;133651;140071;142784.57215427922;145298;817.9304759095544;868.2533021601417;1002.964896228643;1003.5734812704571;1011.9829571280962;17246.741298880275;19092.522686932938;19188.944608046135;19554.60890782148;19652.213911443952;38900.63847765315;50870.7282317945;54107.45892541024;58463.12305315339;58742.41257587369;79032.02419939317;85406;86375;87526;90013.54993225254;6955.478339124687;7159.890217016371;7700.152983171734;8259.628316725642;8265.586720663823;12251.264924104582;13039.152455090389;13144.737105257651;13189.142705724482;13239.867601323864;11218.102551795673;11335;11410.885891141314;11439.256448330832;11513.13651476126;8889.644414223618;10644.77585077726;11055.613053543088;11340.546378144782;11507.482163302839;11431.485583148944;11622.6513204604;11663.708407289885;11784.705382365166;11786.882131178578;7975.4018448615425;8099.473534220257;8186.877196841933;8361.581920903809;8378.329733621418;10513.789724205626;11309.943450283025;11371.601993930173;11456.19806613529;11506.712332191762 13:56:41 INFO - PROCESS | 1650 | |3;mootools.html;1488.5980785187999;1547.7987861577997;1720.4580557124505;1849.7410362549253;1892.082340065069;7675.232476752322;8441.831163376737;8674.653013879437;8888.555572221387;8902.643894244238;6782.253952065269;7985.04179498461;8376.120507346732;8584.356173287013;8640.91359086409;2338.812894968403;2363.161077817376;2365.4441206316587;2365.7752513511273;2367.3844800351862;2332.790048895599;2333.183385814968;2380.3573035280465;2381.071382160959;2383.2969274064194;2091.5712278982846;2154.052217024512;2163.0590693048493;2165.32874808809;2166.8483206175574;2290.862548247102;2348.8120950323932;2356;2357.481354102112;2359.28041947206;2330.4290448839974;2348.729896061958;2361.893714782822;2367.2306500387444;2373.0389192377115;7839.529628222325;8029.879551806727;8043.39674524413;8800.427972181851;8881.511516866569;4794.137055330039;5163.845084647466;5304.893902121975;5329.840104796824;5354.491323324278;3587.802670853102;3616.4032934565766;3665.450182472624;3674.3202507535925;3680.7301480989017;1080.1197024425069;1091.841682955967;1097.5993762276792;1101.2456467319832;1114.4650567727535;11569.42152892352;11675;11682.532698692126;11693.298402095987;11749.4712737927;4555.772211389483;4584.266517357207;4783.880402989884;4786.401699787527;4791.712497250106;10018.398896066114;10022.398656080513;10027.749306267402;10033.197344212445;10038.548265328078;5356.946430535722;5363.329583802025;5365.946340536622;5373.704446255493;5376.924615077;3577.58857731359;4888.95111048885;4907;4907.435644900811;4912.042151780369;3448.77582957112;3618.5476815398074;3698.6671199592165;3757.248550289953;3793.260314238752;1151.0791366906442;1152.2798251093068;1161.5760247509597;1168.824676298545;1170.0814860334597;770.4106358635648;781.8475397297473;902.2466240689098;917.6971599372191;938.1416004355938;5844.1818145459665;5943.494802941712;5994;6109.725062372204;6153.876922461525;813.4184058398156;891.9598618062073;903.9819203616022;936.6487567162314;941.1811723800338;3498.8075655839175;3858.3247931611854;3976.886471787904;4023.597640235953;4037.1925614876554;10403.375797452025;10664.146868250522;10721.98141176587;10786.838197427045;10796.78406431867;10571.62999295021;10597.31117477377;10663.946680266705;10695.73260668474;10768.515416806262;7861.52830830152;8055.959720201478;8215.383846211558;8236.217559331853;8237.629306681214;5904;5935.228420305332;6021.277446706424;6026.427489388501;6052.66710330936;10018.098371146632;10146.18830493544;10156.984301569784;10164.288499804943;10171.694849154537;9762.951185244028;9989;10254.743631409274;10407.47962601882;10453.320534165256;7549.849003019799;7618.276263754934;7714.1128770192145;7716.884246736191;7717.305442510201;8153.714619988272;8273.296769774395;8341.958290208753;8347;8363.080061193386;7474.439417043634;7507.774766757006;7543.509671871311;7597;7715.614219289126;7161;7304.780856574312;7388.704451821867;7423.777286681408;7550.187954890677;6933.514653974174;7005.824854378681;7081.610511421921;7090.113735783028;7092.25531319204;5000.049990501793;5032.823851165192;5045.646804723634;5122.79508819658;5129.820456284012;5101.9489805101475;5139.1263485206855;5148.6910785353;5221.112410890234;5255.185446255837 13:56:41 INFO - PROCESS | 1650 | |4;prototype.html;254.78343408103112;283.67660866612056;295.8964362473136;308.4201700802492;312.7873046328497;4615.869112067544;5181.5854731621475;5190.740462976845;5190.8702282443;5216.5826733861295;4471.575200355961;4491.663125265602;4982.327385802915;5249.527542521172;5352.652077614962;3325.7173140283103;4106.527749308823;4108.986596567675;4110.7739074350975;4130.380442933554;3895.4935858338376;4119.897002574945;4131.462909821719;4148.771817550033;4149.5228048774325;3380.983095084521;3511.736619753516;4110.712250142491;4113.341865301552;4129.501651500829;3753.924921501582;3904.4338570907344;4077.8368865245357;4078.3066878630702;4135.524414692319;3130.655627880931;3589.8205089745406;3594.11944073701;3602.6757591816863;3610.8806270056366;4249.575042495757;5029.893423446836;5128.5126713543905;5170.508801663836;5257.894842103175;3746.044758586543;4326.913461730748;4669.575779387285;4716.2454007359;4729.787159577827;4622.468416132154;4676.602488788457;5612.747426365782;5620;5671.886562268732;3422.4695172248094;3811.5706610021243;3914.706397020235;3927.6857851371824;3954.2486927483687;4867.756612169413;5435.755391007414;5634.436556344414;5750.338711048241;5774.498630356095;3827.8086095695103;3964.5440774310746;3978.124812541236;4014.715291106818;4031.294523458383;3813.828377723009;3896.4350169225872;4046.9595304046584;4057.6753859691153;4064.5732198119363;3876.6317199865966;3979.313963074913;4646.002219045843;4667.6965997210755;4673.813047478131;5605.80379686709;5640.915386269209;5651.0675738503505;5661.7735290588735;5671.744771485293;1211.5275042733342;1249.9437525311382;1330.1885849631717;1334.8197993270785;1388.861113888603;713.5896859305919;778.350077685128;835.3734698975768;871.4596949891109;873.0527377795163;4100.897477563085;4102.220578090153;4107.898536604845;4142.337226043879;4272.654113954094;3765.456162973168;3909.706771992112;4075.918481630351;4311.928437362728;4657.673962822638;3880.671597041752;4248.852809741353;4367.410399596078;4384.320430333303;4419.6466480516665;2293.747687754379;2389.557931782626;2468.8888999995042;2502.435977514054;2572.9099481518056;4812.542808433193;4869.001854619794;4908.410990681141;4912.066707325596;4965.577925876341;3992.6606238470054;4427.889302767457;4553.157665831832;4640.97679511607;4669.342383453893;4175.457969356991;4563.452385713736;4741.723972493222;4802.759862006886;4957.950420495749;3891.377379619247;4012.2376748417705;4225.8309667614185;4753.524647535219;4790.275500819772;4553.430821147356;4657.208274593259;5338.9834743369875;5372.677639341652;5403.216938410394;5226.059309324206;5448;5939.049752039653;5960.165576819336;5973.611715238496;3951.38753493209;3953.169834334822;3960.9603903961743;3972.940405893856;3980.601939805996;5046.543117964206;5356.384015838073;5428.864278393072;5666.600039975981;5744.368119506935;5483.479069488392;5562.610617256753;5576.773109915812;5580.804671836466;5588.050031494737;4733.455652599858;4746.715197088186;5201.739913004259;5539.19681646151;5660.773569057192;4812.422509298766;5511.834644960497;5519.972400137974;5522.530584900329;5537.723113844372;5076.543111120017;5194.922076168936;5573.108302671553;5587.608867379245;5590.8043218487155;4767.6662633615315;4826.541478559531;5291.862249616351;5517.020808763223;5528.336599608073 13:56:41 INFO - PROCESS | 1650 | |5;yui.html;728.3990707666175;753.3521171792257;782.1240210963718;811.6956141446957;841.5329492131866;15435.845641543596;16016.679666406664;16172.595685107879;16239.675206495878;16251.349946002176;10321.638742644009;12789.552365667203;12801.743965120691;12951.74096518069;12979.415926283316;688.3495097133211;844.940854140212;853.9487630742144;863.6761214544546;865.9134086591322;695.6765104226533;707.1796715809662;848.7750746052301;865.9913400865973;871.7428358634187;907.1110311894347;910.7313342563926;912.6303846942;912.6851236323457;912.8174365126968;725.2855936902147;877.4515927545284;903.6837107012559;904.9140331668481;905.8097799462088;665.9700313485905;668.7993601919453;669.7890164598185;670.2191946382459;670.3363669966747;15221.467248646355;15270.236488175547;15279.541613751606;15281.465148719853;15293.158876261801;14225;14290.57128286153;14308.356123974447;14367.066140700821;14618.26908654563;13083.542076027294;13209.33953302331;13268.86731132686;13470.595882123454;13516.594502164951;4569.657275704336;4674.298855171752;4691.249400096002;4695.81216751333;4806.591439727627;118377.81622183754;119613;119858.80141198564;120071.39964300209;120378.79621203763;11342.319460832212;11452.255603385753;11728.94135529317;11811.350375729417;11845;18666.533336666693;20381.592368152553;23017.964191611416;23020.4244893879;23036.308910732703;8122.796930076677;8307.501549907025;8361.83276334482;8421.74734757946;8423.368247381472;4235.195312890541;4420.138073075784;4499.640028797754;4517.322401639781;4574.176648203355;3377.4427219508507;3542.6634469725336;3547.343741407848;3547.8048707320827;3553.520274762874;617.2685367839125;636.5353292096795;637.2066776862836;650.1353200243665;651.5732195412013;564.4327450911808;579.8231539380445;637.7576520922113;638.9648569328639;650.5933791380387;8845.805816214857;8857;8907.910920890708;9024.291558645938;9074.092590740873;568.4116938968148;580.5007693383686;595.59499540313;605.5065121925704;641.3490307338055;4327;4336.82652693895;4377.802998865059;4404.757738324359;4485.551444855553;104424.95575044304;138354;148160;154820;157290;124066;124390;126006;126625;126740.84547857317;108163.91836081538;111381.2154696139;112131.4393428044;112208;113740;8519.701810436605;8521.659133634585;8628.57512553688;8667.826643467222;8710;57316;58546.4145358558;58751;62208.066878995945;62334.37665623286;83279.16720832957;91140.54429727809;91608.54195728978;92199.6170057463;93676.53161734148;10937.781244375226;11090.667279981613;11136.276142050741;11293.209475336647;11411.486483108447;12088.093392995384;12127.575534856238;12163.81754273669;12321.753564928831;12748.681282968;11804.291742495478;11825.645230643095;11860.822087668865;11939.880601193878;12226.816597750863;9994.800103998024;11224.316352546857;11535.36555489456;11902.761944760883;12283.815742763687;11313.660590182308;11585.304881707125;11869.881301186877;11905.52377904874;11962.641120766391;9726.805463890541;9781.75545611337;9831.803363932824;10050.447225402673;10134.797304054026;9557.474338911426;9816.411015339103;9823.950880245553;9934.205263578751;9949 13:56:41 INFO - PROCESS | 1650 | __end_tp_report 13:56:41 INFO - PROCESS | 1650 | __start_cc_report 13:56:41 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1103 13:56:41 INFO - PROCESS | 1650 | __end_cc_report 13:56:41 INFO - PROCESS | 1650 | __startTimestamp1467233801587__endTimestamp 13:56:41 INFO - PROCESS | 1650 | 13:56:41 INFO - PROCESS | 1650 | ------- Summary: start ------- 13:56:41 INFO - PROCESS | 1650 | Number of tests: 6 13:56:41 INFO - PROCESS | 1650 | 13:56:41 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5651.13 Median:5843.30 stddev:665.21 (11.4%) stddev-sans-first:627.80 13:56:41 INFO - PROCESS | 1650 | Values: 2774.0 3329.3 3342.3 3358.0 3398.7 4704.8 5585.6 5653.3 5660.9 5711.5 4800.5 5650.8 5668.7 5797.5 5815.6 5047.8 5081.7 5138.9 5146.3 5280.3 5199.0 5209.0 5233.2 5263.2 5266.2 5066.9 5097.7 5118.0 5171.0 5175.5 4223.0 5148.7 5150.4 5153.0 5160.1 4906.5 5087.3 5095.0 5119.9 5121.9 5566.2 5590.5 5648.0 5651.8 5773.5 6126.5 6177.8 6199.1 6203.3 6213.5 6202.1 6267.9 6276.6 6286.9 6302.6 5612.9 5664.0 5683.0 5716.4 5733.8 5290.4 6264.4 6280.6 6300.2 6311.4 6141.4 6156.9 6175.8 6177.0 6179.1 6052.3 6069.3 6113.1 6128.2 6150.3 5169.9 6170.6 6178.4 6190.8 6197.3 5815.4 5856.3 6159.3 6171.8 6182.0 4799.8 4845.3 4847.3 4910.0 4910.6 4395.9 4425.3 4431.1 4441.2 4444.5 5276.0 5582.9 5639.9 5655.4 5687.7 6234.2 6245.4 6259.4 6265.5 6285.2 5515.1 5575.7 5626.0 5646.3 5662.2 5283.7 5312.3 5325.6 5331.7 5332.3 6299.2 6308.6 6320.0 6335.3 6338.0 6109.2 6116.5 6119.0 6142.3 6173.3 6226.6 6230.4 6281.0 6296.3 6297.4 5095.8 6129.9 6143.1 6183.6 6183.6 5903.4 6193.3 6216.3 6217.0 6231.4 5913.5 5978.3 6031.6 6039.0 6059.1 5950.1 6011.5 6023.2 6037.8 6049.8 5726.7 5726.7 5827.2 5895.4 5926.0 5923.2 6000.6 6006.4 6010.0 6043.7 5843.3 5852.8 5858.5 5860.5 5867.8 13:56:41 INFO - PROCESS | 1650 | 13:56:41 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:76033.50 Median:3471.59 stddev:173700.20 (5003.5%) stddev-sans-first:174094.90 13:56:41 INFO - PROCESS | 1650 | Values: 501.7 508.2 528.2 540.4 548.8 30180.7 34378.8 40977.8 41571.6 41685.4 43368.1 43578.8 43605.3 43607.7 43609.6 8820.6 9486.5 9717.1 9727.6 9739.9 8794.9 11656.9 11826.6 11850.2 12084.2 11818.8 11884.9 11895.2 11900.9 11908.9 8711.0 8911.4 8920.5 11588.3 11838.2 8837.5 11479.7 11740.4 11767.8 12015.0 40266.8 40556.4 40567.8 40624.4 40636.0 3455.9 3476.9 3870.7 4151.7 4243.9 31198.4 32291.0 32466.0 32503.9 32609.0 3266.2 3412.1 3426.5 3432.4 3466.3 522802.0 525377.0 526108.0 526292.0 528160.0 61447.0 61627.4 61966.1 62030.7 62200.1 65465.7 65534.0 65553.0 65553.0 65607.7 16157.4 16330.7 16360.9 16372.0 16397.6 11712.6 11927.3 12752.9 12923.6 12999.4 17525.4 17558.3 17588.6 17589.9 17656.8 6309.7 6325.8 6331.7 6401.6 6456.5 865.4 1002.5 1034.6 1078.1 1155.4 26683.4 29637.4 30285.2 30466.7 30955.2 1079.9 1086.3 1128.3 1139.1 1149.7 15727.4 15765.7 15906.6 16087.7 16469.9 573246.0 732069.0 743738.0 744153.0 976189.0 59652.0 60048.7 60567.0 60602.4 60789.5 1243.3 1278.8 1376.2 1393.9 1409.1 27585.4 30580.0 30930.8 30979.2 32559.0 57494.0 57685.8 57980.1 58135.7 58188.7 622749.0 625437.0 629106.0 630536.0 630887.0 22138.0 23302.0 23800.9 26446.9 26649.6 34323.0 45216.8 46017.0 46562.5 46632.8 32407.7 35817.3 38766.0 38811.6 39336.8 32502.0 37438.0 37527.2 37905.6 38536.8 30139.0 36672.3 37242.4 37441.0 37573.0 32517.0 32951.2 33793.7 33810.0 33965.8 24707.5 24737.6 24918.8 25264.9 25425.0 13:56:41 INFO - PROCESS | 1650 | 13:56:41 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:36373.45 Median:25983.85 stddev:104998.89 (404.1%) stddev-sans-first:105258.54 13:56:41 INFO - PROCESS | 1650 | Values: 318.9 353.5 363.7 377.2 405.8 15207.7 15418.5 19333.5 20672.9 20702.9 17341.4 19854.8 20835.8 21263.4 21267.3 5322.4 5504.9 5517.1 5517.7 5538.8 5539.2 5903.0 5921.3 5953.8 5959.3 5830.4 5836.8 5928.0 5987.8 5993.1 5865.9 5915.3 5921.6 5947.3 5955.8 5716.3 5804.7 5871.6 5882.6 5882.7 14301.7 24988.1 25982.7 25985.0 26066.1 1920.5 2177.2 2482.0 2563.9 2903.4 8479.7 9887.8 9890.8 9963.2 10025.9 1764.9 1812.9 1818.5 1826.0 1907.4 115624.0 120025.4 126462.0 127897.7 129019.7 11267.4 11441.5 11467.9 11524.4 11622.8 14120.2 14255.0 14292.4 14304.1 14323.4 8551.4 8559.6 8605.2 8605.4 8695.8 8603.4 8615.0 8616.6 8722.8 8742.6 4527.2 4554.0 4572.7 4573.4 4615.8 1837.7 1890.4 1911.7 1934.5 1937.3 720.8 785.7 799.2 891.3 902.2 17640.7 18695.1 21428.0 22104.0 22107.4 939.5 944.7 948.9 950.4 1051.6 11393.8 11400.5 11572.8 11587.4 11593.0 619026.0 621122.0 629614.0 633553.0 634458.0 129499.0 133651.0 140071.0 142784.6 145298.0 817.9 868.3 1003.0 1003.6 1012.0 17246.7 19092.5 19188.9 19554.6 19652.2 38900.6 50870.7 54107.5 58463.1 58742.4 79032.0 85406.0 86375.0 87526.0 90013.5 6955.5 7159.9 7700.2 8259.6 8265.6 12251.3 13039.2 13144.7 13189.1 13239.9 11218.1 11335.0 11410.9 11439.3 11513.1 8889.6 10644.8 11055.6 11340.5 11507.5 11431.5 11622.7 11663.7 11784.7 11786.9 7975.4 8099.5 8186.9 8361.6 8378.3 10513.8 11309.9 11371.6 11456.2 11506.7 13:56:41 INFO - PROCESS | 1650 | 13:56:41 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5691.96 Median:4897.98 stddev:3199.59 (65.3%) stddev-sans-first:3192.97 13:56:41 INFO - PROCESS | 1650 | Values: 1488.6 1547.8 1720.5 1849.7 1892.1 7675.2 8441.8 8674.7 8888.6 8902.6 6782.3 7985.0 8376.1 8584.4 8640.9 2338.8 2363.2 2365.4 2365.8 2367.4 2332.8 2333.2 2380.4 2381.1 2383.3 2091.6 2154.1 2163.1 2165.3 2166.8 2290.9 2348.8 2356.0 2357.5 2359.3 2330.4 2348.7 2361.9 2367.2 2373.0 7839.5 8029.9 8043.4 8800.4 8881.5 4794.1 5163.8 5304.9 5329.8 5354.5 3587.8 3616.4 3665.5 3674.3 3680.7 1080.1 1091.8 1097.6 1101.2 1114.5 11569.4 11675.0 11682.5 11693.3 11749.5 4555.8 4584.3 4783.9 4786.4 4791.7 10018.4 10022.4 10027.7 10033.2 10038.5 5356.9 5363.3 5365.9 5373.7 5376.9 3577.6 4889.0 4907.0 4907.4 4912.0 3448.8 3618.5 3698.7 3757.2 3793.3 1151.1 1152.3 1161.6 1168.8 1170.1 770.4 781.8 902.2 917.7 938.1 5844.2 5943.5 5994.0 6109.7 6153.9 813.4 892.0 904.0 936.6 941.2 3498.8 3858.3 3976.9 4023.6 4037.2 10403.4 10664.1 10722.0 10786.8 10796.8 10571.6 10597.3 10663.9 10695.7 10768.5 7861.5 8056.0 8215.4 8236.2 8237.6 5904.0 5935.2 6021.3 6026.4 6052.7 10018.1 10146.2 10157.0 10164.3 10171.7 9763.0 9989.0 10254.7 10407.5 10453.3 7549.8 7618.3 7714.1 7716.9 7717.3 8153.7 8273.3 8342.0 8347.0 8363.1 7474.4 7507.8 7543.5 7597.0 7715.6 7161.0 7304.8 7388.7 7423.8 7550.2 6933.5 7005.8 7081.6 7090.1 7092.3 5000.0 5032.8 5045.6 5122.8 5129.8 5101.9 5139.1 5148.7 5221.1 5255.2 13:56:41 INFO - PROCESS | 1650 | 13:56:41 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4275.22 Median:4619.17 stddev:1296.94 (28.1%) stddev-sans-first:1264.99 13:56:41 INFO - PROCESS | 1650 | Values: 254.8 283.7 295.9 308.4 312.8 4615.9 5181.6 5190.7 5190.9 5216.6 4471.6 4491.7 4982.3 5249.5 5352.7 3325.7 4106.5 4109.0 4110.8 4130.4 3895.5 4119.9 4131.5 4148.8 4149.5 3381.0 3511.7 4110.7 4113.3 4129.5 3753.9 3904.4 4077.8 4078.3 4135.5 3130.7 3589.8 3594.1 3602.7 3610.9 4249.6 5029.9 5128.5 5170.5 5257.9 3746.0 4326.9 4669.6 4716.2 4729.8 4622.5 4676.6 5612.7 5620.0 5671.9 3422.5 3811.6 3914.7 3927.7 3954.2 4867.8 5435.8 5634.4 5750.3 5774.5 3827.8 3964.5 3978.1 4014.7 4031.3 3813.8 3896.4 4047.0 4057.7 4064.6 3876.6 3979.3 4646.0 4667.7 4673.8 5605.8 5640.9 5651.1 5661.8 5671.7 1211.5 1249.9 1330.2 1334.8 1388.9 713.6 778.4 835.4 871.5 873.1 4100.9 4102.2 4107.9 4142.3 4272.7 3765.5 3909.7 4075.9 4311.9 4657.7 3880.7 4248.9 4367.4 4384.3 4419.6 2293.7 2389.6 2468.9 2502.4 2572.9 4812.5 4869.0 4908.4 4912.1 4965.6 3992.7 4427.9 4553.2 4641.0 4669.3 4175.5 4563.5 4741.7 4802.8 4958.0 3891.4 4012.2 4225.8 4753.5 4790.3 4553.4 4657.2 5339.0 5372.7 5403.2 5226.1 5448.0 5939.0 5960.2 5973.6 3951.4 3953.2 3961.0 3972.9 3980.6 5046.5 5356.4 5428.9 5666.6 5744.4 5483.5 5562.6 5576.8 5580.8 5588.1 4733.5 4746.7 5201.7 5539.2 5660.8 4812.4 5511.8 5520.0 5522.5 5537.7 5076.5 5194.9 5573.1 5587.6 5590.8 4767.7 4826.5 5291.9 5517.0 5528.3 13:56:41 INFO - PROCESS | 1650 | 13:56:41 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:24444.81 Median:4412.45 stddev:39458.76 (894.3%) stddev-sans-first:39529.27 13:56:41 INFO - PROCESS | 1650 | Values: 728.4 753.4 782.1 811.7 841.5 15435.8 16016.7 16172.6 16239.7 16251.3 10321.6 12789.6 12801.7 12951.7 12979.4 688.3 844.9 853.9 863.7 865.9 695.7 707.2 848.8 866.0 871.7 907.1 910.7 912.6 912.7 912.8 725.3 877.5 903.7 904.9 905.8 666.0 668.8 669.8 670.2 670.3 15221.5 15270.2 15279.5 15281.5 15293.2 14225.0 14290.6 14308.4 14367.1 14618.3 13083.5 13209.3 13268.9 13470.6 13516.6 4569.7 4674.3 4691.2 4695.8 4806.6 118377.8 119613.0 119858.8 120071.4 120378.8 11342.3 11452.3 11728.9 11811.4 11845.0 18666.5 20381.6 23018.0 23020.4 23036.3 8122.8 8307.5 8361.8 8421.7 8423.4 4235.2 4420.1 4499.6 4517.3 4574.2 3377.4 3542.7 3547.3 3547.8 3553.5 617.3 636.5 637.2 650.1 651.6 564.4 579.8 637.8 639.0 650.6 8845.8 8857.0 8907.9 9024.3 9074.1 568.4 580.5 595.6 605.5 641.3 4327.0 4336.8 4377.8 4404.8 4485.6 104425.0 138354.0 148160.0 154820.0 157290.0 124066.0 124390.0 126006.0 126625.0 126740.8 108163.9 111381.2 112131.4 112208.0 113740.0 8519.7 8521.7 8628.6 8667.8 8710.0 57316.0 58546.4 58751.0 62208.1 62334.4 83279.2 91140.5 91608.5 92199.6 93676.5 10937.8 11090.7 11136.3 11293.2 11411.5 12088.1 12127.6 12163.8 12321.8 12748.7 11804.3 11825.6 11860.8 11939.9 12226.8 9994.8 11224.3 11535.4 11902.8 12283.8 11313.7 11585.3 11869.9 11905.5 11962.6 9726.8 9781.8 9831.8 10050.4 10134.8 9557.5 9816.4 9824.0 9934.2 9949.0 13:56:41 INFO - PROCESS | 1650 | -------- Summary: end -------- 13:56:41 INFO - PROCESS | 1650 | 13:56:42 INFO - TEST-INFO | 1650: exit 0 13:56:42 INFO - TEST-OK | dromaeo_css | took 1116956ms 13:56:42 INFO - TEST-START | kraken 13:56:42 INFO - operating with platform_type : mac_ 13:56:42 INFO - Initialising browser for kraken test... 13:56:42 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpTucwUv/profile http://localhost:49268/getInfo.html) 13:56:44 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 13:56:44 INFO - PROCESS | 1659 | colorDepth:24 13:56:44 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 13:56:44 INFO - PROCESS | 1659 | __metrics 13:56:44 INFO - PROCESS | 1659 | 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:56:44 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:56:44 INFO - PROCESS | 1659 | ************************* 13:56:44 INFO - PROCESS | 1659 | A coding exception was thrown in a Promise resolution callback. 13:56:44 INFO - PROCESS | 1659 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 13:56:44 INFO - PROCESS | 1659 | 13:56:44 ERROR - PROCESS | 1659 | Full message: TypeError: win is null 13:56:44 INFO - PROCESS | 1659 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:858:17 13:56:44 INFO - PROCESS | 1659 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 13:56:44 INFO - PROCESS | 1659 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 13:56:44 INFO - PROCESS | 1659 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 13:56:44 INFO - PROCESS | 1659 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 13:56:44 INFO - PROCESS | 1659 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 13:56:44 INFO - PROCESS | 1659 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 13:56:44 INFO - PROCESS | 1659 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 13:56:44 INFO - PROCESS | 1659 | 13:56:44 INFO - PROCESS | 1659 | ************************* 13:56:45 INFO - TEST-INFO | 1659: exit 0 13:56:45 INFO - Browser initialized. 13:56:45 INFO - Running cycle 1/1 for kraken test... 13:56:45 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpTucwUv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:56:57 INFO - PROCESS | 1660 | RSS: Main: 266948608 13:56:57 INFO - PROCESS | 1660 | 13:56:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 13:57:00 INFO - PROCESS | 1660 | RSS: Main: 314105856 13:57:00 INFO - PROCESS | 1660 | 13:57:03 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 13:57:03 INFO - PROCESS | 1660 | RSS: Main: 324669440 13:57:03 INFO - PROCESS | 1660 | 13:57:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 13:57:08 INFO - PROCESS | 1660 | RSS: Main: 407429120 13:57:08 INFO - PROCESS | 1660 | 13:57:10 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 13:57:10 INFO - PROCESS | 1660 | RSS: Main: 308801536 13:57:10 INFO - PROCESS | 1660 | 13:57:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:57:13 INFO - PROCESS | 1660 | RSS: Main: 313004032 13:57:13 INFO - PROCESS | 1660 | 13:57:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 13:57:16 INFO - PROCESS | 1660 | RSS: Main: 348835840 13:57:16 INFO - PROCESS | 1660 | 13:57:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 13:57:20 INFO - PROCESS | 1660 | RSS: Main: 352686080 13:57:20 INFO - PROCESS | 1660 | 13:57:23 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 13:57:23 INFO - PROCESS | 1660 | RSS: Main: 348631040 13:57:23 INFO - PROCESS | 1660 | 13:57:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:57:26 INFO - PROCESS | 1660 | RSS: Main: 331706368 13:57:26 INFO - PROCESS | 1660 | 13:57:27 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:57:28 INFO - PROCESS | 1660 | RSS: Main: 338444288 13:57:28 INFO - PROCESS | 1660 | 13:57:30 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:57:31 INFO - PROCESS | 1660 | RSS: Main: 336302080 13:57:31 INFO - PROCESS | 1660 | 13:57:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:57:35 INFO - PROCESS | 1660 | RSS: Main: 344612864 13:57:35 INFO - PROCESS | 1660 | 13:57:37 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:57:38 INFO - PROCESS | 1660 | RSS: Main: 347148288 13:57:38 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 13:57:40 INFO - PROCESS | 1660 | RSS: Main: 390508544 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | __start_tp_report 13:57:40 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 13:57:40 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 13:57:40 INFO - PROCESS | 1660 | |i|pagename|runs| 13:57:40 INFO - PROCESS | 1660 | |0;ai-astar;103;113;113;113;113;113;114;115;113;112 13:57:40 INFO - PROCESS | 1660 | |1;audio-beat-detection;142;111;105;153;107;107;130;105;140;106 13:57:40 INFO - PROCESS | 1660 | |2;audio-dft;192;185;185;186;184;182;182;185;182;182 13:57:40 INFO - PROCESS | 1660 | |3;audio-fft;72;96;58;107;59;57;59;68;82;56 13:57:40 INFO - PROCESS | 1660 | |4;audio-oscillator;89;86;93;69;78;69;96;72;69;101 13:57:40 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;89;87;91;87;89;87;90;87;89;87 13:57:40 INFO - PROCESS | 1660 | |6;imaging-darkroom;95;95;95;95;96;103;147;147;95;95 13:57:40 INFO - PROCESS | 1660 | |7;imaging-desaturate;87;85;86;84;84;84;83;80;84;85 13:57:40 INFO - PROCESS | 1660 | |8;json-parse-financial;55;56;54;54;54;53;53;53;54;54 13:57:40 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;67;70;72;75;70;68;72;70;70;72 13:57:40 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;83;81;84;81;82;81;80;82;78;77 13:57:40 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;140;144;140;150;150;132;144;144;220;137 13:57:40 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;165;164;164;163;218;211;162;164;164;156 13:57:40 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;67;67;68;67;67;67;65;67;67;67 13:57:40 INFO - PROCESS | 1660 | __end_tp_report 13:57:40 INFO - PROCESS | 1660 | __start_cc_report 13:57:40 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1994 13:57:40 INFO - PROCESS | 1660 | __end_cc_report 13:57:40 INFO - PROCESS | 1660 | __startTimestamp1467233860516__endTimestamp 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | ------- Summary: start ------- 13:57:40 INFO - PROCESS | 1660 | Number of tests: 14 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:3.33 (2.9%) stddev-sans-first:0.83 13:57:40 INFO - PROCESS | 1660 | Values: 103.0 113.0 113.0 113.0 113.0 113.0 114.0 115.0 113.0 112.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:120.60 Median:120.50 stddev:18.66 (15.5%) stddev-sans-first:18.12 13:57:40 INFO - PROCESS | 1660 | Values: 142.0 111.0 105.0 153.0 107.0 107.0 130.0 105.0 140.0 106.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:3.06 (1.7%) stddev-sans-first:1.66 13:57:40 INFO - PROCESS | 1660 | Values: 192.0 185.0 185.0 186.0 184.0 182.0 182.0 185.0 182.0 182.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:71.40 Median:63.50 stddev:18.04 (28.4%) stddev-sans-first:19.13 13:57:40 INFO - PROCESS | 1660 | Values: 72.0 96.0 58.0 107.0 59.0 57.0 59.0 68.0 82.0 56.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:82.20 Median:82.00 stddev:12.32 (15.0%) stddev-sans-first:12.82 13:57:40 INFO - PROCESS | 1660 | Values: 89.0 86.0 93.0 69.0 78.0 69.0 96.0 72.0 69.0 101.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.00 stddev:1.49 (1.7%) stddev-sans-first:1.56 13:57:40 INFO - PROCESS | 1660 | Values: 89.0 87.0 91.0 87.0 89.0 87.0 90.0 87.0 89.0 87.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:106.30 Median:95.00 stddev:21.59 (22.7%) stddev-sans-first:22.51 13:57:40 INFO - PROCESS | 1660 | Values: 95.0 95.0 95.0 95.0 96.0 103.0 147.0 147.0 95.0 95.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.50 stddev:1.87 (2.2%) stddev-sans-first:1.69 13:57:40 INFO - PROCESS | 1660 | Values: 87.0 85.0 86.0 84.0 84.0 84.0 83.0 80.0 84.0 85.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:0.94 (1.7%) stddev-sans-first:0.93 13:57:40 INFO - PROCESS | 1660 | Values: 55.0 56.0 54.0 54.0 54.0 53.0 53.0 53.0 54.0 54.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.00 stddev:2.27 (3.2%) stddev-sans-first:2.00 13:57:40 INFO - PROCESS | 1660 | Values: 67.0 70.0 72.0 75.0 70.0 68.0 72.0 70.0 70.0 72.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.50 stddev:2.13 (2.6%) stddev-sans-first:2.12 13:57:40 INFO - PROCESS | 1660 | Values: 83.0 81.0 84.0 81.0 82.0 81.0 80.0 82.0 78.0 77.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:150.10 Median:144.00 stddev:25.17 (17.5%) stddev-sans-first:26.43 13:57:40 INFO - PROCESS | 1660 | Values: 140.0 144.0 140.0 150.0 150.0 132.0 144.0 144.0 220.0 137.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:164.00 stddev:22.03 (13.4%) stddev-sans-first:23.17 13:57:40 INFO - PROCESS | 1660 | Values: 165.0 164.0 164.0 163.0 218.0 211.0 162.0 164.0 164.0 156.0 13:57:40 INFO - PROCESS | 1660 | 13:57:40 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 13:57:40 INFO - PROCESS | 1660 | Values: 67.0 67.0 68.0 67.0 67.0 67.0 65.0 67.0 67.0 67.0 13:57:40 INFO - PROCESS | 1660 | -------- Summary: end -------- 13:57:40 INFO - PROCESS | 1660 | 13:57:41 INFO - TEST-INFO | 1660: exit 0 13:57:41 INFO - TEST-OK | kraken | took 59131ms 13:57:41 INFO - SUITE-END | took 1176s 13:57:41 INFO - Completed test suite (00:19:36) 13:57:41 INFO - javascript benchmark 13:57:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2773.9722602773977, 3329.2842038961635, 3342.314825460781, 3358.0261724100014, 3398.6601339866015, 4704.847312408456, 5585.581081418894, 5653.321601407837, 5660.858478538039, 5711.457411545896, 4800.49594792547, 5650.773969041234, 5668.68822214778, 5797.478226959573, 5815.621984571, 5047.848564543051, 5081.695098294114, 5138.897222055557, 5146.279520867081, 5280.287161233223, 5199.038177937075, 5209.0, 5233.21501774733, 5263.236830659552, 5266.210068489738, 5066.8733281668065, 5097.7196254206, 5118.0, 5171.0, 5175.482451754832, 4222.9577704222875, 5148.665336753136, 5150.407703114152, 5153.0, 5160.096983027993, 4906.509349065101, 5087.262346959707, 5095.0, 5119.923201151949, 5121.897562048775, 5566.2207290979295, 5590.496855283044, 5648.03983322832, 5651.802186923438, 5773.509251713567, 6126.464448728089, 6177.814665559996, 6199.070139479114, 6203.348648391899, 6213.5339849511465, 6202.131701561785, 6267.937320626781, 6276.623402595859, 6286.937130628681, 6302.590331628476, 5612.85967850807, 5663.999999999959, 5683.0, 5716.371199168088, 5733.799317023884, 5290.444503327172, 6264.436200741955, 6280.560360774702, 6300.21247344082, 6311.431971122621, 6141.385861413823, 6156.938430615726, 6175.814725558241, 6177.0, 6179.073139029181, 6052.346229221198, 6069.332373438918, 6113.052476866093, 6128.2033335666965, 6150.261968563801, 5169.870753231199, 6170.56806023583, 6178.382161783786, 6190.845228869224, 6197.25632924052, 5815.447532484407, 5856.297244330707, 6159.3224745277985, 6171.84570385744, 6182.041783523561, 4799.784009719571, 4845.3216549683075, 4847.321375007502, 4909.950900491021, 4910.558049775537, 4395.868123956287, 4425.291953287458, 4431.135928493977, 4441.2227860124995, 4444.533324000998, 5275.997560463499, 5582.91625625616, 5639.915401268984, 5655.4344565543115, 5687.744051517691, 6234.1583886174785, 6245.406686364788, 6259.436650701458, 6265.498760099181, 6285.214348206474, 5515.062439385314, 5575.721213939368, 5625.971870140623, 5646.2942132233475, 5662.17898404729, 5283.656562323436, 5312.256284120226, 5325.6272060956135, 5331.733413329318, 5332.253484512172, 6299.212598425197, 6308.589941653778, 6320.0, 6335.303116657256, 6338.0, 6109.175261339662, 6116.480099191618, 6119.045604113885, 6142.262928448615, 6173.259208894961, 6226.59527130735, 6230.376962303914, 6280.9685951569945, 6296.275928268308, 6297.433231009231, 5095.847124586268, 6129.938700612936, 6143.109249158758, 6183.5671502994355, 6183.598066125687, 5903.380145084704, 6193.256809182927, 6216.2540495140875, 6216.968915155395, 6231.40801623845, 5913.46778789911, 5978.3423823378525, 6031.638101713911, 6038.969805150946, 6059.09113632941, 5950.077737950624, 6011.549133815069, 6023.156758053964, 6037.758489660365, 6049.848753781191, 5726.685032323262, 5726.713664316684, 5827.184194212899, 5895.351511333664, 5925.970370148121, 5923.229980102731, 6000.579959402975, 6006.429389208019, 6009.9699501503965, 6043.697815109315, 5843.29880414353, 5852.824415267549, 5858.531317494505, 5860.501857342174, 5867.765289388377], "value": 5608.133703378123, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.71903733908994, 508.20465970755765, 528.2393353570857, 540.410952062252, 548.7859734703467, 30180.698193018063, 34378.828105859495, 40977.79511102444, 41571.58428415719, 41685.37471937924, 43368.13263734723, 43578.78210608943, 43605.345919811225, 43607.69176924697, 43609.56390436095, 8820.603072861704, 9486.47824369661, 9717.125458708715, 9727.56225969833, 9739.853902191473, 8794.86807697885, 11656.883431165706, 11826.58606948757, 11850.229735067236, 12084.214526055823, 11818.822717659243, 11884.881151188463, 11895.226810257349, 11900.880991190108, 11908.940455297712, 8710.99823520297, 8911.376203665746, 8920.464772113693, 11588.304701717881, 11838.230515016583, 8837.513936733478, 11479.655610331703, 11740.354280514568, 11767.764644707058, 12015.0, 40266.798666006776, 40556.39165412521, 40567.7971610143, 40624.39063414051, 40636.0, 3455.861765529401, 3476.9304613907834, 3870.6903447724117, 4151.735945214208, 4243.893902652459, 31198.37603247945, 32291.031269061965, 32466.026019219462, 32503.862364817356, 32609.0, 3266.2324353776844, 3412.1128506588216, 3426.5031570422148, 3432.3650124726755, 3466.254755227613, 522802.0, 525377.0, 526108.0, 526292.0, 528160.0, 61447.0, 61627.38372616217, 61966.0705089424, 62030.68984655138, 62200.066998995055, 65465.672671636334, 65534.0, 65553.0, 65553.01670474946, 65607.67196163989, 16157.434489793035, 16330.67338653244, 16360.918195408947, 16372.0, 16397.59006024859, 11712.590059347882, 11927.343996080126, 12752.936235318764, 12923.612291631267, 12999.350032498338, 17525.386611468537, 17558.29766809313, 17588.648227035388, 17589.944603323842, 17656.82343176552, 6309.747610095545, 6325.841853953596, 6331.651759153198, 6401.615903045832, 6456.451201647818, 865.3855762408634, 1002.5187909803335, 1034.5603118674533, 1078.0997866781324, 1155.358775879395, 26683.399817727914, 29637.407251855275, 30285.242868928013, 30466.695333046828, 30955.226119346746, 1079.8704155501391, 1086.2613422872364, 1128.2948157401624, 1139.0830381542771, 1149.689583812366, 15727.44953926607, 15765.68468630621, 15906.60233494172, 16087.678246435005, 16469.88699863405, 573246.0, 732069.0, 743738.0, 744153.0, 976189.0, 59652.0, 60048.69975650181, 60567.0, 60602.39397606056, 60789.480262992896, 1243.2726854790048, 1278.8081787731915, 1376.215557132445, 1393.9442422303198, 1409.105218186459, 27585.448291034467, 30580.0, 30930.845345773127, 30979.225519362197, 32559.0, 57494.0, 57685.84628307494, 57980.13029804472, 58135.70932145312, 58188.70905645445, 622749.0, 625437.0, 629106.0, 630536.0, 630887.0, 22138.003789829818, 23302.01793832901, 23800.88099559491, 26446.86776566105, 26649.600255996564, 34322.97031089071, 45216.77391613021, 46017.0, 46562.53437465582, 46632.7668361656, 32407.675923241408, 35817.283654326246, 38766.0, 38811.6118838808, 39336.803315983234, 32502.0, 37438.0, 37527.24945501129, 37905.62094379021, 38536.80731596324, 30139.0, 36672.26655466929, 37242.44136337903, 37441.0, 37573.0, 32517.0, 32951.17622059468, 33793.66206337905, 33810.0, 33965.811196608, 24707.505849882542, 24737.628935565623, 24918.75081249237, 25264.873675631505, 25425.0], "value": 20485.650797382244, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.8852013275221, 353.4627366403069, 363.6745113123755, 377.1852797956413, 405.76262886211566, 15207.695846083072, 15418.460353887616, 19333.516662083453, 20672.862992535403, 20702.896485517587, 17341.393051243176, 19854.801451985477, 20835.791642083575, 21263.36209913701, 21267.255646052392, 5322.387925388572, 5504.944950550504, 5517.117261238202, 5517.69652669103, 5538.833834984957, 5539.196816461611, 5903.0, 5921.289445266552, 5953.791617293396, 5959.284885813709, 5830.44610761979, 5836.824895253128, 5928.02187639039, 5987.790427335044, 5993.0710739835395, 5865.853353666171, 5915.349311575723, 5921.555883308769, 5947.316058653267, 5955.8213253602025, 5716.3426205986425, 5804.651720896717, 5871.6183448075735, 5882.55880808941, 5882.735276912506, 14301.713965720628, 24988.125415610368, 25982.700864956867, 25985.0, 26066.087686930867, 1920.4910698664864, 2177.205320058183, 2481.9751802481924, 2563.9487210255875, 2903.3757742085554, 8479.728040793869, 9887.752806179904, 9890.752731181778, 9963.15313198379, 10025.899741002642, 1764.938227162056, 1812.8549716022687, 1818.5180927054341, 1825.954351141219, 1907.4468404162776, 115624.0, 120025.39987300007, 126461.99999999908, 127897.72102279044, 129019.7098029017, 11267.380294083903, 11441.485133169028, 11467.885321146849, 11524.366159861289, 11622.825657615143, 14120.152790832582, 14255.0, 14292.42830286777, 14304.141751494944, 14323.427062917575, 8551.444156129955, 8559.614817333235, 8605.182507661762, 8605.440646358093, 8695.826083478294, 8603.354748393771, 8615.0, 8616.569171541523, 8722.825543489094, 8742.562871856382, 4527.230370836965, 4553.954460455419, 4572.74849883253, 4573.382593349856, 4615.838445654386, 1837.6967800313066, 1890.432870138953, 1911.665458544749, 1934.5067007912892, 1937.273522429089, 720.8051483233567, 785.6660919109356, 799.2007992007992, 891.2558014058334, 902.1700035966926, 17640.735388969177, 18695.065246737882, 21428.0, 22104.0, 22107.447313816963, 939.4992319966498, 944.6693657219918, 948.9478078705738, 950.4107453378949, 1051.6476980211562, 11393.772124557629, 11400.543978240943, 11572.826407603892, 11587.362695051854, 11593.014593759457, 619026.0, 621122.0, 629614.0, 633553.0, 634458.0, 129499.0, 133651.0, 140071.0, 142784.57215427922, 145298.0, 817.9304759095544, 868.2533021601417, 1002.964896228643, 1003.5734812704571, 1011.9829571280962, 17246.741298880275, 19092.522686932938, 19188.944608046135, 19554.60890782148, 19652.213911443952, 38900.63847765315, 50870.7282317945, 54107.45892541024, 58463.12305315339, 58742.41257587369, 79032.02419939317, 85406.0, 86375.0, 87526.0, 90013.54993225254, 6955.478339124687, 7159.890217016371, 7700.152983171734, 8259.628316725642, 8265.586720663823, 12251.264924104582, 13039.152455090389, 13144.737105257651, 13189.142705724482, 13239.867601323864, 11218.102551795673, 11335.0, 11410.885891141314, 11439.256448330832, 11513.13651476126, 8889.644414223618, 10644.77585077726, 11055.613053543088, 11340.546378144782, 11507.482163302839, 11431.485583148944, 11622.6513204604, 11663.708407289885, 11784.705382365166, 11786.882131178578, 7975.4018448615425, 8099.473534220257, 8186.877196841933, 8361.581920903809, 8378.329733621418, 10513.789724205626, 11309.943450283025, 11371.601993930173, 11456.19806613529, 11506.712332191762], "value": 9552.781610330814, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.5980785187999, 1547.7987861577997, 1720.4580557124505, 1849.7410362549253, 1892.082340065069, 7675.232476752322, 8441.831163376737, 8674.653013879437, 8888.555572221387, 8902.643894244238, 6782.253952065269, 7985.04179498461, 8376.120507346732, 8584.356173287013, 8640.91359086409, 2338.812894968403, 2363.161077817376, 2365.4441206316587, 2365.7752513511273, 2367.3844800351862, 2332.790048895599, 2333.183385814968, 2380.3573035280465, 2381.071382160959, 2383.2969274064194, 2091.5712278982846, 2154.052217024512, 2163.0590693048493, 2165.32874808809, 2166.8483206175574, 2290.862548247102, 2348.8120950323932, 2356.0, 2357.481354102112, 2359.28041947206, 2330.4290448839974, 2348.729896061958, 2361.893714782822, 2367.2306500387444, 2373.0389192377115, 7839.529628222325, 8029.879551806727, 8043.39674524413, 8800.427972181851, 8881.511516866569, 4794.137055330039, 5163.845084647466, 5304.893902121975, 5329.840104796824, 5354.491323324278, 3587.802670853102, 3616.4032934565766, 3665.450182472624, 3674.3202507535925, 3680.7301480989017, 1080.1197024425069, 1091.841682955967, 1097.5993762276792, 1101.2456467319832, 1114.4650567727535, 11569.42152892352, 11675.0, 11682.532698692126, 11693.298402095987, 11749.4712737927, 4555.772211389483, 4584.266517357207, 4783.880402989884, 4786.401699787527, 4791.712497250106, 10018.398896066114, 10022.398656080513, 10027.749306267402, 10033.197344212445, 10038.548265328078, 5356.946430535722, 5363.329583802025, 5365.946340536622, 5373.704446255493, 5376.924615077, 3577.58857731359, 4888.95111048885, 4907.0, 4907.435644900811, 4912.042151780369, 3448.77582957112, 3618.5476815398074, 3698.6671199592165, 3757.248550289953, 3793.260314238752, 1151.0791366906442, 1152.2798251093068, 1161.5760247509597, 1168.824676298545, 1170.0814860334597, 770.4106358635648, 781.8475397297473, 902.2466240689098, 917.6971599372191, 938.1416004355938, 5844.1818145459665, 5943.494802941712, 5994.0, 6109.725062372204, 6153.876922461525, 813.4184058398156, 891.9598618062073, 903.9819203616022, 936.6487567162314, 941.1811723800338, 3498.8075655839175, 3858.3247931611854, 3976.886471787904, 4023.597640235953, 4037.1925614876554, 10403.375797452025, 10664.146868250522, 10721.98141176587, 10786.838197427045, 10796.78406431867, 10571.62999295021, 10597.31117477377, 10663.946680266705, 10695.73260668474, 10768.515416806262, 7861.52830830152, 8055.959720201478, 8215.383846211558, 8236.217559331853, 8237.629306681214, 5904.0, 5935.228420305332, 6021.277446706424, 6026.427489388501, 6052.66710330936, 10018.098371146632, 10146.18830493544, 10156.984301569784, 10164.288499804943, 10171.694849154537, 9762.951185244028, 9989.0, 10254.743631409274, 10407.47962601882, 10453.320534165256, 7549.849003019799, 7618.276263754934, 7714.1128770192145, 7716.884246736191, 7717.305442510201, 8153.714619988272, 8273.296769774395, 8341.958290208753, 8347.0, 8363.080061193386, 7474.439417043634, 7507.774766757006, 7543.509671871311, 7597.0, 7715.614219289126, 7161.0, 7304.780856574312, 7388.704451821867, 7423.777286681408, 7550.187954890677, 6933.514653974174, 7005.824854378681, 7081.610511421921, 7090.113735783028, 7092.25531319204, 5000.049990501793, 5032.823851165192, 5045.646804723634, 5122.79508819658, 5129.820456284012, 5101.9489805101475, 5139.1263485206855, 5148.6910785353, 5221.112410890234, 5255.185446255837], "value": 4559.017648464341, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.78343408103112, 283.67660866612056, 295.8964362473136, 308.4201700802492, 312.7873046328497, 4615.869112067544, 5181.5854731621475, 5190.740462976845, 5190.8702282443, 5216.5826733861295, 4471.575200355961, 4491.663125265602, 4982.327385802915, 5249.527542521172, 5352.652077614962, 3325.7173140283103, 4106.527749308823, 4108.986596567675, 4110.7739074350975, 4130.380442933554, 3895.4935858338376, 4119.897002574945, 4131.462909821719, 4148.771817550033, 4149.5228048774325, 3380.983095084521, 3511.736619753516, 4110.712250142491, 4113.341865301552, 4129.501651500829, 3753.924921501582, 3904.4338570907344, 4077.8368865245357, 4078.3066878630702, 4135.524414692319, 3130.655627880931, 3589.8205089745406, 3594.11944073701, 3602.6757591816863, 3610.8806270056366, 4249.575042495757, 5029.893423446836, 5128.5126713543905, 5170.508801663836, 5257.894842103175, 3746.044758586543, 4326.913461730748, 4669.575779387285, 4716.2454007359, 4729.787159577827, 4622.468416132154, 4676.602488788457, 5612.747426365782, 5620.0, 5671.886562268732, 3422.4695172248094, 3811.5706610021243, 3914.706397020235, 3927.6857851371824, 3954.2486927483687, 4867.756612169413, 5435.755391007414, 5634.436556344414, 5750.338711048241, 5774.498630356095, 3827.8086095695103, 3964.5440774310746, 3978.124812541236, 4014.715291106818, 4031.294523458383, 3813.828377723009, 3896.4350169225872, 4046.9595304046584, 4057.6753859691153, 4064.5732198119363, 3876.6317199865966, 3979.313963074913, 4646.002219045843, 4667.6965997210755, 4673.813047478131, 5605.80379686709, 5640.915386269209, 5651.0675738503505, 5661.7735290588735, 5671.744771485293, 1211.5275042733342, 1249.9437525311382, 1330.1885849631717, 1334.8197993270785, 1388.861113888603, 713.5896859305919, 778.350077685128, 835.3734698975768, 871.4596949891109, 873.0527377795163, 4100.897477563085, 4102.220578090153, 4107.898536604845, 4142.337226043879, 4272.654113954094, 3765.456162973168, 3909.706771992112, 4075.918481630351, 4311.928437362728, 4657.673962822638, 3880.671597041752, 4248.852809741353, 4367.410399596078, 4384.320430333303, 4419.6466480516665, 2293.747687754379, 2389.557931782626, 2468.8888999995042, 2502.435977514054, 2572.9099481518056, 4812.542808433193, 4869.001854619794, 4908.410990681141, 4912.066707325596, 4965.577925876341, 3992.6606238470054, 4427.889302767457, 4553.157665831832, 4640.97679511607, 4669.342383453893, 4175.457969356991, 4563.452385713736, 4741.723972493222, 4802.759862006886, 4957.950420495749, 3891.377379619247, 4012.2376748417705, 4225.8309667614185, 4753.524647535219, 4790.275500819772, 4553.430821147356, 4657.208274593259, 5338.9834743369875, 5372.677639341652, 5403.216938410394, 5226.059309324206, 5448.0, 5939.049752039653, 5960.165576819336, 5973.611715238496, 3951.38753493209, 3953.169834334822, 3960.9603903961743, 3972.940405893856, 3980.601939805996, 5046.543117964206, 5356.384015838073, 5428.864278393072, 5666.600039975981, 5744.368119506935, 5483.479069488392, 5562.610617256753, 5576.773109915812, 5580.804671836466, 5588.050031494737, 4733.455652599858, 4746.715197088186, 5201.739913004259, 5539.19681646151, 5660.773569057192, 4812.422509298766, 5511.834644960497, 5519.972400137974, 5522.530584900329, 5537.723113844372, 5076.543111120017, 5194.922076168936, 5573.108302671553, 5587.608867379245, 5590.8043218487155, 4767.6662633615315, 4826.541478559531, 5291.862249616351, 5517.020808763223, 5528.336599608073], "value": 3863.044060255346, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.3990707666175, 753.3521171792257, 782.1240210963718, 811.6956141446957, 841.5329492131866, 15435.845641543596, 16016.679666406664, 16172.595685107879, 16239.675206495878, 16251.349946002176, 10321.638742644009, 12789.552365667203, 12801.743965120691, 12951.74096518069, 12979.415926283316, 688.3495097133211, 844.940854140212, 853.9487630742144, 863.6761214544546, 865.9134086591322, 695.6765104226533, 707.1796715809662, 848.7750746052301, 865.9913400865973, 871.7428358634187, 907.1110311894347, 910.7313342563926, 912.6303846942, 912.6851236323457, 912.8174365126968, 725.2855936902147, 877.4515927545284, 903.6837107012559, 904.9140331668481, 905.8097799462088, 665.9700313485905, 668.7993601919453, 669.7890164598185, 670.2191946382459, 670.3363669966747, 15221.467248646355, 15270.236488175547, 15279.541613751606, 15281.465148719853, 15293.158876261801, 14225.0, 14290.57128286153, 14308.356123974447, 14367.066140700821, 14618.26908654563, 13083.542076027294, 13209.33953302331, 13268.86731132686, 13470.595882123454, 13516.594502164951, 4569.657275704336, 4674.298855171752, 4691.249400096002, 4695.81216751333, 4806.591439727627, 118377.81622183754, 119613.0, 119858.80141198564, 120071.39964300209, 120378.79621203763, 11342.319460832212, 11452.255603385753, 11728.94135529317, 11811.350375729417, 11845.0, 18666.533336666693, 20381.592368152553, 23017.964191611416, 23020.4244893879, 23036.308910732703, 8122.796930076677, 8307.501549907025, 8361.83276334482, 8421.74734757946, 8423.368247381472, 4235.195312890541, 4420.138073075784, 4499.640028797754, 4517.322401639781, 4574.176648203355, 3377.4427219508507, 3542.6634469725336, 3547.343741407848, 3547.8048707320827, 3553.520274762874, 617.2685367839125, 636.5353292096795, 637.2066776862836, 650.1353200243665, 651.5732195412013, 564.4327450911808, 579.8231539380445, 637.7576520922113, 638.9648569328639, 650.5933791380387, 8845.805816214857, 8857.0, 8907.910920890708, 9024.291558645938, 9074.092590740873, 568.4116938968148, 580.5007693383686, 595.59499540313, 605.5065121925704, 641.3490307338055, 4327.0, 4336.82652693895, 4377.802998865059, 4404.757738324359, 4485.551444855553, 104424.95575044304, 138354.0, 148160.0, 154820.0, 157290.0, 124066.0, 124390.0, 126006.0, 126625.0, 126740.84547857317, 108163.91836081538, 111381.2154696139, 112131.4393428044, 112208.0, 113740.0, 8519.701810436605, 8521.659133634585, 8628.57512553688, 8667.826643467222, 8710.0, 57316.0, 58546.4145358558, 58751.0, 62208.066878995945, 62334.37665623286, 83279.16720832957, 91140.54429727809, 91608.54195728978, 92199.6170057463, 93676.53161734148, 10937.781244375226, 11090.667279981613, 11136.276142050741, 11293.209475336647, 11411.486483108447, 12088.093392995384, 12127.575534856238, 12163.81754273669, 12321.753564928831, 12748.681282968, 11804.291742495478, 11825.645230643095, 11860.822087668865, 11939.880601193878, 12226.816597750863, 9994.800103998024, 11224.316352546857, 11535.36555489456, 11902.761944760883, 12283.815742763687, 11313.660590182308, 11585.304881707125, 11869.881301186877, 11905.52377904874, 11962.641120766391, 9726.805463890541, 9781.75545611337, 9831.803363932824, 10050.447225402673, 10134.797304054026, 9557.474338911426, 9816.411015339103, 9823.950880245553, 9934.205263578751, 9949.0], "value": 7604.159194466925, "unit": "score"}], "name": "dromaeo_css", "value": 7264.683821576062, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 115.0, 113.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 105.0, 153.0, 107.0, 107.0, 130.0, 105.0, 140.0, 106.0], "value": 120.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 185.0, 185.0, 186.0, 184.0, 182.0, 182.0, 185.0, 182.0, 182.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 96.0, 58.0, 107.0, 59.0, 57.0, 59.0, 68.0, 82.0, 56.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 93.0, 69.0, 78.0, 69.0, 96.0, 72.0, 69.0, 101.0], "value": 82.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 91.0, 87.0, 89.0, 87.0, 90.0, 87.0, 89.0, 87.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 103.0, 147.0, 147.0, 95.0, 95.0], "value": 106.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 84.0, 84.0, 84.0, 83.0, 80.0, 84.0, 85.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 54.0, 54.0, 53.0, 53.0, 53.0, 54.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 72.0, 75.0, 70.0, 68.0, 72.0, 70.0, 70.0, 72.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 84.0, 81.0, 82.0, 81.0, 80.0, 82.0, 78.0, 77.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 144.0, 140.0, 150.0, 150.0, 132.0, 144.0, 144.0, 220.0, 137.0], "value": 150.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 164.0, 163.0, 218.0, 211.0, 162.0, 164.0, 164.0, 156.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 65.0, 67.0, 67.0, 67.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1445.3, "alertThreshold": 2.0}]} 13:57:41 INFO - Return code: 0 13:57:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:57:41 ERROR - # TBPL SUCCESS # 13:57:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:57:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:57:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:57:41 INFO - 2016-06-29 13:57:41.821 screenresolution[1664:9963] starting screenresolution argv=screenresolution get 13:57:41 INFO - 2016-06-29 13:57:41.824 screenresolution[1664:9963] Display 0: 1600x1200x32@60 13:57:41 INFO - 2016-06-29 13:57:41.830 screenresolution[1665:9966] starting screenresolution argv=screenresolution list 13:57:41 INFO - Available Modes on Display 0 13:57:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:57:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:57:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:57:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:57:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:57:42 INFO - Intel Iris: 13:57:42 INFO - Chipset Model: Intel Iris 13:57:42 INFO - Type: GPU 13:57:42 INFO - Bus: Built-In 13:57:42 INFO - VRAM (Dynamic, Max): 1536 MB 13:57:42 INFO - Vendor: Intel (0x8086) 13:57:42 INFO - Device ID: 0x0a2e 13:57:42 INFO - Revision ID: 0x0009 13:57:42 INFO - Displays: 13:57:42 INFO - Display: 13:57:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:57:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:57:42 INFO - Display Serial Number: 1600x1200 60 13:57:42 INFO - Main Display: Yes 13:57:42 INFO - Mirror: Off 13:57:42 INFO - Online: Yes 13:57:42 INFO - Rotation: Supported 13:57:42 INFO - Return code: 0 13:57:42 INFO - Running post-action listener: _resource_record_post_action 13:57:42 INFO - ##### 13:57:42 INFO - ##### Finished run-tests step (success) 13:57:42 INFO - ##### 13:57:42 INFO - Running post-run listener: _resource_record_post_run 13:57:42 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36685312; Write bytes: 708067840; Read time: 238; Write time: 2020 13:57:42 INFO - TinderboxPrint: CPU usage
53.6% 13:57:42 INFO - TinderboxPrint: I/O read bytes / time
36,685,312 / 238 13:57:42 INFO - TinderboxPrint: I/O write bytes / time
708,067,840 / 2,020 13:57:42 INFO - TinderboxPrint: CPU idle
2,251.7 (46.4%) 13:57:42 INFO - TinderboxPrint: CPU system
967.6 (20.0%) 13:57:42 INFO - TinderboxPrint: CPU user
1,630.3 (33.6%) 13:57:42 INFO - TinderboxPrint: Swap in / out
443,437,056 / 0 13:57:42 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 406895104; Write bytes: 403412992; Read time: 30753; Write time: 884 13:57:42 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 34477568; Write bytes: 293569536; Read time: 217; Write time: 1096 13:57:42 INFO - Running post-run listener: _upload_blobber_files 13:57:42 INFO - Blob upload gear active. 13:57:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:57:42 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:57:42 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:57:42 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:57:42 INFO - (blobuploader) - INFO - Open directory for files ... 13:57:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:57:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:57:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:57:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:57:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:57:44 INFO - (blobuploader) - INFO - Done attempting. 13:57:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:57:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:57:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:57:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:57:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:57:45 INFO - (blobuploader) - INFO - Done attempting. 13:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:57:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:57:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:57:47 INFO - (blobuploader) - INFO - Done attempting. 13:57:47 INFO - (blobuploader) - INFO - Iteration through files over. 13:57:47 INFO - Return code: 0 13:57:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:57:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:57:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7abda1233fb24523db44d55bdec22aba3203e60232fc42ec6ab0ba753efd07597414a8b48914556202484b43b9a13c03bc591fc7610ac22c446b2821c67b1579", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9f073299c3c90e8f21d519b013db4d61c263d9292f63b2d8300f4fcf672df72d42b52a984635363421bfdd3a1e6278f6e9f6391d2fcad6278ce2b47e26b5139c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2282651cfa64132d387801748fa07121d6f49a8a20f6aa08452b29f3a0114972e91a08597249f940d9977ddc0e8740324360b784e57894f7223867337be82740"} 13:57:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:57:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:57:47 INFO - Contents: 13:57:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7abda1233fb24523db44d55bdec22aba3203e60232fc42ec6ab0ba753efd07597414a8b48914556202484b43b9a13c03bc591fc7610ac22c446b2821c67b1579", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9f073299c3c90e8f21d519b013db4d61c263d9292f63b2d8300f4fcf672df72d42b52a984635363421bfdd3a1e6278f6e9f6391d2fcad6278ce2b47e26b5139c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2282651cfa64132d387801748fa07121d6f49a8a20f6aa08452b29f3a0114972e91a08597249f940d9977ddc0e8740324360b784e57894f7223867337be82740"} 13:57:47 INFO - Running post-run listener: copy_logs_to_upload_dir 13:57:47 INFO - Copying logs to upload dir... 13:57:47 INFO - mkdir: /builds/slave/test/build/upload/logs 13:57:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.597773 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-06-29 13:57:47.844704) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 13:57:47.853649) ========= 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.dBNAyFE8ex/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.ErqkudnvtV/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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/7abda1233fb24523db44d55bdec22aba3203e60232fc42ec6ab0ba753efd07597414a8b48914556202484b43b9a13c03bc591fc7610ac22c446b2821c67b1579", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9f073299c3c90e8f21d519b013db4d61c263d9292f63b2d8300f4fcf672df72d42b52a984635363421bfdd3a1e6278f6e9f6391d2fcad6278ce2b47e26b5139c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2282651cfa64132d387801748fa07121d6f49a8a20f6aa08452b29f3a0114972e91a08597249f940d9977ddc0e8740324360b784e57894f7223867337be82740"} build_url:https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010862 build_url: 'https://queue.taskcluster.net/v1/task/Yg2cWUtsQWqUkktEsA76cw/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/7abda1233fb24523db44d55bdec22aba3203e60232fc42ec6ab0ba753efd07597414a8b48914556202484b43b9a13c03bc591fc7610ac22c446b2821c67b1579", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9f073299c3c90e8f21d519b013db4d61c263d9292f63b2d8300f4fcf672df72d42b52a984635363421bfdd3a1e6278f6e9f6391d2fcad6278ce2b47e26b5139c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2282651cfa64132d387801748fa07121d6f49a8a20f6aa08452b29f3a0114972e91a08597249f940d9977ddc0e8740324360b784e57894f7223867337be82740"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 13:57:47.891032) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 13:57:47.891686) ========= 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.dBNAyFE8ex/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.ErqkudnvtV/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.005039 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 13:57:47.942798) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 13:57:47.943317) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 13:57:47.943867) ========= ========= Total master_lag: 0.19 =========