builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0163 starttime: 1462794819.25 results: success (0) buildid: 20160509024751 builduid: 991189a231734b41ad920f1637f0b2f5 revision: 7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:39.247508) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:39.248023) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:39.248325) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:39.268513) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:39.268914) ========= 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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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-05-09 04:53:39-- 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% 17.3M=0.001s 2016-05-09 04:53:39 (17.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138537 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:39.425387) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:39.425742) ========= 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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.026844 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:39.474807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:39.475187) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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-05-09 04:53:39,552 truncating revision to first 12 chars 2016-05-09 04:53:39,552 Setting DEBUG logging. 2016-05-09 04:53:39,552 attempt 1/10 2016-05-09 04:53:39,552 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7adbdf0fce9a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 04:53:40,146 unpacking tar archive at: mozilla-central-7adbdf0fce9a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.845409 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:40.337473) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:40.337806) ========= script_repo_revision: 7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:40.338184) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:40.338447) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:53:40.351744) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-09 04:53:40.352101) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:53:40 INFO - MultiFileLogger online at 20160509 04:53:40 in /builds/slave/test 04:53:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:53:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:53:40 INFO - {'append_to_log': False, 04:53:40 INFO - 'base_work_dir': '/builds/slave/test', 04:53:40 INFO - 'blob_upload_branch': 'Firefox', 04:53:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:53:40 INFO - 'branch': 'Firefox', 04:53:40 INFO - 'buildbot_json_path': 'buildprops.json', 04:53:40 INFO - 'config_files': ('talos/mac_config.py',), 04:53:40 INFO - 'default_actions': ('clobber', 04:53:40 INFO - 'read-buildbot-config', 04:53:40 INFO - 'download-and-extract', 04:53:40 INFO - 'populate-webroot', 04:53:40 INFO - 'create-virtualenv', 04:53:40 INFO - 'install', 04:53:40 INFO - 'run-tests'), 04:53:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:53:40 INFO - 'download_minidump_stackwalk': True, 04:53:40 INFO - 'download_symbols': 'ondemand', 04:53:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:53:40 INFO - 'tooltool.py': '/tools/tooltool.py', 04:53:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:53:40 INFO - '/tools/misc-python/virtualenv.py')}, 04:53:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:53:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:53:40 INFO - 'installer_path': 'installer.exe', 04:53:40 INFO - 'log_level': 'info', 04:53:40 INFO - 'log_name': 'talos', 04:53:40 INFO - 'log_to_console': True, 04:53:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:53:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:53:40 INFO - 'opt_config_files': (), 04:53:40 INFO - 'pip_index': False, 04:53:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:53:40 INFO - 'cmd': ('bash', 04:53:40 INFO - '-c', 04:53:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:53:40 INFO - 'enabled': True, 04:53:40 INFO - 'halt_on_failure': False, 04:53:40 INFO - 'name': 'check_screen_resolution'},), 04:53:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:53:40 INFO - 'cmd': ('bash', 04:53:40 INFO - '-c', 04:53:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:53:40 INFO - 'enabled': True, 04:53:40 INFO - 'halt_on_failure': False, 04:53:40 INFO - 'name': 'check_screen_resolution'},), 04:53:40 INFO - 'run_cmd_checks_enabled': True, 04:53:40 INFO - 'sps_profile': False, 04:53:40 INFO - 'sps_profile_interval': 0, 04:53:40 INFO - 'suite': 'dromaeojs', 04:53:40 INFO - 'system_bits': '32', 04:53:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:53:40 INFO - 'title': 't-yosemite-r7-0163', 04:53:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:53:40 INFO - 'use_talos_json': True, 04:53:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:53:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:53:40 INFO - 'work_dir': 'build'} 04:53:40 INFO - ##### 04:53:40 INFO - ##### Running clobber step. 04:53:40 INFO - ##### 04:53:40 INFO - Running pre-action listener: _resource_record_pre_action 04:53:40 INFO - Running main action method: clobber 04:53:40 INFO - rmtree: /builds/slave/test/build 04:53:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:53:42 INFO - Running post-action listener: _resource_record_post_action 04:53:42 INFO - ##### 04:53:42 INFO - ##### Finished clobber step (success) 04:53:42 INFO - ##### 04:53:42 INFO - ##### 04:53:42 INFO - ##### Running read-buildbot-config step. 04:53:42 INFO - ##### 04:53:42 INFO - Running pre-action listener: _resource_record_pre_action 04:53:42 INFO - Running main action method: read_buildbot_config 04:53:42 INFO - Using buildbot properties: 04:53:42 INFO - { 04:53:42 INFO - "project": "", 04:53:42 INFO - "product": "firefox", 04:53:42 INFO - "script_repo_revision": "production", 04:53:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:53:42 INFO - "repository": "", 04:53:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:53:42 INFO - "buildid": "20160509024751", 04:53:42 INFO - "pgo_build": "False", 04:53:42 INFO - "basedir": "/builds/slave/test", 04:53:42 INFO - "buildnumber": 239, 04:53:42 INFO - "slavename": "t-yosemite-r7-0163", 04:53:42 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:53:42 INFO - "platform": "yosemite_r7", 04:53:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:53:42 INFO - "branch": "mozilla-central", 04:53:42 INFO - "repo_path": "mozilla-central", 04:53:42 INFO - "revision": "7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce", 04:53:42 INFO - "stage_platform": "macosx64", 04:53:42 INFO - "builduid": "991189a231734b41ad920f1637f0b2f5", 04:53:42 INFO - "slavebuilddir": "test" 04:53:42 INFO - } 04:53:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:53:42 INFO - Running post-action listener: _resource_record_post_action 04:53:42 INFO - ##### 04:53:42 INFO - ##### Finished read-buildbot-config step (success) 04:53:42 INFO - ##### 04:53:42 INFO - ##### 04:53:42 INFO - ##### Running download-and-extract step. 04:53:42 INFO - ##### 04:53:42 INFO - Running pre-action listener: _resource_record_pre_action 04:53:42 INFO - Running main action method: download_and_extract 04:53:42 INFO - mkdir: /builds/slave/test/build/tests 04:53: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')]} 04:53:42 INFO - https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:53:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:53:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:53:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:53:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 04:53:44 INFO - Downloaded 1199 bytes. 04:53:44 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:53:44 INFO - Using the following test package requirements: 04:53:44 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:53:44 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:53:44 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:53:44 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:53:44 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:53:44 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:53:44 INFO - u'jsshell-mac.zip'], 04:53:44 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:53:44 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:53:44 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:53:44 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:53:44 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:53:44 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:53:44 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:53:44 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:53:44 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:53:44 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:53:44 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:53:44 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:53:44 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')]} 04:53:44 INFO - https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:53:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:53:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:53:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:53:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:53:46 INFO - Downloaded 18146557 bytes. 04:53:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:53:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:53:50 INFO - Return code: 0 04:53:50 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:50 INFO - https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:53:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:53:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:53:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:53:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:53:51 INFO - Downloaded 18146557 bytes. 04:53:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:53:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:53:55 INFO - Return code: 0 04:53: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')]} 04:53:55 INFO - https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:53:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:53:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:53:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:53:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:53:56 INFO - Downloaded 10840152 bytes. 04:53:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:53:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:53:56 INFO - Return code: 0 04:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:56 INFO - https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:53:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:53:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:53:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:53:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 04:53:59 INFO - Downloaded 110435607 bytes. 04:53:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:53:59 INFO - mkdir: /builds/slave/test/properties 04:53:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:53:59 INFO - Writing to file /builds/slave/test/properties/build_url 04:53:59 INFO - Contents: 04:53:59 INFO - build_url:https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:54:00 INFO - Running post-action listener: _resource_record_post_action 04:54:00 INFO - Running post-action listener: set_extra_try_arguments 04:54:00 INFO - ##### 04:54:00 INFO - ##### Finished download-and-extract step (success) 04:54:00 INFO - ##### 04:54:00 INFO - ##### 04:54:00 INFO - ##### Running populate-webroot step. 04:54:00 INFO - ##### 04:54:00 INFO - Running pre-action listener: _resource_record_pre_action 04:54:00 INFO - Running main action method: populate_webroot 04:54:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:54:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:54:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:54:00 INFO - '--tppagecycles', 04:54:00 INFO - '7'], 04:54:00 INFO - 'tests': ['tsvgm']}}, 04:54:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:54:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:54:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:54:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:54:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:54:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:54:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:00 INFO - 'talos_options': ['--disable-e10s'], 04:54:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:54:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:54:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:00 INFO - 'talos_options': ['--disable-e10s'], 04:54:00 INFO - 'tests': ['damp', 'tps']}, 04:54:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:00 INFO - 'tests': ['damp', 'tps']}, 04:54:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:54:00 INFO - 'tests': ['dromaeo_dom']}, 04:54:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:54:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:54:00 INFO - 'tests': ['a11yr', 04:54:00 INFO - 'ts_paint', 04:54:00 INFO - 'tpaint', 04:54:00 INFO - 'sessionrestore', 04:54:00 INFO - 'sessionrestore_no_auto_restore', 04:54:00 INFO - 'tabpaint']}, 04:54:00 INFO - 'other-e10s': {'tests': ['a11yr', 04:54:00 INFO - 'ts_paint', 04:54:00 INFO - 'tpaint', 04:54:00 INFO - 'sessionrestore', 04:54:00 INFO - 'sessionrestore_no_auto_restore', 04:54:00 INFO - 'tabpaint']}, 04:54:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:54:00 INFO - 'ts_paint', 04:54:00 INFO - 'tpaint', 04:54:00 INFO - 'sessionrestore', 04:54:00 INFO - 'sessionrestore_no_auto_restore', 04:54:00 INFO - 'tabpaint']}, 04:54:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:54:00 INFO - 'ts_paint', 04:54:00 INFO - 'tpaint', 04:54:00 INFO - 'sessionrestore', 04:54:00 INFO - 'sessionrestore_no_auto_restore', 04:54:00 INFO - 'tabpaint']}, 04:54:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:54:00 INFO - 'tests': ['a11yr', 04:54:00 INFO - 'ts_paint', 04:54:00 INFO - 'tpaint', 04:54:00 INFO - 'sessionrestore', 04:54:00 INFO - 'sessionrestore_no_auto_restore', 04:54:00 INFO - 'tabpaint']}, 04:54:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:54:00 INFO - 'tests': ['a11yr', 04:54:00 INFO - 'ts_paint', 04:54:00 INFO - 'tpaint', 04:54:00 INFO - 'sessionrestore', 04:54:00 INFO - 'sessionrestore_no_auto_restore', 04:54:00 INFO - 'tabpaint']}, 04:54:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:54:00 INFO - 'tests': ['tsvgx', 04:54:00 INFO - 'tsvgr_opacity', 04:54:00 INFO - 'tart', 04:54:00 INFO - 'tscrollx', 04:54:00 INFO - 'cart']}, 04:54:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:54:00 INFO - 'tsvgr_opacity', 04:54:00 INFO - 'tart', 04:54:00 INFO - 'tscrollx', 04:54:00 INFO - 'cart']}, 04:54:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:00 INFO - 'talos_options': ['--disable-e10s'], 04:54:00 INFO - 'tests': ['tp5o']}, 04:54:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:00 INFO - 'tests': ['tp5o']}, 04:54:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:00 INFO - 'talos_options': ['--disable-e10s', 04:54:00 INFO - '--xperf_path', 04:54:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:54:00 INFO - 'tests': ['tp5n']}, 04:54:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:54:00 INFO - 'talos_options': ['--xperf_path', 04:54:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:54:00 INFO - 'tests': ['tp5n']}}, 04:54:00 INFO - 'talos.zip': {'path': '', 04:54:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:54:00 INFO - Running post-action listener: _resource_record_post_action 04:54:00 INFO - ##### 04:54:00 INFO - ##### Finished populate-webroot step (success) 04:54:00 INFO - ##### 04:54:00 INFO - ##### 04:54:00 INFO - ##### Running create-virtualenv step. 04:54:00 INFO - ##### 04:54:00 INFO - Running pre-action listener: _resource_record_pre_action 04:54:00 INFO - Running main action method: create_virtualenv 04:54:00 INFO - Creating virtualenv /builds/slave/test/build/venv 04:54:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:54:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:54:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:54:00 INFO - Using real prefix '/tools/python27' 04:54:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:54:01 INFO - Installing distribute.............................................................................................................................................................................................done. 04:54:04 INFO - Installing pip.................done. 04:54:04 INFO - Return code: 0 04:54:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:54: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')]} 04:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54: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')]} 04:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff379ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107696d30>, '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 0x1074badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768db20>, '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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:54:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ddl8lCrUqv/Render', 04:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:04 INFO - 'HOME': '/Users/cltbld', 04:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:04 INFO - 'LOGNAME': 'cltbld', 04:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:04 INFO - 'MOZ_NO_REMOTE': '1', 04:54:04 INFO - 'NO_EM_RESTART': '1', 04:54:04 INFO - 'PAGER': '/bin/cat', 04:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:04 INFO - 'PWD': '/builds/slave/test', 04:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:04 INFO - 'SHELL': '/bin/bash', 04:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UG8Mz3GfYF/Listeners', 04:54:04 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:04 INFO - 'USER': 'cltbld', 04:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:04 INFO - 'XPC_FLAGS': '0x0', 04:54:04 INFO - 'XPC_SERVICE_NAME': '0', 04:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:54:04 INFO - Cleaning up... 04:54:04 INFO - Return code: 0 04:54:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:54: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')]} 04:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54: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')]} 04:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff379ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107696d30>, '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 0x1074badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768db20>, '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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:54:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ddl8lCrUqv/Render', 04:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:04 INFO - 'HOME': '/Users/cltbld', 04:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:04 INFO - 'LOGNAME': 'cltbld', 04:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:04 INFO - 'MOZ_NO_REMOTE': '1', 04:54:04 INFO - 'NO_EM_RESTART': '1', 04:54:04 INFO - 'PAGER': '/bin/cat', 04:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:04 INFO - 'PWD': '/builds/slave/test', 04:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:04 INFO - 'SHELL': '/bin/bash', 04:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UG8Mz3GfYF/Listeners', 04:54:04 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:04 INFO - 'USER': 'cltbld', 04:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:04 INFO - 'XPC_FLAGS': '0x0', 04:54:04 INFO - 'XPC_SERVICE_NAME': '0', 04:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:05 INFO - Downloading/unpacking psutil>=0.7.1 04:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:54:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:54:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:54:07 INFO - Installing collected packages: psutil 04:54:07 INFO - Running setup.py install for psutil 04:54:08 INFO - building 'psutil._psutil_osx' extension 04:54:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:54:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:54:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:54:08 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 04:54:08 INFO - building 'psutil._psutil_posix' extension 04:54:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:54:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:54:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:54:08 INFO - ^ 04:54:09 INFO - 1 warning generated. 04:54:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:54:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:54:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:54:09 INFO - Successfully installed psutil 04:54:09 INFO - Cleaning up... 04:54:09 INFO - Return code: 0 04:54:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:54:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff379ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107696d30>, '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 0x1074badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768db20>, '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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:54:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:54:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:54:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ddl8lCrUqv/Render', 04:54:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:09 INFO - 'HOME': '/Users/cltbld', 04:54:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:09 INFO - 'LOGNAME': 'cltbld', 04:54:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:09 INFO - 'MOZ_NO_REMOTE': '1', 04:54:09 INFO - 'NO_EM_RESTART': '1', 04:54:09 INFO - 'PAGER': '/bin/cat', 04:54:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:09 INFO - 'PWD': '/builds/slave/test', 04:54:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:09 INFO - 'SHELL': '/bin/bash', 04:54:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UG8Mz3GfYF/Listeners', 04:54:09 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:54:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:09 INFO - 'USER': 'cltbld', 04:54:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:09 INFO - 'XPC_FLAGS': '0x0', 04:54:09 INFO - 'XPC_SERVICE_NAME': '0', 04:54:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:54:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:54:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:54:11 INFO - Installing collected packages: mozsystemmonitor 04:54:11 INFO - Running setup.py install for mozsystemmonitor 04:54:12 INFO - Successfully installed mozsystemmonitor 04:54:12 INFO - Cleaning up... 04:54:12 INFO - Return code: 0 04:54:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:54:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1077f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff379ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107696d30>, '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 0x1074badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768db20>, '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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:54:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:54:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ddl8lCrUqv/Render', 04:54:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:12 INFO - 'HOME': '/Users/cltbld', 04:54:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:12 INFO - 'LOGNAME': 'cltbld', 04:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:12 INFO - 'MOZ_NO_REMOTE': '1', 04:54:12 INFO - 'NO_EM_RESTART': '1', 04:54:12 INFO - 'PAGER': '/bin/cat', 04:54:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:12 INFO - 'PWD': '/builds/slave/test', 04:54:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:12 INFO - 'SHELL': '/bin/bash', 04:54:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UG8Mz3GfYF/Listeners', 04:54:12 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:54:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:12 INFO - 'USER': 'cltbld', 04:54:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:12 INFO - 'XPC_FLAGS': '0x0', 04:54:12 INFO - 'XPC_SERVICE_NAME': '0', 04:54:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:12 INFO - Downloading/unpacking blobuploader==1.2.4 04:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:14 INFO - Downloading blobuploader-1.2.4.tar.gz 04:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:54:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:54: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 04:54: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 04:54: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 04:54: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 04:54: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 04:54: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 04:54:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:54:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:16 INFO - Downloading docopt-0.6.1.tar.gz 04:54:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:54:16 INFO - Installing collected packages: blobuploader, requests, docopt 04:54:16 INFO - Running setup.py install for blobuploader 04:54:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:54:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:54:16 INFO - Running setup.py install for requests 04:54:16 INFO - Running setup.py install for docopt 04:54:16 INFO - Successfully installed blobuploader requests docopt 04:54:16 INFO - Cleaning up... 04:54:17 INFO - Return code: 0 04:54:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54:17 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')]} 04:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:17 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')]} 04:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:17 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 0x1077f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff379ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107696d30>, '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 0x1074badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768db20>, '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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:54:17 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 04:54:17 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 04:54:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ddl8lCrUqv/Render', 04:54:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:17 INFO - 'HOME': '/Users/cltbld', 04:54:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:17 INFO - 'LOGNAME': 'cltbld', 04:54:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:17 INFO - 'MOZ_NO_REMOTE': '1', 04:54:17 INFO - 'NO_EM_RESTART': '1', 04:54:17 INFO - 'PAGER': '/bin/cat', 04:54:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:17 INFO - 'PWD': '/builds/slave/test', 04:54:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:17 INFO - 'SHELL': '/bin/bash', 04:54:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UG8Mz3GfYF/Listeners', 04:54:17 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:54:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:17 INFO - 'USER': 'cltbld', 04:54:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:17 INFO - 'XPC_FLAGS': '0x0', 04:54:17 INFO - 'XPC_SERVICE_NAME': '0', 04:54:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:54:17 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-rH2Fye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:54:17 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-9RNhBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:54:17 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-NfxPX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:54:17 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-iChcb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:54:17 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-8GOc4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:54:17 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-n09V_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:54:17 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-iUFk1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:54:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-PngcvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:54:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-d3DDLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:54:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-8hix0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:54:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-uQgcLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:54:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-zqEoka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:54:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Jwhmn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:54:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-5ymqlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-509KF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:54:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-GxiIdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:54:18 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-2baiqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:54:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:54:19 INFO - Running setup.py install for manifestparser 04:54:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:54:19 INFO - Running setup.py install for mozcrash 04:54:19 INFO - Running setup.py install for mozdebug 04:54:19 INFO - Running setup.py install for mozdevice 04:54:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:54:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:54:19 INFO - Running setup.py install for mozfile 04:54:19 INFO - Running setup.py install for mozhttpd 04:54:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:54:19 INFO - Running setup.py install for mozinfo 04:54:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:54:20 INFO - Running setup.py install for mozInstall 04:54:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:54:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:54:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:54:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:54:20 INFO - Running setup.py install for mozleak 04:54:20 INFO - Running setup.py install for mozlog 04:54:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:54:20 INFO - Running setup.py install for moznetwork 04:54:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:54:20 INFO - Running setup.py install for mozprocess 04:54:20 INFO - Running setup.py install for mozprofile 04:54:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:54:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:54:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:54:21 INFO - Running setup.py install for mozrunner 04:54:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:54:21 INFO - Running setup.py install for mozscreenshot 04:54:21 INFO - Running setup.py install for moztest 04:54:21 INFO - Running setup.py install for mozversion 04:54:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:54:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:54:21 INFO - Cleaning up... 04:54:21 INFO - Return code: 0 04:54:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:21 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 0x1077f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff379ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107696d30>, '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 0x1074badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768db20>, '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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:54:21 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 04:54:21 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 04:54:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ddl8lCrUqv/Render', 04:54:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:21 INFO - 'HOME': '/Users/cltbld', 04:54:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:21 INFO - 'LOGNAME': 'cltbld', 04:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:21 INFO - 'MOZ_NO_REMOTE': '1', 04:54:21 INFO - 'NO_EM_RESTART': '1', 04:54:21 INFO - 'PAGER': '/bin/cat', 04:54:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:21 INFO - 'PWD': '/builds/slave/test', 04:54:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:21 INFO - 'SHELL': '/bin/bash', 04:54:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UG8Mz3GfYF/Listeners', 04:54:21 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:54:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:21 INFO - 'USER': 'cltbld', 04:54:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:21 INFO - 'XPC_FLAGS': '0x0', 04:54:21 INFO - 'XPC_SERVICE_NAME': '0', 04:54:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:54:21 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-yDPAhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:54:22 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)) 04:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:54:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-TwgXU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:54:22 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)) 04:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:54:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-O0EB1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:54:22 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)) 04:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:54:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Mevfsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:54:22 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)) 04:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:54:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-F4mOuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:54:22 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)) 04:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:54:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-f3GdvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:54:22 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)) 04:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:54:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-TMmAqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:54:22 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)) 04:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:54:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-GZsXCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:54:22 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)) 04:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:54:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-y9hx9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:54:22 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)) 04:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:54:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-y3uN2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 04:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:54:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-jmodom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:54:23 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)) 04:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:54:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-S3fbLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:54:23 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)) 04:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:54:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-fXAy6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:54:23 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)) 04:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:54:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-tGOnv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:54:23 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)) 04:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-1LH06J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:23 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)) 04:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:54:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-_d6ZBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:54:23 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)) 04:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:54:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-YAyld5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:54:23 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)) 04:54:23 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)) 04:54:23 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)) 04:54:23 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)) 04:54:23 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)) 04:54:23 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)) 04:54:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:26 INFO - Downloading blessings-1.6.tar.gz 04:54:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:54:26 INFO - Installing collected packages: blessings 04:54:26 INFO - Running setup.py install for blessings 04:54:26 INFO - Successfully installed blessings 04:54:26 INFO - Cleaning up... 04:54:26 INFO - Return code: 0 04:54:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:54:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:54:26 INFO - Reading from file tmpfile_stdout 04:54:26 INFO - Current package versions: 04:54:26 INFO - blessings == 1.6 04:54:26 INFO - blobuploader == 1.2.4 04:54:26 INFO - docopt == 0.6.1 04:54:26 INFO - manifestparser == 1.1 04:54:26 INFO - mozInstall == 1.12 04:54:26 INFO - mozcrash == 0.17 04:54:26 INFO - mozdebug == 0.1 04:54:26 INFO - mozdevice == 0.48 04:54:26 INFO - mozfile == 1.2 04:54:26 INFO - mozhttpd == 0.7 04:54:26 INFO - mozinfo == 0.9 04:54:26 INFO - mozleak == 0.1 04:54:26 INFO - mozlog == 3.1 04:54:26 INFO - moznetwork == 0.27 04:54:26 INFO - mozprocess == 0.22 04:54:26 INFO - mozprofile == 0.28 04:54:26 INFO - mozrunner == 6.11 04:54:26 INFO - mozscreenshot == 0.1 04:54:26 INFO - mozsystemmonitor == 0.0 04:54:26 INFO - moztest == 0.7 04:54:26 INFO - mozversion == 1.4 04:54:26 INFO - psutil == 3.1.1 04:54:26 INFO - requests == 1.2.3 04:54:26 INFO - wsgiref == 0.1.2 04:54:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54:26 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')]} 04:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:26 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')]} 04:54:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:26 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 0x1077f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff379ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107696d30>, '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 0x1074badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768db20>, '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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:54:26 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 04:54:26 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 04:54:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ddl8lCrUqv/Render', 04:54:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:26 INFO - 'HOME': '/Users/cltbld', 04:54:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:26 INFO - 'LOGNAME': 'cltbld', 04:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:26 INFO - 'MOZ_NO_REMOTE': '1', 04:54:26 INFO - 'NO_EM_RESTART': '1', 04:54:26 INFO - 'PAGER': '/bin/cat', 04:54:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:26 INFO - 'PWD': '/builds/slave/test', 04:54:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:26 INFO - 'SHELL': '/bin/bash', 04:54:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UG8Mz3GfYF/Listeners', 04:54:26 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:54:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:26 INFO - 'USER': 'cltbld', 04:54:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:26 INFO - 'XPC_FLAGS': '0x0', 04:54:26 INFO - 'XPC_SERVICE_NAME': '0', 04:54:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:27 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)) 04:54:27 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)) 04:54:27 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)) 04:54:27 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)) 04:54:27 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)) 04:54:27 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)) 04:54:27 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)) 04:54:27 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)) 04:54:27 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)) 04:54:27 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)) 04:54:27 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)) 04:54:27 INFO - Cleaning up... 04:54:27 INFO - Return code: 0 04:54:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:54:27 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')]} 04:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:27 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')]} 04:54:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:27 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 0x1077f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff379ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107696d30>, '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 0x1074badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768db20>, '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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:54:27 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 04:54:27 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 04:54:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ddl8lCrUqv/Render', 04:54:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:27 INFO - 'HOME': '/Users/cltbld', 04:54:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:27 INFO - 'LOGNAME': 'cltbld', 04:54:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:27 INFO - 'MOZ_NO_REMOTE': '1', 04:54:27 INFO - 'NO_EM_RESTART': '1', 04:54:27 INFO - 'PAGER': '/bin/cat', 04:54:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:27 INFO - 'PWD': '/builds/slave/test', 04:54:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:27 INFO - 'SHELL': '/bin/bash', 04:54:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UG8Mz3GfYF/Listeners', 04:54:27 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:54:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:27 INFO - 'USER': 'cltbld', 04:54:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:27 INFO - 'XPC_FLAGS': '0x0', 04:54:27 INFO - 'XPC_SERVICE_NAME': '0', 04:54:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:27 INFO - Downloading/unpacking jsonschema 04:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:54:29 INFO - Downloading/unpacking functools32 (from jsonschema) 04:54:29 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 04:54:29 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 04:54:29 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 04:54:29 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 04:54:29 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 04:54:29 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 04:54:30 INFO - Downloading functools32-3.2.3-2.tar.gz 04:54:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:54:30 INFO - warning: no files found matching '*.txt' 04:54:30 INFO - no previously-included directories found matching 'build' 04:54:30 INFO - no previously-included directories found matching 'dist' 04:54:30 INFO - no previously-included directories found matching '.git*' 04:54:30 INFO - Installing collected packages: jsonschema, functools32 04:54:30 INFO - Running setup.py install for functools32 04:54:30 INFO - warning: no files found matching '*.txt' 04:54:30 INFO - no previously-included directories found matching 'build' 04:54:30 INFO - no previously-included directories found matching 'dist' 04:54:30 INFO - no previously-included directories found matching '.git*' 04:54:30 INFO - Successfully installed jsonschema functools32 04:54:30 INFO - Cleaning up... 04:54:30 INFO - Return code: 0 04:54:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff379ca52b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107696d30>, '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 0x1074badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768db20>, '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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:54:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ddl8lCrUqv/Render', 04:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:30 INFO - 'HOME': '/Users/cltbld', 04:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:30 INFO - 'LOGNAME': 'cltbld', 04:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:30 INFO - 'MOZ_NO_REMOTE': '1', 04:54:30 INFO - 'NO_EM_RESTART': '1', 04:54:30 INFO - 'PAGER': '/bin/cat', 04:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:30 INFO - 'PWD': '/builds/slave/test', 04:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:30 INFO - 'SHELL': '/bin/bash', 04:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UG8Mz3GfYF/Listeners', 04:54:30 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:30 INFO - 'USER': 'cltbld', 04:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:30 INFO - 'XPC_FLAGS': '0x0', 04:54:30 INFO - 'XPC_SERVICE_NAME': '0', 04:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:30 INFO - Downloading/unpacking flake8 04:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:54:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:54:33 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 04:54:33 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 04:54:33 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 04:54:33 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 04:54:33 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 04:54:33 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 04:54:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:54:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:54:33 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 04:54:33 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 04:54:33 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 04:54:33 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 04:54:33 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 04:54:33 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 04:54:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:54:34 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 04:54:34 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 04:54:34 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 04:54:34 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 04:54:34 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 04:54:34 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 04:54:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:54:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:54:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:54:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:54:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:54:34 INFO - no previously-included directories found matching 'docs/_build' 04:54:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:54:34 INFO - Running setup.py install for pep8 04:54:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:54:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:54:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:54:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:54:34 INFO - no previously-included directories found matching 'docs/_build' 04:54:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:54:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:54:34 INFO - Cleaning up... 04:54:34 INFO - Return code: 0 04:54:34 INFO - Running post-action listener: _resource_record_post_action 04:54:34 INFO - Running post-action listener: _start_resource_monitoring 04:54:34 INFO - Starting resource monitoring. 04:54:34 INFO - ##### 04:54:34 INFO - ##### Finished create-virtualenv step (success) 04:54:34 INFO - ##### 04:54:34 INFO - ##### 04:54:34 INFO - ##### Running install step. 04:54:34 INFO - ##### 04:54:34 INFO - Running pre-action listener: _resource_record_pre_action 04:54:34 INFO - Running main action method: install 04:54:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:54:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:54:35 INFO - Reading from file tmpfile_stdout 04:54:35 INFO - Detecting whether we're running mozinstall >=1.0... 04:54:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:54:35 INFO - Reading from file tmpfile_stdout 04:54:35 INFO - Output received: 04:54:35 INFO - Usage: mozinstall [options] installer 04:54:35 INFO - Options: 04:54:35 INFO - -h, --help show this help message and exit 04:54:35 INFO - -d DEST, --destination=DEST 04:54:35 INFO - Directory to install application into. [default: 04:54:35 INFO - "/builds/slave/test"] 04:54:35 INFO - --app=APP Application being installed. [default: firefox] 04:54:35 INFO - mkdir: /builds/slave/test/build/application 04:54:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:55:08 INFO - Reading from file tmpfile_stdout 04:55:08 INFO - Output received: 04:55:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:55:08 INFO - Running post-action listener: _resource_record_post_action 04:55:08 INFO - ##### 04:55:08 INFO - ##### Finished install step (success) 04:55:08 INFO - ##### 04:55:08 INFO - ##### 04:55:08 INFO - ##### Running run-tests step. 04:55:08 INFO - ##### 04:55:08 INFO - Running pre-action listener: _resource_record_pre_action 04:55:08 INFO - Running main action method: run_tests 04:55:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:55:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:55:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:55:08 INFO - 2016-05-09 04:55:08.615 screenresolution[1637:7396] starting screenresolution argv=screenresolution get 04:55:08 INFO - 2016-05-09 04:55:08.618 screenresolution[1637:7396] Display 0: 1600x1200x32@60 04:55:08 INFO - 2016-05-09 04:55:08.624 screenresolution[1638:7398] starting screenresolution argv=screenresolution list 04:55:08 INFO - Available Modes on Display 0 04:55:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:55:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:55:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:55:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:55:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:55:08 INFO - Intel Iris: 04:55:08 INFO - Chipset Model: Intel Iris 04:55:08 INFO - Type: GPU 04:55:08 INFO - Bus: Built-In 04:55:08 INFO - VRAM (Dynamic, Max): 1536 MB 04:55:08 INFO - Vendor: Intel (0x8086) 04:55:08 INFO - Device ID: 0x0a2e 04:55:08 INFO - Revision ID: 0x0009 04:55:08 INFO - Displays: 04:55:08 INFO - Display: 04:55:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:55:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:55:08 INFO - Display Serial Number: 1600x1200 60 04:55:08 INFO - Main Display: Yes 04:55:08 INFO - Mirror: Off 04:55:08 INFO - Online: Yes 04:55:08 INFO - Rotation: Supported 04:55:08 INFO - Return code: 0 04:55:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:55:08 INFO - Python 2.7.3 04:55:08 INFO - Return code: 0 04:55:08 INFO - grabbing minidump binary from tooltool 04:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:55:08 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107696d30>, '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 0x1074badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:55:08 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 04:55:08 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 04:55:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:55:08 INFO - Return code: 0 04:55:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:55:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:55:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:55:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:55:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:55:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:55:10 INFO - Return code: 0 04:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 04:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0163 --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 04:55:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ddl8lCrUqv/Render', 04:55:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:10 INFO - 'HOME': '/Users/cltbld', 04:55:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:10 INFO - 'LOGNAME': 'cltbld', 04:55:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:55:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:10 INFO - 'MOZ_NO_REMOTE': '1', 04:55:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:55:10 INFO - 'NO_EM_RESTART': '1', 04:55:10 INFO - 'PAGER': '/bin/cat', 04:55:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:10 INFO - 'PWD': '/builds/slave/test', 04:55:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:55:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:10 INFO - 'SHELL': '/bin/bash', 04:55:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UG8Mz3GfYF/Listeners', 04:55:10 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:55:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:10 INFO - 'USER': 'cltbld', 04:55:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:10 INFO - 'XPC_FLAGS': '0x0', 04:55:10 INFO - 'XPC_SERVICE_NAME': '0', 04:55:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 04:55:10 INFO - mozversion application_buildid: 20160509024751 04:55:10 INFO - mozversion application_changeset: 7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce 04:55:10 INFO - mozversion application_display_name: Nightly 04:55:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:55:10 INFO - mozversion application_name: Firefox 04:55:10 INFO - mozversion application_remotingname: firefox 04:55:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:55:10 INFO - mozversion application_vendor: Mozilla 04:55:10 INFO - mozversion application_version: 49.0a1 04:55:10 INFO - mozversion platform_buildid: 20160509024751 04:55:10 INFO - mozversion platform_changeset: 7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce 04:55:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:55:10 INFO - mozversion platform_version: 49.0a1 04:55:10 INFO - using testdate: 1462794910 04:55:10 INFO - actual date: 1462794910 04:55:10 INFO - starting webserver on 'localhost:49272' 04:55:10 INFO - SUITE-START | Running 2 tests 04:55:10 INFO - TEST-START | dromaeo_css 04:55:10 INFO - operating with platform_type : mac_ 04:55:10 INFO - Initialising browser for dromaeo_css test... 04:55:10 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpk5yDgG/profile http://localhost:49272/getInfo.html) 04:55:12 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 04:55:12 INFO - PROCESS | 1656 | colorDepth:24 04:55:12 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 04:55:12 INFO - PROCESS | 1656 | __metrics 04:55:12 INFO - PROCESS | 1656 | 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" 04:55:12 ERROR - PROCESS | 1656 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:55:13 INFO - TEST-INFO | 1656: exit 0 04:55:13 INFO - Browser initialized. 04:55:13 INFO - Running cycle 1/1 for dromaeo_css test... 04:55:13 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpk5yDgG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:55:25 INFO - PROCESS | 1658 | RSS: Main: 239112192 04:55:25 INFO - PROCESS | 1658 | 04:56:17 INFO - PROCESS | 1658 | 1462794977125 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] 04:58:28 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 04:58:29 INFO - PROCESS | 1658 | RSS: Main: 359231488 04:58:29 INFO - PROCESS | 1658 | 05:01:32 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 05:01:33 INFO - PROCESS | 1658 | RSS: Main: 374042624 05:01:33 INFO - PROCESS | 1658 | 05:04:35 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 05:04:36 INFO - PROCESS | 1658 | RSS: Main: 370163712 05:04:36 INFO - PROCESS | 1658 | 05:07:38 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 05:07:39 INFO - PROCESS | 1658 | RSS: Main: 368508928 05:07:39 INFO - PROCESS | 1658 | 05:10:42 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 05:10:42 INFO - PROCESS | 1658 | RSS: Main: 333070336 05:10:42 INFO - PROCESS | 1658 | 05:13:46 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 05:13:46 INFO - PROCESS | 1658 | RSS: Main: 374796288 05:13:46 INFO - PROCESS | 1658 | 05:13:46 INFO - PROCESS | 1658 | __start_tp_report 05:13:46 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 05:13:46 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 05:13:46 INFO - PROCESS | 1658 | |i|pagename|runs| 05:13:46 INFO - PROCESS | 1658 | |0;dojo.html;3442.913927151821;3457.0665920201545;3476.217850983528;3485.477178423235;3519.8944031679057;4225.640820530255;5576.219329293902;5672.943270567293;5702.344230413503;5867.1492633568105;5714.657120572769;5761.107028410593;5773.451522105393;5777.682227477487;5813.360530341659;5234.895302093956;5277.050130976423;5280.313559237294;5301.787928482875;5367.865803354908;4735.431748190204;5130.179171332587;5254.264402983586;5385.7576409061685;5457.590680698963;5246.580273578104;5292.10034294169;5307.097793375136;5308.522232999029;5342.599305052117;5334.146536554133;5340.519353258186;5355.437679043684;5369.570434365241;5374.811881584125;5178.508041736029;5188.740562971836;5192.844214673566;5268.736563171826;5287.656302340374;5574.693391863446;5612.438756124396;5642.463965923272;5650.943490565124;5693.089105743061;6218.626882387026;6270.3416141305415;6277.340879207663;6299;6304.117423560705;6156.907646385308;6173.598716083485;6214.65819379934;6228.657423841687;6231.626102433868;6010.338862725053;6041.365656606081;6045.939540604626;6064.575479716377;6083.087536869505;5218.425973142951;6212;6316.115743795872;6338.841528961812;6378.872422551616;6094.786682466092;6105.8843398869885;6158.876822463526;6232.25212974446;6240.4071613196675;6226.1905952226825;6230.59501132425;6235.095911092866;6246.46905013079;6249.312575616678;6128.295246046761;6228.439440450381;6229.470495007885;6233.065040243909;6252.812415627538;6151.938480615226;6159.384061593805;6193.783217587363;6218.19163508741;6246.625202487865;4956.033573453215;4990.6506544541535;5005.224190250517;5009.348784657971;5011.273365362002;3923.2938071146896;4475.753833539186;4483.394741709827;4543.727376357363;4572.497025327211;5866.97327967604;5896.882062358729;5899.882002359929;5906.2912450505355;5913.70431478433;6121.296050954197;6190.0714892765545;6270.184875966095;6272.968635156886;6287.528435367366;5801.274840644919;5825.359210486928;5877.853053673693;5901.645901245939;5913.526917846562;5497.092979658391;5504.366997795225;5504.6421982571;5506.779728810803;5508.366537848118;6201.472874805692;6431.292557818636;6469.967650161719;6504.544681872224;6521.576097553644;6178.752849885954;6182.319944805978;6206.472449841814;6306.873862522815;6319.96840015797;6482.222133344029;6494.123293355526;6502.414782669583;6502.967485162733;6517.804465866032;6120.17377654011;6123.1606109212;6154.138420621206;6178.042403427476;6199.56603037783;6104.459155447048;6125.388069147376;6155.6614386209185;6234.3453937337745;6238.438540531374;6014.939850601613;6099.908501372394;6103.664298463627;6111.291086246819;6137.60105593135;5066.594672426123;6048.5161187105805;6088.939110608837;6096.237970253718;6113.052476865915;5999.070144127667;6067.211262535842;6068.605540639845;6081.802781075923;6122.785702500391;5987.595775214344;6123.120160253514;6188.566800323934;6190.164327815687;6208.689565521796;5869.090291004901;5903.527717782653;6008.7296071676;6015.368386319548;6082.939170608414 05:13:46 INFO - PROCESS | 1658 | |1;ext.html;499.63776262209893;523.277876530388;524.412657823238;532.252185679121;560.529155509372;30631.846840765793;31956.881509147184;42709.57290427095;43957.56042439575;44023.67928962128;44381.77809110958;44714;44841.55158448414;44861.55138448615;45103.77448112755;9683.225341972642;9878.654247101353;9883.209343252522;9897.208223342115;9920;8678.349123815708;8892;10339.534720937576;12023.458944347482;12024.218425802295;11908.34504102274;11956.282623042602;11977.281363118198;11991;11997.760044799099;8686.435381700168;8900.02099769025;11681.007114395286;11757.941210293979;11780.764384712344;11449.828252576219;11826.586069487526;11844.763104737944;11922.642320730392;11948.940255298668;41373.58626413758;41649.583504164875;41676.58323416757;41701.79149104235;41754;3452.7583069185166;3827.9617203827884;3868.1490072184074;3946.565877753445;3979.1047014421524;28960.710392896013;29939.25151871199;30246.84876575625;30519.542206866914;30540.8472957636;2753.559430491112;2753.6695596528543;2764.6380729021776;2778.166550035002;2826.349939513903;455863;455865;456864;467564.66217669036;469603;35582.288354233286;36441.81779091088;36450.635493645255;63057;63821;37673;37771;37826.62173378231;37842;37950.620493795264;15330.846691532943;15486;15490.9225453872;15491.993020453634;15552.92223538875;11892.702682433008;12147.271163730204;12743.553975610808;12840.871591284154;12888.80666789999;8256;8304.252617264476;8336.458130221637;8383.077861435237;8409.747707568782;2772.07135609569;3390.9996551017507;3462.082548124749;3469;3492.161881148506;981.6711401680517;1012.9594816207269;1056.4928834159493;1139.700741155084;1192.6243233381458;29348.266293342836;29709.40581188364;29912.953046643262;30227.697723022928;30394;918.820829938169;1022.3916769522123;1120.2998126171144;1132.6545403651965;1144.5936692474218;12939.900108490696;14938.775918361232;15317.923410382877;15358.769618455732;15658.68682626341;645100.5489945135;654666;654805;658338;659076;61758.382416175264;61992;62122.689386552775;62184;62275.68862155751;1343.32162258061;1448.86960173587;1486.3534362552325;1508.6228442889278;1510.75072613022;29083.41833163367;30446.391072178874;30604.540931885593;30615;31394.21514462157;52645.7367713159;53113.67215819636;53271.4672853282;53537.46462535325;53551.464485356206;494769;514565;515891;517072;517802;23452.296431107094;23965.400864978514;24007.51984960326;24631.38421539476;27247.86376068107;38685.61314386933;46964.53035469738;48359.5164048355;50579.98840023105;50913.23630145477;38741.6125838738;39046.023849403995;39206.6079339203;39623.207535848545;39769;37792;37996.25878092018;38266.426003610526;38450.42324365193;38942.41586376263;37697.43453848249;37759.86720398392;38146.2370752589;38340.61659383482;38695.80652096721;32813.67186328106;32884.01347959565;33642.158946026546;33708.157296067795;33718;24555.649439281013;24602.630960535964;24785.653936719034;25178.62232066484;25434.982600695766 05:13:46 INFO - PROCESS | 1658 | |2;jquery.html;386.29693956998256;386.43773309834194;389.682408836798;394.7434167790937;414.85687437833957;12047.956167826946;19859.801401985977;20128.09423575939;21078.367648970536;21099.789002109992;19911.30310439135;20506.38480845577;20984.790152098474;20993.58012839742;21000.05499752511;3760.717946154036;5416.1063424534905;5433.510984011438;5450.890982180354;5463.972680136594;5265.131253343194;5371.704556249405;5480.095784195603;6157.076438534211;6160.599561028519;5315.830517286191;6194.132821405007;6203.193584833988;6221.253449586056;6227.532935029868;5889.793857214999;6092.11664308675;6135.509159267248;6137.846553836145;6138.109974053782;5762.510186634141;5793.2179155814265;5967.373425790273;5991.161237426763;6000.549958753111;13595.98030147733;13911.443542258297;14166.716665666732;14226.85773142276;14277.143371397646;2136.5192831612762;2149.8495105342477;2169.457635591102;2180.6292930201744;2356.2931120663734;9945.850812237823;10103.141232995204;10137.290389672726;10449.582016719398;10590.36457812526;1706.5221737913405;1725.9050752208623;1795.6588248232795;1811.4475085099039;1945.4066509714532;110820;115948;116996;117278.41360793226;119297.40351298275;9691.370060946158;11643.243189192675;11716.589919352782;11770.3526306054;11871.64385068432;13029.631888651744;13555.525556605471;14261.572152835432;14273.78589321161;15309.923450382677;7145.535540189871;7588.089429268523;8063.435559510778;8677.002144753307;8798;8446.788830279169;8519.829603407898;8566.3146948244;8683.696070637623;8713.651453941771;4552.294394368878;4632.72203667781;4679.64902632304;4763.237881938873;4774.976125119421;1771.8757957346193;1799.1364145210373;1855.3413538194018;1983.226541648758;1994.5512259741731;930.6603089872148;932.4125800745487;963.7542426681151;991.8115558043949;1033.7646512417589;18445.354412595763;20407.81724521398;22004.669929951062;22101;22197.889010554845;894.2756367342473;927.221134247222;1040.7554224757175;1056.8097742406287;1063.872335319751;9764;10884.292520986111;10896.019358257794;10899.29154604948;10948.945255273673;619647.9017604883;622737;626274;642816;645129;130024.69975300315;130627;131402;146397;151901.86249987685;1057.117307048623;1067.8505009298703;1088.8911108889047;1088.9945550272357;1090.2858627598778;13823.202983612066;16421.835781642032;17632.471025869243;17639.559011024827;17810.376636817648;52042.47957520376;53771.73114134404;53951;54036.18945715739;54157;82521;83616;84899.5755021221;86500;87819.56090219508;8036.839263214586;8060.919390806017;8107.594620268845;8297.377696672895;8523.831836262614;8285.62714677853;9093.272538196796;11539.24994875364;12292.770722927993;12645.87354126447;10234.590616375559;10694;10757.031867131996;10876.564937402733;11040.00639942409;9190.540472976458;9369.437833729999;10117.140043096419;10432.374057556572;10742.785144297226;10607.575696972035;10727.302725322828;11004;11179.0497807684;11243.55025798959;8120.309773669304;8294.543800091053;8298.958505207434;8350.457220280663;8544.273736732448;8297.792555186168;10874.184436167478;10889.440981369213;11019.118470522179;11180.329180249211 05:13:46 INFO - PROCESS | 1658 | |3;mootools.html;1406.191439922045;1582.5568840724598;1610.524895155929;1839.5217243516681;1977.090538352358;7639.121501027376;8438.409311348209;8692.869606955901;8694.043655197924;8817.735467935956;8459.957700211491;8687.609057592408;8700.26047785939;8702.782430439227;8704.390692651517;2098.9475263118393;2334.8482348647367;2390.8565486070893;2420.479596886669;2420.7942324902406;2446.645236440715;2466.8766561671846;2467.962980555293;2470.8641024743633;2474.826762126652;2372.9881350593223;2428;2431.465077682907;2431.744666809986;2434.075051480435;2102.9684554731616;2315.3285547191294;2365.66880636711;2391.1272385579136;2391.426057746146;2163.945901352463;2420.346506443268;2437.4035007070397;2439.0365805506904;2441.926742197737;8597.957010214972;8667.133286671346;8678.175573320525;8679.392442529026;8687.695930642398;4318.762468064255;5102.081625307444;5255.632105752599;5270.314859068297;5296;3401.064707205538;3468.289000754839;3522.6301238370115;3532.5760908690863;3549.165946002687;1016.5882817458867;1048.1405247696891;1048.2505008918583;1050.2228351020267;1063.9946800266027;11648.88351116495;11682.941585292105;11685.12361572877;11693.70765730855;11763.294202347886;4697.769529675788;4838.467768545457;4853.150698627796;4854.490278520775;4904.852854414374;9732.902670973199;9846.852297215548;9856.50717464124;9863.506824658738;9870.703878883645;5308.23030660552;5315.707636080053;5353.277307563507;5358.089124848785;5360.785568577213;4339.370791235317;4539.477960034638;4708.95291047092;4838.371011768518;4852.660313778072;3937.89738873116;3955.0310174006977;4044.2518134145275;4083.97958010214;4084.8774536763945;1299.493197652916;1310.4692599497218;1313.481174935895;1327.734453109382;1342.3020029584757;837.8408102460513;843.9915600843913;855.461059532503;935.335911502827;938.3666025432806;4687.695299805502;5852.268466441695;6025.758969641165;6054.122152287981;6148.292946311231;974.2303580171586;985.7486340983004;994.4977786218059;999.6001599360169;1041.1722680469045;3931.331673615492;3962.4848769660334;3994.460747799068;4070.8778736637946;4071.2875246831686;10245.897541024495;10504.632337868137;10727.83908241377;10745.731356715989;10751.462426878781;10540.947295263475;10605.893941060645;10679.412632305297;10690.78618427627;10704.94647526773;7663.850422436679;7997.720079797179;8139.982502187227;8145.266925976691;8148.185181481805;5917.408259174048;5959.821205363846;6088.8173354797655;6095.207623008981;6225.3774622537385;9518.095780900803;9790.706278811647;9901.306908516335;9968.451735154636;10043.799124017332;9774.706758797247;10018.499075046364;10077.445740484343;10159.187265018632;10172.237082218715;6086.573939824347;6769.221539522821;7006.159260888522;7208.56748595086;7544.849103017799;8126.837463250584;8303.792405189919;8316.126806685212;8330.083690793886;8381.790455238668;7272.490925635155;7315.48791584584;7368.263173682589;7485.775426736989;7511.286427789351;7156.284371563011;7201.783946481614;7248.166460857069;7282.526635768658;7322.157951835394;6876.17485901695;6944.375006249462;6976.895346569704;6986.54587451814;6987.266337034742;4316.978415107905;4855.684380515255;4866.318715379921;4895.510448955076;5020.146575082172;4952.950470495249;4994.650374473752;5023.497650235094;5048.772805223847;5251.317328747238 05:13:46 INFO - PROCESS | 1658 | |4;prototype.html;266.7559183347237;308.6620150934727;316.9809811411314;323.39040907888636;325.8142858570613;4501.144782491328;4701.435827700672;5261.8421447356595;5294.338207724035;5299.60252981026;4434.290513517838;5231.816886408977;5248.868778280545;5266.420693723697;5267.341582302212;3785.5268091488497;3900.648941595256;3977.2840888640035;4051.6758659307184;4054.432379466877;3387.6104248011425;3964.841406343757;3999.4800675912093;4036.293648611496;4058.391241313812;3763.3253202324977;3831.63599458051;3841.308564458396;4020.4722205561993;4027;3812.866549670762;3823.751456155369;4009.939850902239;4023.229778897276;4037.5356833963883;3509.2630547585036;3518.964810351915;3519.4720791881423;3533.049903330722;3538.876139335111;4989.775460104268;5010.423801262865;5018.849434516934;5020.815945063909;5054.393472783289;4470.776461176961;4602.424696912886;4642.050338857688;4661.813527458898;4670.673052886299;4608.746518941491;4731;5364;5507.504324610804;5555.638883472561;3485.9542137358944;3539.5398598182073;3906.921861562753;3917.157811070605;3987.9202415951518;5373.865653358658;5380.515753582196;5616.9608622404985;5619.904118696856;5751.683657398841;3509.5262139611336;3546.840392182358;3603.5315408997185;3762.8494860205287;3926.8821935341984;3648.890533284006;3843.808419389998;4028.798560072043;4030.334994725896;4033.9140557473556;4387.758673272937;4609.5620916013595;4620.4686461056735;4627.583517483443;4629.606483448944;5095.00647373766;5356.437574054746;5482.533984611273;5525.640833345819;5575.972120139373;1098.6429410441638;1238.8699186585459;1295.3393769177599;1300.518808041031;1329.5080820096432;805.3074356053789;819.9918000820035;844.8014716541608;861.5950503263456;908.3550679017972;4669.813207471731;4766.213574760126;4934.0149209728315;4993.450720420751;5133.820316288987;5089.440161582223;5669.773209071591;5713.600047996683;5756.597038207286;5803.88392232153;4570.657200709894;4646.535346465326;4971.246708734882;5124.333836601218;5131.692098474104;2470.71586767524;2822.717728227161;2848.017433985272;2855.2290881462304;2877.5395936650452;5422.728863556807;5434.347878254555;5633.2395126657375;5645.717714114277;5677.489025987681;4868.71170549841;4886.438059623118;5285.312909321764;5454.809081682201;5534.861628459321;5117.667351622207;5123.795048198031;5272.604554658416;5613.996511866214;5639.943600564024;4915.574483399774;5083.339165908556;5230.084735171406;5278.9472105278455;5324.760385782572;5112.335396398445;5332.1202001670845;5359.3032905722;5388.54197393226;5415.241866138665;5389.359213985041;5556.416576259435;5630.915536266877;5663.85840353978;5675.7445914933105;3425.0373426985975;3529.6823285904393;3632.6004139545157;3758.285925674113;3764.247150569952;4643.791029403753;4826.782794774173;4982.5764809990105;5135.178371460548;5226.738663066907;4528.958339581849;4776.450708168605;5174.395937259462;5174.767135478988;5208.187578707456;4734.526547345238;4814.422269327563;5138.614603904647;5212.843614691566;5239.449857765032;4946.505349465025;5395.273512476127;5419.485148910847;5433.750237445331;5442.292501974718;4579.74811385377;5282.97358513205;5398.811041613525;5548.361938377138;5575.665460072409;4654.650901182493;5085.830259040367;5431.782728690808;5443.646162999393;5450.182472629137 05:13:46 INFO - PROCESS | 1658 | |5;yui.html;724.1563578431128;739.2755100001998;751.3012898004853;751.5415596486142;757.8219118507153;14896.77654835177;15309.540713778577;15321.846781532195;15339.769903451457;15372.077675339488;13911.930440347784;13942.930285348584;14023.088499247542;14029.579112626612;14034.157950522977;704.7286794584096;717.7236763845905;889.902110767815;892.6697122064829;905.5608030105394;906.650939388337;913.8811954445913;918.8897332320131;919.995400022999;921.4010892919622;977.330528587916;977.9364341317792;979.8775153105862;980.9460479673616;984.3700031979539;756.9962150189269;935.625749700122;951.923846092311;955.3933252384717;969.142309056483;614.5759425996048;724.7572063358781;724.9746258880966;729.770122411444;733.9853202935911;14600.050996685288;14633.634159146106;14659.486917957927;14695.412183512646;14700.970932034761;11987.220830646067;12125.27248365092;12180.086493512934;12321.445534950948;12431.902919802837;10541.68374948745;10616.363018218854;10769.730756731065;10952.561897524089;11014.559417623286;3939.7833119178435;3982.203559288125;4033.253848038122;4054.736442131252;4120.587941205886;76693;76797;79009.20990790133;80953.59523202405;81676;7692.346150577264;9441.811163776823;9441.952790236142;9512.76562004448;9733.659321923698;21236;21239.044243009103;21254.78745212528;21298.36104916855;21338.14647414117;6318.684065796784;7478.925210747823;7539.700391380572;7672.271134242238;7823.413244006609;4030.717849750548;4116.217918595517;4120.464339635888;4125.566815484331;4134.421181034658;3549.8757543485854;3642.508261384732;3644.85420583179;3650.142216579102;3652.543432070991;518.3675915383227;599.6342231238941;615.7352338494491;617.561531312773;632.225523733432;532.6058716549725;547.9616426850082;567.7757285872058;595.8659301657093;614.532954954229;7887.369010479148;8033.357331413473;8102.635381407851;8159.836803263902;8359.916400835913;557.9023670857583;584.2288179602883;594.669958173218;595.961262517935;607.1136141233848;3751.5498140222803;3841.753956648447;3844.9038774030314;3896.668783153407;3900.5124359455067;63009;72589;90452;90575.54712226396;91041.54479227561;78613;79713.60143199247;80238.59880700559;80474.79287810688;81511;71298.57402851914;71353;71430;71585.64207179035;71629.64185179041;7400.296971787671;7402.777916662509;7454.55272683619;7508.511946723445;7607.771766847003;42905.57094429016;42976;43611.78194109136;43684;44863.32705009493;56823.45412366918;62369.68815155895;62478;62888.37111629008;63693.68153159205;8927.24118449939;9680.709578712649;9823.459709716035;10255.94872025635;10346.430946298025;9617.471039092918;9988.850167247352;11071.446427678746;11157.609483668033;11558.19092663539;8656.740297791077;9824.164945979672;10122.44326562046;10498.370097794157;10505.579776808841;10190.133838623504;10432.478376081013;10510.894891051297;10526.842097368699;10624.575016999233;10003.899961000589;10212.540435680263;10309.175265978854;10391.116755075904;10451.059404653313;8405.327573794204;8523.232909038215;8530.616122274389;8785.824283514421;8870.645174193218;8630.266427353745;8645.610947507252;8723.869141963003;8740.038595754335;8988 05:13:46 INFO - PROCESS | 1658 | __end_tp_report 05:13:46 INFO - PROCESS | 1658 | __start_cc_report 05:13:46 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1160 05:13:46 INFO - PROCESS | 1658 | __end_cc_report 05:13:46 INFO - PROCESS | 1658 | __startTimestamp1462796026380__endTimestamp 05:13:46 INFO - PROCESS | 1658 | 05:13:46 INFO - PROCESS | 1658 | ------- Summary: start ------- 05:13:46 INFO - PROCESS | 1658 | Number of tests: 6 05:13:46 INFO - PROCESS | 1658 | 05:13:46 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5776.31 Median:6048.52 stddev:644.02 (10.6%) stddev-sans-first:619.44 05:13:46 INFO - PROCESS | 1658 | Values: 3442.9 3457.1 3476.2 3485.5 3519.9 4225.6 5576.2 5672.9 5702.3 5867.1 5714.7 5761.1 5773.5 5777.7 5813.4 5234.9 5277.1 5280.3 5301.8 5367.9 4735.4 5130.2 5254.3 5385.8 5457.6 5246.6 5292.1 5307.1 5308.5 5342.6 5334.1 5340.5 5355.4 5369.6 5374.8 5178.5 5188.7 5192.8 5268.7 5287.7 5574.7 5612.4 5642.5 5650.9 5693.1 6218.6 6270.3 6277.3 6299.0 6304.1 6156.9 6173.6 6214.7 6228.7 6231.6 6010.3 6041.4 6045.9 6064.6 6083.1 5218.4 6212.0 6316.1 6338.8 6378.9 6094.8 6105.9 6158.9 6232.3 6240.4 6226.2 6230.6 6235.1 6246.5 6249.3 6128.3 6228.4 6229.5 6233.1 6252.8 6151.9 6159.4 6193.8 6218.2 6246.6 4956.0 4990.7 5005.2 5009.3 5011.3 3923.3 4475.8 4483.4 4543.7 4572.5 5867.0 5896.9 5899.9 5906.3 5913.7 6121.3 6190.1 6270.2 6273.0 6287.5 5801.3 5825.4 5877.9 5901.6 5913.5 5497.1 5504.4 5504.6 5506.8 5508.4 6201.5 6431.3 6470.0 6504.5 6521.6 6178.8 6182.3 6206.5 6306.9 6320.0 6482.2 6494.1 6502.4 6503.0 6517.8 6120.2 6123.2 6154.1 6178.0 6199.6 6104.5 6125.4 6155.7 6234.3 6238.4 6014.9 6099.9 6103.7 6111.3 6137.6 5066.6 6048.5 6088.9 6096.2 6113.1 5999.1 6067.2 6068.6 6081.8 6122.8 5987.6 6123.1 6188.6 6190.2 6208.7 5869.1 5903.5 6008.7 6015.4 6082.9 05:13:46 INFO - PROCESS | 1658 | 05:13:46 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:66907.86 Median:37685.22 stddev:146744.43 (389.4%) stddev-sans-first:147071.38 05:13:46 INFO - PROCESS | 1658 | Values: 499.6 523.3 524.4 532.3 560.5 30631.8 31956.9 42709.6 43957.6 44023.7 44381.8 44714.0 44841.6 44861.6 45103.8 9683.2 9878.7 9883.2 9897.2 9920.0 8678.3 8892.0 10339.5 12023.5 12024.2 11908.3 11956.3 11977.3 11991.0 11997.8 8686.4 8900.0 11681.0 11757.9 11780.8 11449.8 11826.6 11844.8 11922.6 11948.9 41373.6 41649.6 41676.6 41701.8 41754.0 3452.8 3828.0 3868.1 3946.6 3979.1 28960.7 29939.3 30246.8 30519.5 30540.8 2753.6 2753.7 2764.6 2778.2 2826.3 455863.0 455865.0 456864.0 467564.7 469603.0 35582.3 36441.8 36450.6 63057.0 63821.0 37673.0 37771.0 37826.6 37842.0 37950.6 15330.8 15486.0 15490.9 15492.0 15552.9 11892.7 12147.3 12743.6 12840.9 12888.8 8256.0 8304.3 8336.5 8383.1 8409.7 2772.1 3391.0 3462.1 3469.0 3492.2 981.7 1013.0 1056.5 1139.7 1192.6 29348.3 29709.4 29913.0 30227.7 30394.0 918.8 1022.4 1120.3 1132.7 1144.6 12939.9 14938.8 15317.9 15358.8 15658.7 645100.5 654666.0 654805.0 658338.0 659076.0 61758.4 61992.0 62122.7 62184.0 62275.7 1343.3 1448.9 1486.4 1508.6 1510.8 29083.4 30446.4 30604.5 30615.0 31394.2 52645.7 53113.7 53271.5 53537.5 53551.5 494769.0 514565.0 515891.0 517072.0 517802.0 23452.3 23965.4 24007.5 24631.4 27247.9 38685.6 46964.5 48359.5 50580.0 50913.2 38741.6 39046.0 39206.6 39623.2 39769.0 37792.0 37996.3 38266.4 38450.4 38942.4 37697.4 37759.9 38146.2 38340.6 38695.8 32813.7 32884.0 33642.2 33708.2 33718.0 24555.6 24602.6 24785.7 25178.6 25435.0 05:13:46 INFO - PROCESS | 1658 | 05:13:46 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:35848.95 Median:2175.04 stddev:105499.85 (4850.5%) stddev-sans-first:105762.20 05:13:46 INFO - PROCESS | 1658 | Values: 386.3 386.4 389.7 394.7 414.9 12048.0 19859.8 20128.1 21078.4 21099.8 19911.3 20506.4 20984.8 20993.6 21000.1 3760.7 5416.1 5433.5 5450.9 5464.0 5265.1 5371.7 5480.1 6157.1 6160.6 5315.8 6194.1 6203.2 6221.3 6227.5 5889.8 6092.1 6135.5 6137.8 6138.1 5762.5 5793.2 5967.4 5991.2 6000.5 13596.0 13911.4 14166.7 14226.9 14277.1 2136.5 2149.8 2169.5 2180.6 2356.3 9945.9 10103.1 10137.3 10449.6 10590.4 1706.5 1725.9 1795.7 1811.4 1945.4 110820.0 115948.0 116996.0 117278.4 119297.4 9691.4 11643.2 11716.6 11770.4 11871.6 13029.6 13555.5 14261.6 14273.8 15309.9 7145.5 7588.1 8063.4 8677.0 8798.0 8446.8 8519.8 8566.3 8683.7 8713.7 4552.3 4632.7 4679.6 4763.2 4775.0 1771.9 1799.1 1855.3 1983.2 1994.6 930.7 932.4 963.8 991.8 1033.8 18445.4 20407.8 22004.7 22101.0 22197.9 894.3 927.2 1040.8 1056.8 1063.9 9764.0 10884.3 10896.0 10899.3 10948.9 619647.9 622737.0 626274.0 642816.0 645129.0 130024.7 130627.0 131402.0 146397.0 151901.9 1057.1 1067.9 1088.9 1089.0 1090.3 13823.2 16421.8 17632.5 17639.6 17810.4 52042.5 53771.7 53951.0 54036.2 54157.0 82521.0 83616.0 84899.6 86500.0 87819.6 8036.8 8060.9 8107.6 8297.4 8523.8 8285.6 9093.3 11539.2 12292.8 12645.9 10234.6 10694.0 10757.0 10876.6 11040.0 9190.5 9369.4 10117.1 10432.4 10742.8 10607.6 10727.3 11004.0 11179.0 11243.6 8120.3 8294.5 8299.0 8350.5 8544.3 8297.8 10874.2 10889.4 11019.1 11180.3 05:13:46 INFO - PROCESS | 1658 | 05:13:46 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5670.44 Median:4900.18 stddev:3152.64 (64.3%) stddev-sans-first:3145.19 05:13:46 INFO - PROCESS | 1658 | Values: 1406.2 1582.6 1610.5 1839.5 1977.1 7639.1 8438.4 8692.9 8694.0 8817.7 8460.0 8687.6 8700.3 8702.8 8704.4 2098.9 2334.8 2390.9 2420.5 2420.8 2446.6 2466.9 2468.0 2470.9 2474.8 2373.0 2428.0 2431.5 2431.7 2434.1 2103.0 2315.3 2365.7 2391.1 2391.4 2163.9 2420.3 2437.4 2439.0 2441.9 8598.0 8667.1 8678.2 8679.4 8687.7 4318.8 5102.1 5255.6 5270.3 5296.0 3401.1 3468.3 3522.6 3532.6 3549.2 1016.6 1048.1 1048.3 1050.2 1064.0 11648.9 11682.9 11685.1 11693.7 11763.3 4697.8 4838.5 4853.2 4854.5 4904.9 9732.9 9846.9 9856.5 9863.5 9870.7 5308.2 5315.7 5353.3 5358.1 5360.8 4339.4 4539.5 4709.0 4838.4 4852.7 3937.9 3955.0 4044.3 4084.0 4084.9 1299.5 1310.5 1313.5 1327.7 1342.3 837.8 844.0 855.5 935.3 938.4 4687.7 5852.3 6025.8 6054.1 6148.3 974.2 985.7 994.5 999.6 1041.2 3931.3 3962.5 3994.5 4070.9 4071.3 10245.9 10504.6 10727.8 10745.7 10751.5 10540.9 10605.9 10679.4 10690.8 10704.9 7663.9 7997.7 8140.0 8145.3 8148.2 5917.4 5959.8 6088.8 6095.2 6225.4 9518.1 9790.7 9901.3 9968.5 10043.8 9774.7 10018.5 10077.4 10159.2 10172.2 6086.6 6769.2 7006.2 7208.6 7544.8 8126.8 8303.8 8316.1 8330.1 8381.8 7272.5 7315.5 7368.3 7485.8 7511.3 7156.3 7201.8 7248.2 7282.5 7322.2 6876.2 6944.4 6976.9 6986.5 6987.3 4317.0 4855.7 4866.3 4895.5 5020.1 4953.0 4994.7 5023.5 5048.8 5251.3 05:13:46 INFO - PROCESS | 1658 | 05:13:46 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4393.04 Median:4940.26 stddev:1316.59 (26.7%) stddev-sans-first:1283.34 05:13:46 INFO - PROCESS | 1658 | Values: 266.8 308.7 317.0 323.4 325.8 4501.1 4701.4 5261.8 5294.3 5299.6 4434.3 5231.8 5248.9 5266.4 5267.3 3785.5 3900.6 3977.3 4051.7 4054.4 3387.6 3964.8 3999.5 4036.3 4058.4 3763.3 3831.6 3841.3 4020.5 4027.0 3812.9 3823.8 4009.9 4023.2 4037.5 3509.3 3519.0 3519.5 3533.0 3538.9 4989.8 5010.4 5018.8 5020.8 5054.4 4470.8 4602.4 4642.1 4661.8 4670.7 4608.7 4731.0 5364.0 5507.5 5555.6 3486.0 3539.5 3906.9 3917.2 3987.9 5373.9 5380.5 5617.0 5619.9 5751.7 3509.5 3546.8 3603.5 3762.8 3926.9 3648.9 3843.8 4028.8 4030.3 4033.9 4387.8 4609.6 4620.5 4627.6 4629.6 5095.0 5356.4 5482.5 5525.6 5576.0 1098.6 1238.9 1295.3 1300.5 1329.5 805.3 820.0 844.8 861.6 908.4 4669.8 4766.2 4934.0 4993.5 5133.8 5089.4 5669.8 5713.6 5756.6 5803.9 4570.7 4646.5 4971.2 5124.3 5131.7 2470.7 2822.7 2848.0 2855.2 2877.5 5422.7 5434.3 5633.2 5645.7 5677.5 4868.7 4886.4 5285.3 5454.8 5534.9 5117.7 5123.8 5272.6 5614.0 5639.9 4915.6 5083.3 5230.1 5278.9 5324.8 5112.3 5332.1 5359.3 5388.5 5415.2 5389.4 5556.4 5630.9 5663.9 5675.7 3425.0 3529.7 3632.6 3758.3 3764.2 4643.8 4826.8 4982.6 5135.2 5226.7 4529.0 4776.5 5174.4 5174.8 5208.2 4734.5 4814.4 5138.6 5212.8 5239.4 4946.5 5395.3 5419.5 5433.8 5442.3 4579.7 5283.0 5398.8 5548.4 5575.7 4654.7 5085.8 5431.8 5443.6 5450.2 05:13:46 INFO - PROCESS | 1658 | 05:13:46 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:17461.38 Median:62683.19 stddev:24593.60 (39.2%) stddev-sans-first:24630.49 05:13:46 INFO - PROCESS | 1658 | Values: 724.2 739.3 751.3 751.5 757.8 14896.8 15309.5 15321.8 15339.8 15372.1 13911.9 13942.9 14023.1 14029.6 14034.2 704.7 717.7 889.9 892.7 905.6 906.7 913.9 918.9 920.0 921.4 977.3 977.9 979.9 980.9 984.4 757.0 935.6 951.9 955.4 969.1 614.6 724.8 725.0 729.8 734.0 14600.1 14633.6 14659.5 14695.4 14701.0 11987.2 12125.3 12180.1 12321.4 12431.9 10541.7 10616.4 10769.7 10952.6 11014.6 3939.8 3982.2 4033.3 4054.7 4120.6 76693.0 76797.0 79009.2 80953.6 81676.0 7692.3 9441.8 9442.0 9512.8 9733.7 21236.0 21239.0 21254.8 21298.4 21338.1 6318.7 7478.9 7539.7 7672.3 7823.4 4030.7 4116.2 4120.5 4125.6 4134.4 3549.9 3642.5 3644.9 3650.1 3652.5 518.4 599.6 615.7 617.6 632.2 532.6 548.0 567.8 595.9 614.5 7887.4 8033.4 8102.6 8159.8 8359.9 557.9 584.2 594.7 596.0 607.1 3751.5 3841.8 3844.9 3896.7 3900.5 63009.0 72589.0 90452.0 90575.5 91041.5 78613.0 79713.6 80238.6 80474.8 81511.0 71298.6 71353.0 71430.0 71585.6 71629.6 7400.3 7402.8 7454.6 7508.5 7607.8 42905.6 42976.0 43611.8 43684.0 44863.3 56823.5 62369.7 62478.0 62888.4 63693.7 8927.2 9680.7 9823.5 10255.9 10346.4 9617.5 9988.9 11071.4 11157.6 11558.2 8656.7 9824.2 10122.4 10498.4 10505.6 10190.1 10432.5 10510.9 10526.8 10624.6 10003.9 10212.5 10309.2 10391.1 10451.1 8405.3 8523.2 8530.6 8785.8 8870.6 8630.3 8645.6 8723.9 8740.0 8988.0 05:13:46 INFO - PROCESS | 1658 | -------- Summary: end -------- 05:13:46 INFO - PROCESS | 1658 | 05:13:46 INFO - TEST-INFO | 1658: exit 0 05:13:46 INFO - TEST-OK | dromaeo_css | took 1116690ms 05:13:46 INFO - TEST-START | kraken 05:13:46 INFO - operating with platform_type : mac_ 05:13:46 INFO - Initialising browser for kraken test... 05:13:46 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpd7xgmc/profile http://localhost:49272/getInfo.html) 05:13:49 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 05:13:49 INFO - PROCESS | 1667 | colorDepth:24 05:13:49 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 05:13:49 INFO - PROCESS | 1667 | __metrics 05:13:49 INFO - PROCESS | 1667 | 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" 05:13:49 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:13:49 INFO - TEST-INFO | 1667: exit 0 05:13:49 INFO - Browser initialized. 05:13:49 INFO - Running cycle 1/1 for kraken test... 05:13:49 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpd7xgmc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:14:01 INFO - PROCESS | 1669 | RSS: Main: 239075328 05:14:01 INFO - PROCESS | 1669 | 05:14:04 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 05:14:05 INFO - PROCESS | 1669 | RSS: Main: 286961664 05:14:05 INFO - PROCESS | 1669 | 05:14:08 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 05:14:08 INFO - PROCESS | 1669 | RSS: Main: 296050688 05:14:08 INFO - PROCESS | 1669 | 05:14:12 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 05:14:12 INFO - PROCESS | 1669 | RSS: Main: 381517824 05:14:12 INFO - PROCESS | 1669 | 05:14:15 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 05:14:15 INFO - PROCESS | 1669 | RSS: Main: 284430336 05:14:15 INFO - PROCESS | 1669 | 05:14:18 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:14:18 INFO - PROCESS | 1669 | RSS: Main: 287956992 05:14:18 INFO - PROCESS | 1669 | 05:14:21 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 05:14:22 INFO - PROCESS | 1669 | RSS: Main: 327024640 05:14:22 INFO - PROCESS | 1669 | 05:14:25 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 05:14:25 INFO - PROCESS | 1669 | RSS: Main: 327647232 05:14:25 INFO - PROCESS | 1669 | 05:14:28 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 05:14:29 INFO - PROCESS | 1669 | RSS: Main: 326475776 05:14:29 INFO - PROCESS | 1669 | 05:14:31 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:14:31 INFO - PROCESS | 1669 | RSS: Main: 316043264 05:14:31 INFO - PROCESS | 1669 | 05:14:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:14:34 INFO - PROCESS | 1669 | RSS: Main: 321634304 05:14:34 INFO - PROCESS | 1669 | 05:14:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:14:37 INFO - PROCESS | 1669 | RSS: Main: 325046272 05:14:37 INFO - PROCESS | 1669 | 05:14:40 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:14:41 INFO - PROCESS | 1669 | RSS: Main: 329539584 05:14:41 INFO - PROCESS | 1669 | 05:14:44 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:14:45 INFO - PROCESS | 1669 | RSS: Main: 327340032 05:14:45 INFO - PROCESS | 1669 | 05:14:46 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 05:14:47 INFO - PROCESS | 1669 | RSS: Main: 374153216 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | __start_tp_report 05:14:47 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 05:14:47 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 05:14:47 INFO - PROCESS | 1669 | |i|pagename|runs| 05:14:47 INFO - PROCESS | 1669 | |0;ai-astar;113;108;113;113;113;112;113;111;111;111 05:14:47 INFO - PROCESS | 1669 | |1;audio-beat-detection;159;116;142;212;141;143;139;117;169;119 05:14:47 INFO - PROCESS | 1669 | |2;audio-dft;184;183;186;182;183;183;182;185;184;207 05:14:47 INFO - PROCESS | 1669 | |3;audio-fft;77;96;82;136;58;73;79;80;110;55 05:14:47 INFO - PROCESS | 1669 | |4;audio-oscillator;92;100;134;80;84;78;102;81;78;100 05:14:47 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;88;84;87;83;87;83;88;84;87;84 05:14:47 INFO - PROCESS | 1669 | |6;imaging-darkroom;92;93;93;94;92;92;93;93;92;92 05:14:47 INFO - PROCESS | 1669 | |7;imaging-desaturate;83;84;83;85;84;84;83;83;83;86 05:14:47 INFO - PROCESS | 1669 | |8;json-parse-financial;58;58;60;58;58;59;57;120;57;57 05:14:47 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;63;62;75;72;68;65;64;91;90;81 05:14:47 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;86;88;96;168;173;172;174;85;83;81 05:14:47 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;140;139;141;145;145;130;134;142;276;145 05:14:47 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;167;168;169;168;168;168;169;167;166;166 05:14:47 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;69;68;68;67;68;66;67;65;66;65 05:14:47 INFO - PROCESS | 1669 | __end_tp_report 05:14:47 INFO - PROCESS | 1669 | __start_cc_report 05:14:47 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,2239 05:14:47 INFO - PROCESS | 1669 | __end_cc_report 05:14:47 INFO - PROCESS | 1669 | __startTimestamp1462796087114__endTimestamp 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | ------- Summary: start ------- 05:14:47 INFO - PROCESS | 1669 | Number of tests: 14 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:1.62 (1.4%) stddev-sans-first:1.66 05:14:47 INFO - PROCESS | 1669 | Values: 113.0 108.0 113.0 113.0 113.0 112.0 113.0 111.0 111.0 111.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:145.70 Median:142.50 stddev:29.10 (20.4%) stddev-sans-first:30.47 05:14:47 INFO - PROCESS | 1669 | Values: 159.0 116.0 142.0 212.0 141.0 143.0 139.0 117.0 169.0 119.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:185.90 Median:184.00 stddev:7.52 (4.1%) stddev-sans-first:7.94 05:14:47 INFO - PROCESS | 1669 | Values: 184.0 183.0 186.0 182.0 183.0 183.0 182.0 185.0 184.0 207.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:84.60 Median:78.00 stddev:24.16 (31.0%) stddev-sans-first:25.47 05:14:47 INFO - PROCESS | 1669 | Values: 77.0 96.0 82.0 136.0 58.0 73.0 79.0 80.0 110.0 55.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:79.00 stddev:17.34 (21.9%) stddev-sans-first:18.38 05:14:47 INFO - PROCESS | 1669 | Values: 92.0 100.0 134.0 80.0 84.0 78.0 102.0 81.0 78.0 100.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:87.00 stddev:2.07 (2.4%) stddev-sans-first:1.99 05:14:47 INFO - PROCESS | 1669 | Values: 88.0 84.0 87.0 83.0 87.0 83.0 88.0 84.0 87.0 84.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 05:14:47 INFO - PROCESS | 1669 | Values: 92.0 93.0 93.0 94.0 92.0 92.0 93.0 93.0 92.0 92.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:1.03 (1.2%) stddev-sans-first:1.05 05:14:47 INFO - PROCESS | 1669 | Values: 83.0 84.0 83.0 85.0 84.0 84.0 83.0 83.0 83.0 86.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:58.00 stddev:19.63 (33.8%) stddev-sans-first:20.69 05:14:47 INFO - PROCESS | 1669 | Values: 58.0 58.0 60.0 58.0 58.0 59.0 57.0 120.0 57.0 57.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:73.50 stddev:10.92 (14.9%) stddev-sans-first:10.95 05:14:47 INFO - PROCESS | 1669 | Values: 63.0 62.0 75.0 72.0 68.0 65.0 64.0 91.0 90.0 81.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:120.60 Median:84.00 stddev:44.22 (52.6%) stddev-sans-first:45.10 05:14:47 INFO - PROCESS | 1669 | Values: 86.0 88.0 96.0 168.0 173.0 172.0 174.0 85.0 83.0 81.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:153.70 Median:143.50 stddev:43.25 (30.1%) stddev-sans-first:45.59 05:14:47 INFO - PROCESS | 1669 | Values: 140.0 139.0 141.0 145.0 145.0 130.0 134.0 142.0 276.0 145.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.60 Median:168.00 stddev:1.07 (0.6%) stddev-sans-first:1.12 05:14:47 INFO - PROCESS | 1669 | Values: 167.0 168.0 169.0 168.0 168.0 168.0 169.0 167.0 166.0 166.0 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.50 stddev:1.37 (2.0%) stddev-sans-first:1.22 05:14:47 INFO - PROCESS | 1669 | Values: 69.0 68.0 68.0 67.0 68.0 66.0 67.0 65.0 66.0 65.0 05:14:47 INFO - PROCESS | 1669 | -------- Summary: end -------- 05:14:47 INFO - PROCESS | 1669 | 05:14:47 INFO - TEST-INFO | 1669: exit 0 05:14:47 INFO - TEST-OK | kraken | took 60999ms 05:14:47 INFO - SUITE-END | took 1177s 05:14:48 INFO - Completed test suite (00:19:38) 05:14:48 INFO - javascript benchmark 05:14:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3442.913927151821, 3457.0665920201545, 3476.217850983528, 3485.477178423235, 3519.8944031679057, 4225.640820530255, 5576.219329293902, 5672.943270567293, 5702.344230413503, 5867.1492633568105, 5714.657120572769, 5761.107028410593, 5773.451522105393, 5777.682227477487, 5813.360530341659, 5234.895302093956, 5277.050130976423, 5280.313559237294, 5301.787928482875, 5367.865803354908, 4735.431748190204, 5130.179171332587, 5254.264402983586, 5385.7576409061685, 5457.590680698963, 5246.580273578104, 5292.10034294169, 5307.097793375136, 5308.522232999029, 5342.599305052117, 5334.146536554133, 5340.519353258186, 5355.437679043684, 5369.570434365241, 5374.811881584125, 5178.508041736029, 5188.740562971836, 5192.844214673566, 5268.736563171826, 5287.656302340374, 5574.693391863446, 5612.438756124396, 5642.463965923272, 5650.943490565124, 5693.089105743061, 6218.626882387026, 6270.3416141305415, 6277.340879207663, 6299.0, 6304.117423560705, 6156.907646385308, 6173.598716083485, 6214.65819379934, 6228.657423841687, 6231.626102433868, 6010.338862725053, 6041.365656606081, 6045.939540604626, 6064.575479716377, 6083.087536869505, 5218.425973142951, 6212.0, 6316.115743795872, 6338.841528961812, 6378.872422551616, 6094.786682466092, 6105.8843398869885, 6158.876822463526, 6232.25212974446, 6240.4071613196675, 6226.1905952226825, 6230.59501132425, 6235.095911092866, 6246.46905013079, 6249.312575616678, 6128.295246046761, 6228.439440450381, 6229.470495007885, 6233.065040243909, 6252.812415627538, 6151.938480615226, 6159.384061593805, 6193.783217587363, 6218.19163508741, 6246.625202487865, 4956.033573453215, 4990.6506544541535, 5005.224190250517, 5009.348784657971, 5011.273365362002, 3923.2938071146896, 4475.753833539186, 4483.394741709827, 4543.727376357363, 4572.497025327211, 5866.97327967604, 5896.882062358729, 5899.882002359929, 5906.2912450505355, 5913.70431478433, 6121.296050954197, 6190.0714892765545, 6270.184875966095, 6272.968635156886, 6287.528435367366, 5801.274840644919, 5825.359210486928, 5877.853053673693, 5901.645901245939, 5913.526917846562, 5497.092979658391, 5504.366997795225, 5504.6421982571, 5506.779728810803, 5508.366537848118, 6201.472874805692, 6431.292557818636, 6469.967650161719, 6504.544681872224, 6521.576097553644, 6178.752849885954, 6182.319944805978, 6206.472449841814, 6306.873862522815, 6319.96840015797, 6482.222133344029, 6494.123293355526, 6502.414782669583, 6502.967485162733, 6517.804465866032, 6120.17377654011, 6123.1606109212, 6154.138420621206, 6178.042403427476, 6199.56603037783, 6104.459155447048, 6125.388069147376, 6155.6614386209185, 6234.3453937337745, 6238.438540531374, 6014.939850601613, 6099.908501372394, 6103.664298463627, 6111.291086246819, 6137.60105593135, 5066.594672426123, 6048.5161187105805, 6088.939110608837, 6096.237970253718, 6113.052476865915, 5999.070144127667, 6067.211262535842, 6068.605540639845, 6081.802781075923, 6122.785702500391, 5987.595775214344, 6123.120160253514, 6188.566800323934, 6190.164327815687, 6208.689565521796, 5869.090291004901, 5903.527717782653, 6008.7296071676, 6015.368386319548, 6082.939170608414], "value": 5736.931983808841, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.63776262209893, 523.277876530388, 524.412657823238, 532.252185679121, 560.529155509372, 30631.846840765793, 31956.881509147184, 42709.57290427095, 43957.56042439575, 44023.67928962128, 44381.77809110958, 44714.0, 44841.55158448414, 44861.55138448615, 45103.77448112755, 9683.225341972642, 9878.654247101353, 9883.209343252522, 9897.208223342115, 9920.0, 8678.349123815708, 8892.0, 10339.534720937576, 12023.458944347482, 12024.218425802295, 11908.34504102274, 11956.282623042602, 11977.281363118198, 11991.0, 11997.760044799099, 8686.435381700168, 8900.02099769025, 11681.007114395286, 11757.941210293979, 11780.764384712344, 11449.828252576219, 11826.586069487526, 11844.763104737944, 11922.642320730392, 11948.940255298668, 41373.58626413758, 41649.583504164875, 41676.58323416757, 41701.79149104235, 41754.0, 3452.7583069185166, 3827.9617203827884, 3868.1490072184074, 3946.565877753445, 3979.1047014421524, 28960.710392896013, 29939.25151871199, 30246.84876575625, 30519.542206866914, 30540.8472957636, 2753.559430491112, 2753.6695596528543, 2764.6380729021776, 2778.166550035002, 2826.349939513903, 455863.0, 455865.0, 456864.0, 467564.66217669036, 469603.0, 35582.288354233286, 36441.81779091088, 36450.635493645255, 63057.0, 63821.0, 37673.0, 37771.0, 37826.62173378231, 37842.0, 37950.620493795264, 15330.846691532943, 15486.0, 15490.9225453872, 15491.993020453634, 15552.92223538875, 11892.702682433008, 12147.271163730204, 12743.553975610808, 12840.871591284154, 12888.80666789999, 8256.0, 8304.252617264476, 8336.458130221637, 8383.077861435237, 8409.747707568782, 2772.07135609569, 3390.9996551017507, 3462.082548124749, 3469.0, 3492.161881148506, 981.6711401680517, 1012.9594816207269, 1056.4928834159493, 1139.700741155084, 1192.6243233381458, 29348.266293342836, 29709.40581188364, 29912.953046643262, 30227.697723022928, 30394.0, 918.820829938169, 1022.3916769522123, 1120.2998126171144, 1132.6545403651965, 1144.5936692474218, 12939.900108490696, 14938.775918361232, 15317.923410382877, 15358.769618455732, 15658.68682626341, 645100.5489945135, 654666.0, 654805.0, 658338.0, 659076.0, 61758.382416175264, 61992.0, 62122.689386552775, 62184.0, 62275.68862155751, 1343.32162258061, 1448.86960173587, 1486.3534362552325, 1508.6228442889278, 1510.75072613022, 29083.41833163367, 30446.391072178874, 30604.540931885593, 30615.0, 31394.21514462157, 52645.7367713159, 53113.67215819636, 53271.4672853282, 53537.46462535325, 53551.464485356206, 494769.0, 514565.0, 515891.0, 517072.0, 517802.0, 23452.296431107094, 23965.400864978514, 24007.51984960326, 24631.38421539476, 27247.86376068107, 38685.61314386933, 46964.53035469738, 48359.5164048355, 50579.98840023105, 50913.23630145477, 38741.6125838738, 39046.023849403995, 39206.6079339203, 39623.207535848545, 39769.0, 37792.0, 37996.25878092018, 38266.426003610526, 38450.42324365193, 38942.41586376263, 37697.43453848249, 37759.86720398392, 38146.2370752589, 38340.61659383482, 38695.80652096721, 32813.67186328106, 32884.01347959565, 33642.158946026546, 33708.157296067795, 33718.0, 24555.649439281013, 24602.630960535964, 24785.653936719034, 25178.62232066484, 25434.982600695766], "value": 19022.821393882623, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.29693956998256, 386.43773309834194, 389.682408836798, 394.7434167790937, 414.85687437833957, 12047.956167826946, 19859.801401985977, 20128.09423575939, 21078.367648970536, 21099.789002109992, 19911.30310439135, 20506.38480845577, 20984.790152098474, 20993.58012839742, 21000.05499752511, 3760.717946154036, 5416.1063424534905, 5433.510984011438, 5450.890982180354, 5463.972680136594, 5265.131253343194, 5371.704556249405, 5480.095784195603, 6157.076438534211, 6160.599561028519, 5315.830517286191, 6194.132821405007, 6203.193584833988, 6221.253449586056, 6227.532935029868, 5889.793857214999, 6092.11664308675, 6135.509159267248, 6137.846553836145, 6138.109974053782, 5762.510186634141, 5793.2179155814265, 5967.373425790273, 5991.161237426763, 6000.549958753111, 13595.98030147733, 13911.443542258297, 14166.716665666732, 14226.85773142276, 14277.143371397646, 2136.5192831612762, 2149.8495105342477, 2169.457635591102, 2180.6292930201744, 2356.2931120663734, 9945.850812237823, 10103.141232995204, 10137.290389672726, 10449.582016719398, 10590.36457812526, 1706.5221737913405, 1725.9050752208623, 1795.6588248232795, 1811.4475085099039, 1945.4066509714532, 110820.0, 115948.0, 116996.0, 117278.41360793226, 119297.40351298275, 9691.370060946158, 11643.243189192675, 11716.589919352782, 11770.3526306054, 11871.64385068432, 13029.631888651744, 13555.525556605471, 14261.572152835432, 14273.78589321161, 15309.923450382677, 7145.535540189871, 7588.089429268523, 8063.435559510778, 8677.002144753307, 8798.0, 8446.788830279169, 8519.829603407898, 8566.3146948244, 8683.696070637623, 8713.651453941771, 4552.294394368878, 4632.72203667781, 4679.64902632304, 4763.237881938873, 4774.976125119421, 1771.8757957346193, 1799.1364145210373, 1855.3413538194018, 1983.226541648758, 1994.5512259741731, 930.6603089872148, 932.4125800745487, 963.7542426681151, 991.8115558043949, 1033.7646512417589, 18445.354412595763, 20407.81724521398, 22004.669929951062, 22101.0, 22197.889010554845, 894.2756367342473, 927.221134247222, 1040.7554224757175, 1056.8097742406287, 1063.872335319751, 9764.0, 10884.292520986111, 10896.019358257794, 10899.29154604948, 10948.945255273673, 619647.9017604883, 622737.0, 626274.0, 642816.0, 645129.0, 130024.69975300315, 130627.0, 131402.0, 146397.0, 151901.86249987685, 1057.117307048623, 1067.8505009298703, 1088.8911108889047, 1088.9945550272357, 1090.2858627598778, 13823.202983612066, 16421.835781642032, 17632.471025869243, 17639.559011024827, 17810.376636817648, 52042.47957520376, 53771.73114134404, 53951.0, 54036.18945715739, 54157.0, 82521.0, 83616.0, 84899.5755021221, 86500.0, 87819.56090219508, 8036.839263214586, 8060.919390806017, 8107.594620268845, 8297.377696672895, 8523.831836262614, 8285.62714677853, 9093.272538196796, 11539.24994875364, 12292.770722927993, 12645.87354126447, 10234.590616375559, 10694.0, 10757.031867131996, 10876.564937402733, 11040.00639942409, 9190.540472976458, 9369.437833729999, 10117.140043096419, 10432.374057556572, 10742.785144297226, 10607.575696972035, 10727.302725322828, 11004.0, 11179.0497807684, 11243.55025798959, 8120.309773669304, 8294.543800091053, 8298.958505207434, 8350.457220280663, 8544.273736732448, 8297.792555186168, 10874.184436167478, 10889.440981369213, 11019.118470522179, 11180.329180249211], "value": 9344.142920230754, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.191439922045, 1582.5568840724598, 1610.524895155929, 1839.5217243516681, 1977.090538352358, 7639.121501027376, 8438.409311348209, 8692.869606955901, 8694.043655197924, 8817.735467935956, 8459.957700211491, 8687.609057592408, 8700.26047785939, 8702.782430439227, 8704.390692651517, 2098.9475263118393, 2334.8482348647367, 2390.8565486070893, 2420.479596886669, 2420.7942324902406, 2446.645236440715, 2466.8766561671846, 2467.962980555293, 2470.8641024743633, 2474.826762126652, 2372.9881350593223, 2428.0, 2431.465077682907, 2431.744666809986, 2434.075051480435, 2102.9684554731616, 2315.3285547191294, 2365.66880636711, 2391.1272385579136, 2391.426057746146, 2163.945901352463, 2420.346506443268, 2437.4035007070397, 2439.0365805506904, 2441.926742197737, 8597.957010214972, 8667.133286671346, 8678.175573320525, 8679.392442529026, 8687.695930642398, 4318.762468064255, 5102.081625307444, 5255.632105752599, 5270.314859068297, 5296.0, 3401.064707205538, 3468.289000754839, 3522.6301238370115, 3532.5760908690863, 3549.165946002687, 1016.5882817458867, 1048.1405247696891, 1048.2505008918583, 1050.2228351020267, 1063.9946800266027, 11648.88351116495, 11682.941585292105, 11685.12361572877, 11693.70765730855, 11763.294202347886, 4697.769529675788, 4838.467768545457, 4853.150698627796, 4854.490278520775, 4904.852854414374, 9732.902670973199, 9846.852297215548, 9856.50717464124, 9863.506824658738, 9870.703878883645, 5308.23030660552, 5315.707636080053, 5353.277307563507, 5358.089124848785, 5360.785568577213, 4339.370791235317, 4539.477960034638, 4708.95291047092, 4838.371011768518, 4852.660313778072, 3937.89738873116, 3955.0310174006977, 4044.2518134145275, 4083.97958010214, 4084.8774536763945, 1299.493197652916, 1310.4692599497218, 1313.481174935895, 1327.734453109382, 1342.3020029584757, 837.8408102460513, 843.9915600843913, 855.461059532503, 935.335911502827, 938.3666025432806, 4687.695299805502, 5852.268466441695, 6025.758969641165, 6054.122152287981, 6148.292946311231, 974.2303580171586, 985.7486340983004, 994.4977786218059, 999.6001599360169, 1041.1722680469045, 3931.331673615492, 3962.4848769660334, 3994.460747799068, 4070.8778736637946, 4071.2875246831686, 10245.897541024495, 10504.632337868137, 10727.83908241377, 10745.731356715989, 10751.462426878781, 10540.947295263475, 10605.893941060645, 10679.412632305297, 10690.78618427627, 10704.94647526773, 7663.850422436679, 7997.720079797179, 8139.982502187227, 8145.266925976691, 8148.185181481805, 5917.408259174048, 5959.821205363846, 6088.8173354797655, 6095.207623008981, 6225.3774622537385, 9518.095780900803, 9790.706278811647, 9901.306908516335, 9968.451735154636, 10043.799124017332, 9774.706758797247, 10018.499075046364, 10077.445740484343, 10159.187265018632, 10172.237082218715, 6086.573939824347, 6769.221539522821, 7006.159260888522, 7208.56748595086, 7544.849103017799, 8126.837463250584, 8303.792405189919, 8316.126806685212, 8330.083690793886, 8381.790455238668, 7272.490925635155, 7315.48791584584, 7368.263173682589, 7485.775426736989, 7511.286427789351, 7156.284371563011, 7201.783946481614, 7248.166460857069, 7282.526635768658, 7322.157951835394, 6876.17485901695, 6944.375006249462, 6976.895346569704, 6986.54587451814, 6987.266337034742, 4316.978415107905, 4855.684380515255, 4866.318715379921, 4895.510448955076, 5020.146575082172, 4952.950470495249, 4994.650374473752, 5023.497650235094, 5048.772805223847, 5251.317328747238], "value": 4582.420572722267, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.7559183347237, 308.6620150934727, 316.9809811411314, 323.39040907888636, 325.8142858570613, 4501.144782491328, 4701.435827700672, 5261.8421447356595, 5294.338207724035, 5299.60252981026, 4434.290513517838, 5231.816886408977, 5248.868778280545, 5266.420693723697, 5267.341582302212, 3785.5268091488497, 3900.648941595256, 3977.2840888640035, 4051.6758659307184, 4054.432379466877, 3387.6104248011425, 3964.841406343757, 3999.4800675912093, 4036.293648611496, 4058.391241313812, 3763.3253202324977, 3831.63599458051, 3841.308564458396, 4020.4722205561993, 4027.0, 3812.866549670762, 3823.751456155369, 4009.939850902239, 4023.229778897276, 4037.5356833963883, 3509.2630547585036, 3518.964810351915, 3519.4720791881423, 3533.049903330722, 3538.876139335111, 4989.775460104268, 5010.423801262865, 5018.849434516934, 5020.815945063909, 5054.393472783289, 4470.776461176961, 4602.424696912886, 4642.050338857688, 4661.813527458898, 4670.673052886299, 4608.746518941491, 4731.0, 5364.0, 5507.504324610804, 5555.638883472561, 3485.9542137358944, 3539.5398598182073, 3906.921861562753, 3917.157811070605, 3987.9202415951518, 5373.865653358658, 5380.515753582196, 5616.9608622404985, 5619.904118696856, 5751.683657398841, 3509.5262139611336, 3546.840392182358, 3603.5315408997185, 3762.8494860205287, 3926.8821935341984, 3648.890533284006, 3843.808419389998, 4028.798560072043, 4030.334994725896, 4033.9140557473556, 4387.758673272937, 4609.5620916013595, 4620.4686461056735, 4627.583517483443, 4629.606483448944, 5095.00647373766, 5356.437574054746, 5482.533984611273, 5525.640833345819, 5575.972120139373, 1098.6429410441638, 1238.8699186585459, 1295.3393769177599, 1300.518808041031, 1329.5080820096432, 805.3074356053789, 819.9918000820035, 844.8014716541608, 861.5950503263456, 908.3550679017972, 4669.813207471731, 4766.213574760126, 4934.0149209728315, 4993.450720420751, 5133.820316288987, 5089.440161582223, 5669.773209071591, 5713.600047996683, 5756.597038207286, 5803.88392232153, 4570.657200709894, 4646.535346465326, 4971.246708734882, 5124.333836601218, 5131.692098474104, 2470.71586767524, 2822.717728227161, 2848.017433985272, 2855.2290881462304, 2877.5395936650452, 5422.728863556807, 5434.347878254555, 5633.2395126657375, 5645.717714114277, 5677.489025987681, 4868.71170549841, 4886.438059623118, 5285.312909321764, 5454.809081682201, 5534.861628459321, 5117.667351622207, 5123.795048198031, 5272.604554658416, 5613.996511866214, 5639.943600564024, 4915.574483399774, 5083.339165908556, 5230.084735171406, 5278.9472105278455, 5324.760385782572, 5112.335396398445, 5332.1202001670845, 5359.3032905722, 5388.54197393226, 5415.241866138665, 5389.359213985041, 5556.416576259435, 5630.915536266877, 5663.85840353978, 5675.7445914933105, 3425.0373426985975, 3529.6823285904393, 3632.6004139545157, 3758.285925674113, 3764.247150569952, 4643.791029403753, 4826.782794774173, 4982.5764809990105, 5135.178371460548, 5226.738663066907, 4528.958339581849, 4776.450708168605, 5174.395937259462, 5174.767135478988, 5208.187578707456, 4734.526547345238, 4814.422269327563, 5138.614603904647, 5212.843614691566, 5239.449857765032, 4946.505349465025, 5395.273512476127, 5419.485148910847, 5433.750237445331, 5442.292501974718, 4579.74811385377, 5282.97358513205, 5398.811041613525, 5548.361938377138, 5575.665460072409, 4654.650901182493, 5085.830259040367, 5431.782728690808, 5443.646162999393, 5450.182472629137], "value": 3970.3281469263484, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.1563578431128, 739.2755100001998, 751.3012898004853, 751.5415596486142, 757.8219118507153, 14896.77654835177, 15309.540713778577, 15321.846781532195, 15339.769903451457, 15372.077675339488, 13911.930440347784, 13942.930285348584, 14023.088499247542, 14029.579112626612, 14034.157950522977, 704.7286794584096, 717.7236763845905, 889.902110767815, 892.6697122064829, 905.5608030105394, 906.650939388337, 913.8811954445913, 918.8897332320131, 919.995400022999, 921.4010892919622, 977.330528587916, 977.9364341317792, 979.8775153105862, 980.9460479673616, 984.3700031979539, 756.9962150189269, 935.625749700122, 951.923846092311, 955.3933252384717, 969.142309056483, 614.5759425996048, 724.7572063358781, 724.9746258880966, 729.770122411444, 733.9853202935911, 14600.050996685288, 14633.634159146106, 14659.486917957927, 14695.412183512646, 14700.970932034761, 11987.220830646067, 12125.27248365092, 12180.086493512934, 12321.445534950948, 12431.902919802837, 10541.68374948745, 10616.363018218854, 10769.730756731065, 10952.561897524089, 11014.559417623286, 3939.7833119178435, 3982.203559288125, 4033.253848038122, 4054.736442131252, 4120.587941205886, 76693.0, 76797.0, 79009.20990790133, 80953.59523202405, 81676.0, 7692.346150577264, 9441.811163776823, 9441.952790236142, 9512.76562004448, 9733.659321923698, 21236.0, 21239.044243009103, 21254.78745212528, 21298.36104916855, 21338.14647414117, 6318.684065796784, 7478.925210747823, 7539.700391380572, 7672.271134242238, 7823.413244006609, 4030.717849750548, 4116.217918595517, 4120.464339635888, 4125.566815484331, 4134.421181034658, 3549.8757543485854, 3642.508261384732, 3644.85420583179, 3650.142216579102, 3652.543432070991, 518.3675915383227, 599.6342231238941, 615.7352338494491, 617.561531312773, 632.225523733432, 532.6058716549725, 547.9616426850082, 567.7757285872058, 595.8659301657093, 614.532954954229, 7887.369010479148, 8033.357331413473, 8102.635381407851, 8159.836803263902, 8359.916400835913, 557.9023670857583, 584.2288179602883, 594.669958173218, 595.961262517935, 607.1136141233848, 3751.5498140222803, 3841.753956648447, 3844.9038774030314, 3896.668783153407, 3900.5124359455067, 63009.0, 72589.0, 90452.0, 90575.54712226396, 91041.54479227561, 78613.0, 79713.60143199247, 80238.59880700559, 80474.79287810688, 81511.0, 71298.57402851914, 71353.0, 71430.0, 71585.64207179035, 71629.64185179041, 7400.296971787671, 7402.777916662509, 7454.55272683619, 7508.511946723445, 7607.771766847003, 42905.57094429016, 42976.0, 43611.78194109136, 43684.0, 44863.32705009493, 56823.45412366918, 62369.68815155895, 62478.0, 62888.37111629008, 63693.68153159205, 8927.24118449939, 9680.709578712649, 9823.459709716035, 10255.94872025635, 10346.430946298025, 9617.471039092918, 9988.850167247352, 11071.446427678746, 11157.609483668033, 11558.19092663539, 8656.740297791077, 9824.164945979672, 10122.44326562046, 10498.370097794157, 10505.579776808841, 10190.133838623504, 10432.478376081013, 10510.894891051297, 10526.842097368699, 10624.575016999233, 10003.899961000589, 10212.540435680263, 10309.175265978854, 10391.116755075904, 10451.059404653313, 8405.327573794204, 8523.232909038215, 8530.616122274389, 8785.824283514421, 8870.645174193218, 8630.266427353745, 8645.610947507252, 8723.869141963003, 8740.038595754335, 8988.0], "value": 6681.238057613778, "unit": "score"}], "name": "dromaeo_css", "value": 7061.325905515641, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 113.0, 113.0, 113.0, 112.0, 113.0, 111.0, 111.0, 111.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 116.0, 142.0, 212.0, 141.0, 143.0, 139.0, 117.0, 169.0, 119.0], "value": 145.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 186.0, 182.0, 183.0, 183.0, 182.0, 185.0, 184.0, 207.0], "value": 185.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 96.0, 82.0, 136.0, 58.0, 73.0, 79.0, 80.0, 110.0, 55.0], "value": 84.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 100.0, 134.0, 80.0, 84.0, 78.0, 102.0, 81.0, 78.0, 100.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 83.0, 87.0, 83.0, 88.0, 84.0, 87.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 92.0, 92.0, 93.0, 93.0, 92.0, 92.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 85.0, 84.0, 84.0, 83.0, 83.0, 83.0, 86.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 60.0, 58.0, 58.0, 59.0, 57.0, 120.0, 57.0, 57.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 75.0, 72.0, 68.0, 65.0, 64.0, 91.0, 90.0, 81.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 96.0, 168.0, 173.0, 172.0, 174.0, 85.0, 83.0, 81.0], "value": 120.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 141.0, 145.0, 145.0, 130.0, 134.0, 142.0, 276.0, 145.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 169.0, 168.0, 168.0, 168.0, 169.0, 167.0, 166.0, 166.0], "value": 167.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 67.0, 68.0, 66.0, 67.0, 65.0, 66.0, 65.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1528.9, "alertThreshold": 2.0}]} 05:14:48 INFO - Return code: 0 05:14:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:14:48 ERROR - # TBPL SUCCESS # 05:14:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:14:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:14:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:14:48 INFO - 2016-05-09 05:14:48.442 screenresolution[1673:9860] starting screenresolution argv=screenresolution get 05:14:48 INFO - 2016-05-09 05:14:48.446 screenresolution[1673:9860] Display 0: 1600x1200x32@60 05:14:48 INFO - 2016-05-09 05:14:48.451 screenresolution[1674:9864] starting screenresolution argv=screenresolution list 05:14:48 INFO - Available Modes on Display 0 05:14:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:14:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:14:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:14:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:14:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:14:48 INFO - Intel Iris: 05:14:48 INFO - Chipset Model: Intel Iris 05:14:48 INFO - Type: GPU 05:14:48 INFO - Bus: Built-In 05:14:48 INFO - VRAM (Dynamic, Max): 1536 MB 05:14:48 INFO - Vendor: Intel (0x8086) 05:14:48 INFO - Device ID: 0x0a2e 05:14:48 INFO - Revision ID: 0x0009 05:14:48 INFO - Displays: 05:14:48 INFO - Display: 05:14:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:14:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:14:48 INFO - Display Serial Number: 1600x1200 60 05:14:48 INFO - Main Display: Yes 05:14:48 INFO - Mirror: Off 05:14:48 INFO - Online: Yes 05:14:48 INFO - Rotation: Supported 05:14:48 INFO - Return code: 0 05:14:48 INFO - Running post-action listener: _resource_record_post_action 05:14:48 INFO - ##### 05:14:48 INFO - ##### Finished run-tests step (success) 05:14:48 INFO - ##### 05:14:48 INFO - Running post-run listener: _resource_record_post_run 05:14:49 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37484032; Write bytes: 695800832; Read time: 396; Write time: 1886 05:14:49 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 384446976; Write bytes: 378537984; Read time: 28400; Write time: 710 05:14:49 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35689984; Write bytes: 295808000; Read time: 377; Write time: 1133 05:14:49 INFO - Running post-run listener: _upload_blobber_files 05:14:49 INFO - Blob upload gear active. 05:14:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:14:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:14:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:14:50 INFO - (blobuploader) - INFO - Open directory for files ... 05:14:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:14:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:14:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:50 INFO - (blobuploader) - INFO - Done attempting. 05:14:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:14:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:14:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:52 INFO - (blobuploader) - INFO - Done attempting. 05:14:52 INFO - (blobuploader) - INFO - Iteration through files over. 05:14:52 INFO - Return code: 0 05:14:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:14:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:14:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5308288860dc15392d15c9fabea4744adbd27af847c56f3af8e47d4bf277032c8e3c9dfaa1fef6a0ab23853f645226304fbf8039fd56d24fd07e469029fb4ae7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/049c160b7ac06be0c46a236a26b578cf741b6b9a393881e0a678aa367b72b288c74e3f54c85675a6ff97eb2aeae23edd14d6f01ad816ed27806b50b86232da65"} 05:14:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:14:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:14:52 INFO - Contents: 05:14:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5308288860dc15392d15c9fabea4744adbd27af847c56f3af8e47d4bf277032c8e3c9dfaa1fef6a0ab23853f645226304fbf8039fd56d24fd07e469029fb4ae7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/049c160b7ac06be0c46a236a26b578cf741b6b9a393881e0a678aa367b72b288c74e3f54c85675a6ff97eb2aeae23edd14d6f01ad816ed27806b50b86232da65"} 05:14:52 INFO - Running post-run listener: copy_logs_to_upload_dir 05:14:52 INFO - Copying logs to upload dir... 05:14:52 INFO - mkdir: /builds/slave/test/build/upload/logs 05:14:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.000592 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-09 05:14:52.393446) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 05:14:52.397493) ========= 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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5308288860dc15392d15c9fabea4744adbd27af847c56f3af8e47d4bf277032c8e3c9dfaa1fef6a0ab23853f645226304fbf8039fd56d24fd07e469029fb4ae7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/049c160b7ac06be0c46a236a26b578cf741b6b9a393881e0a678aa367b72b288c74e3f54c85675a6ff97eb2aeae23edd14d6f01ad816ed27806b50b86232da65"} build_url:https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011364 build_url: 'https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5308288860dc15392d15c9fabea4744adbd27af847c56f3af8e47d4bf277032c8e3c9dfaa1fef6a0ab23853f645226304fbf8039fd56d24fd07e469029fb4ae7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/049c160b7ac06be0c46a236a26b578cf741b6b9a393881e0a678aa367b72b288c74e3f54c85675a6ff97eb2aeae23edd14d6f01ad816ed27806b50b86232da65"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 05:14:52.426065) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:14:52.426418) ========= 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.Ddl8lCrUqv/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.UG8Mz3GfYF/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.004679 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:14:52.474663) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 05:14:52.475045) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 05:14:52.475828) ========= ========= Total master_lag: 0.16 =========