builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0169 starttime: 1462794763.17 results: success (0) buildid: 20160509024751 builduid: 991189a231734b41ad920f1637f0b2f5 revision: 7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:43.168182) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:43.168733) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:43.169105) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:43.188639) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:43.188984) ========= 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.VoyLUF0NxP/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.SKkyZz59yK/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:52:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 31.5M=0s 2016-05-09 04:52:43 (31.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101787 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:43.308130) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:43.308578) ========= 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.VoyLUF0NxP/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.SKkyZz59yK/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.019809 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:43.357601) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 04:52:43.357980) ========= 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.VoyLUF0NxP/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.SKkyZz59yK/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:52:43,429 truncating revision to first 12 chars 2016-05-09 04:52:43,429 Setting DEBUG logging. 2016-05-09 04:52:43,429 attempt 1/10 2016-05-09 04:52:43,430 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:52:44,544 unpacking tar archive at: mozilla-central-7adbdf0fce9a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.357528 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 04:52:44.733553) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:44.734093) ========= script_repo_revision: 7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:44.734597) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:44.734929) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:52:44.748514) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-09 04:52:44.748847) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.VoyLUF0NxP/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.SKkyZz59yK/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:52:44 INFO - MultiFileLogger online at 20160509 04:52:44 in /builds/slave/test 04:52:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:52:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:52:44 INFO - {'append_to_log': False, 04:52:44 INFO - 'base_work_dir': '/builds/slave/test', 04:52:44 INFO - 'blob_upload_branch': 'Firefox', 04:52:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:52:44 INFO - 'branch': 'Firefox', 04:52:44 INFO - 'buildbot_json_path': 'buildprops.json', 04:52:44 INFO - 'config_files': ('talos/mac_config.py',), 04:52:44 INFO - 'default_actions': ('clobber', 04:52:44 INFO - 'read-buildbot-config', 04:52:44 INFO - 'download-and-extract', 04:52:44 INFO - 'populate-webroot', 04:52:44 INFO - 'create-virtualenv', 04:52:44 INFO - 'install', 04:52:44 INFO - 'run-tests'), 04:52:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:52:44 INFO - 'download_minidump_stackwalk': True, 04:52:44 INFO - 'download_symbols': 'ondemand', 04:52:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:52:44 INFO - 'tooltool.py': '/tools/tooltool.py', 04:52:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:52:44 INFO - '/tools/misc-python/virtualenv.py')}, 04:52:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:52:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:52:44 INFO - 'installer_path': 'installer.exe', 04:52:44 INFO - 'log_level': 'info', 04:52:44 INFO - 'log_name': 'talos', 04:52:44 INFO - 'log_to_console': True, 04:52:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:52:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:52:44 INFO - 'opt_config_files': (), 04:52:44 INFO - 'pip_index': False, 04:52:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:52:44 INFO - 'cmd': ('bash', 04:52:44 INFO - '-c', 04:52:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:52:44 INFO - 'enabled': True, 04:52:44 INFO - 'halt_on_failure': False, 04:52:44 INFO - 'name': 'check_screen_resolution'},), 04:52:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:52:44 INFO - 'cmd': ('bash', 04:52:44 INFO - '-c', 04:52:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:52:44 INFO - 'enabled': True, 04:52:44 INFO - 'halt_on_failure': False, 04:52:44 INFO - 'name': 'check_screen_resolution'},), 04:52:44 INFO - 'run_cmd_checks_enabled': True, 04:52:44 INFO - 'sps_profile': False, 04:52:44 INFO - 'sps_profile_interval': 0, 04:52:44 INFO - 'suite': 'dromaeojs-e10s', 04:52:44 INFO - 'system_bits': '32', 04:52:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:52:44 INFO - 'title': 't-yosemite-r7-0169', 04:52:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:52:44 INFO - 'use_talos_json': True, 04:52:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:52:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:52:44 INFO - 'work_dir': 'build'} 04:52:44 INFO - ##### 04:52:44 INFO - ##### Running clobber step. 04:52:44 INFO - ##### 04:52:44 INFO - Running pre-action listener: _resource_record_pre_action 04:52:44 INFO - Running main action method: clobber 04:52:44 INFO - rmtree: /builds/slave/test/build 04:52:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:52:45 INFO - Running post-action listener: _resource_record_post_action 04:52:45 INFO - ##### 04:52:45 INFO - ##### Finished clobber step (success) 04:52:45 INFO - ##### 04:52:45 INFO - ##### 04:52:45 INFO - ##### Running read-buildbot-config step. 04:52:45 INFO - ##### 04:52:45 INFO - Running pre-action listener: _resource_record_pre_action 04:52:45 INFO - Running main action method: read_buildbot_config 04:52:45 INFO - Using buildbot properties: 04:52:45 INFO - { 04:52:45 INFO - "project": "", 04:52:45 INFO - "product": "firefox", 04:52:45 INFO - "script_repo_revision": "production", 04:52:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:52:45 INFO - "repository": "", 04:52:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:52:45 INFO - "buildid": "20160509024751", 04:52:45 INFO - "pgo_build": "False", 04:52:45 INFO - "basedir": "/builds/slave/test", 04:52:45 INFO - "buildnumber": 268, 04:52:45 INFO - "slavename": "t-yosemite-r7-0169", 04:52:45 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:52:45 INFO - "platform": "yosemite_r7", 04:52:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:52:45 INFO - "branch": "mozilla-central", 04:52:45 INFO - "repo_path": "mozilla-central", 04:52:45 INFO - "revision": "7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce", 04:52:45 INFO - "stage_platform": "macosx64", 04:52:45 INFO - "builduid": "991189a231734b41ad920f1637f0b2f5", 04:52:45 INFO - "slavebuilddir": "test" 04:52:45 INFO - } 04:52:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:52:45 INFO - Running post-action listener: _resource_record_post_action 04:52:45 INFO - ##### 04:52:45 INFO - ##### Finished read-buildbot-config step (success) 04:52:45 INFO - ##### 04:52:45 INFO - ##### 04:52:45 INFO - ##### Running download-and-extract step. 04:52:45 INFO - ##### 04:52:45 INFO - Running pre-action listener: _resource_record_pre_action 04:52:45 INFO - Running main action method: download_and_extract 04:52:45 INFO - mkdir: /builds/slave/test/build/tests 04:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:45 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:52:45 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:52:45 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:52:45 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:52:45 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:52:47 INFO - Downloaded 1199 bytes. 04:52:47 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:52:47 INFO - Using the following test package requirements: 04:52:47 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:52:47 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:52:47 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:52:47 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:52:47 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:52:47 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:52:47 INFO - u'jsshell-mac.zip'], 04:52:47 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:52:47 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:52:47 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:52:47 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:52:47 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:52:47 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:52:47 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:52:47 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:52:47 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:52:47 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:52:47 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:52:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:47 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:52:47 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:52:47 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:52:47 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:52:47 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:52:54 INFO - Downloaded 18146557 bytes. 04:52:54 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:52:54 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:52:58 INFO - Return code: 0 04:52:58 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:52:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:52:58 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:52:58 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:52:58 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:52:58 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:52:58 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:00 INFO - Downloaded 18146557 bytes. 04:53:00 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:00 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:03 INFO - Return code: 0 04:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:03 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:03 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:03 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:03 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:03 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:04 INFO - Downloaded 10840152 bytes. 04:53:04 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:04 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:05 INFO - Return code: 0 04:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:05 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:05 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:05 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:05 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:05 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:13 INFO - Downloaded 110435607 bytes. 04:53:13 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:13 INFO - mkdir: /builds/slave/test/properties 04:53:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:53:13 INFO - Writing to file /builds/slave/test/properties/build_url 04:53:13 INFO - Contents: 04:53:13 INFO - build_url:https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:53:14 INFO - Running post-action listener: _resource_record_post_action 04:53:14 INFO - Running post-action listener: set_extra_try_arguments 04:53:14 INFO - ##### 04:53:14 INFO - ##### Finished download-and-extract step (success) 04:53:14 INFO - ##### 04:53:14 INFO - ##### 04:53:14 INFO - ##### Running populate-webroot step. 04:53:14 INFO - ##### 04:53:14 INFO - Running pre-action listener: _resource_record_pre_action 04:53:14 INFO - Running main action method: populate_webroot 04:53:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:53:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:53:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:53:14 INFO - '--tppagecycles', 04:53:14 INFO - '7'], 04:53:14 INFO - 'tests': ['tsvgm']}}, 04:53:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:53:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:53:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:53:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:53:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:53:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:53:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:14 INFO - 'talos_options': ['--disable-e10s'], 04:53:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:53:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:53:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:14 INFO - 'talos_options': ['--disable-e10s'], 04:53:14 INFO - 'tests': ['damp', 'tps']}, 04:53:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:14 INFO - 'tests': ['damp', 'tps']}, 04:53:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:53:14 INFO - 'tests': ['dromaeo_dom']}, 04:53:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:53:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:53:14 INFO - 'tests': ['a11yr', 04:53:14 INFO - 'ts_paint', 04:53:14 INFO - 'tpaint', 04:53:14 INFO - 'sessionrestore', 04:53:14 INFO - 'sessionrestore_no_auto_restore', 04:53:14 INFO - 'tabpaint']}, 04:53:14 INFO - 'other-e10s': {'tests': ['a11yr', 04:53:14 INFO - 'ts_paint', 04:53:14 INFO - 'tpaint', 04:53:14 INFO - 'sessionrestore', 04:53:14 INFO - 'sessionrestore_no_auto_restore', 04:53:14 INFO - 'tabpaint']}, 04:53:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:53:14 INFO - 'ts_paint', 04:53:14 INFO - 'tpaint', 04:53:14 INFO - 'sessionrestore', 04:53:14 INFO - 'sessionrestore_no_auto_restore', 04:53:14 INFO - 'tabpaint']}, 04:53:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:53:14 INFO - 'ts_paint', 04:53:14 INFO - 'tpaint', 04:53:14 INFO - 'sessionrestore', 04:53:14 INFO - 'sessionrestore_no_auto_restore', 04:53:14 INFO - 'tabpaint']}, 04:53:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:53:14 INFO - 'tests': ['a11yr', 04:53:14 INFO - 'ts_paint', 04:53:14 INFO - 'tpaint', 04:53:14 INFO - 'sessionrestore', 04:53:14 INFO - 'sessionrestore_no_auto_restore', 04:53:14 INFO - 'tabpaint']}, 04:53:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:53:14 INFO - 'tests': ['a11yr', 04:53:14 INFO - 'ts_paint', 04:53:14 INFO - 'tpaint', 04:53:14 INFO - 'sessionrestore', 04:53:14 INFO - 'sessionrestore_no_auto_restore', 04:53:14 INFO - 'tabpaint']}, 04:53:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:53:14 INFO - 'tests': ['tsvgx', 04:53:14 INFO - 'tsvgr_opacity', 04:53:14 INFO - 'tart', 04:53:14 INFO - 'tscrollx', 04:53:14 INFO - 'cart']}, 04:53:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:53:14 INFO - 'tsvgr_opacity', 04:53:14 INFO - 'tart', 04:53:14 INFO - 'tscrollx', 04:53:14 INFO - 'cart']}, 04:53:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:14 INFO - 'talos_options': ['--disable-e10s'], 04:53:14 INFO - 'tests': ['tp5o']}, 04:53:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:14 INFO - 'tests': ['tp5o']}, 04:53:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:14 INFO - 'talos_options': ['--disable-e10s', 04:53:14 INFO - '--xperf_path', 04:53:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:53:14 INFO - 'tests': ['tp5n']}, 04:53:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:53:14 INFO - 'talos_options': ['--xperf_path', 04:53:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:53:14 INFO - 'tests': ['tp5n']}}, 04:53:14 INFO - 'talos.zip': {'path': '', 04:53:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:53:14 INFO - Running post-action listener: _resource_record_post_action 04:53:14 INFO - ##### 04:53:14 INFO - ##### Finished populate-webroot step (success) 04:53:14 INFO - ##### 04:53:14 INFO - ##### 04:53:14 INFO - ##### Running create-virtualenv step. 04:53:14 INFO - ##### 04:53:14 INFO - Running pre-action listener: _resource_record_pre_action 04:53:14 INFO - Running main action method: create_virtualenv 04:53:14 INFO - Creating virtualenv /builds/slave/test/build/venv 04:53:14 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:53:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:53:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:53:14 INFO - Using real prefix '/tools/python27' 04:53:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:53:15 INFO - Installing distribute.............................................................................................................................................................................................done. 04:53:17 INFO - Installing pip.................done. 04:53:17 INFO - Return code: 0 04:53:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:53: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:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53: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:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:17 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 0x1072c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd17bd836a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716cd30>, '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 0x106f90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107163b20>, '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.VoyLUF0NxP/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.SKkyZz59yK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:53:17 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:53:17 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:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoyLUF0NxP/Render', 04:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:17 INFO - 'HOME': '/Users/cltbld', 04:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:17 INFO - 'LOGNAME': 'cltbld', 04:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:17 INFO - 'MOZ_NO_REMOTE': '1', 04:53:17 INFO - 'NO_EM_RESTART': '1', 04:53:17 INFO - 'PAGER': '/bin/cat', 04:53:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:17 INFO - 'PWD': '/builds/slave/test', 04:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:17 INFO - 'SHELL': '/bin/bash', 04:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SKkyZz59yK/Listeners', 04:53:17 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:17 INFO - 'USER': 'cltbld', 04:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:17 INFO - 'XPC_FLAGS': '0x0', 04:53:17 INFO - 'XPC_SERVICE_NAME': '0', 04:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:18 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:53:18 INFO - Cleaning up... 04:53:18 INFO - Return code: 0 04:53:18 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1072c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd17bd836a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716cd30>, '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 0x106f90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107163b20>, '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.VoyLUF0NxP/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.SKkyZz59yK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:53:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:53:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoyLUF0NxP/Render', 04:53:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:18 INFO - 'HOME': '/Users/cltbld', 04:53:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:18 INFO - 'LOGNAME': 'cltbld', 04:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:18 INFO - 'MOZ_NO_REMOTE': '1', 04:53:18 INFO - 'NO_EM_RESTART': '1', 04:53:18 INFO - 'PAGER': '/bin/cat', 04:53:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:18 INFO - 'PWD': '/builds/slave/test', 04:53:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:18 INFO - 'SHELL': '/bin/bash', 04:53:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SKkyZz59yK/Listeners', 04:53:18 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:53:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:18 INFO - 'USER': 'cltbld', 04:53:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:18 INFO - 'XPC_FLAGS': '0x0', 04:53:18 INFO - 'XPC_SERVICE_NAME': '0', 04:53:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:18 INFO - Downloading/unpacking psutil>=0.7.1 04:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:53:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:53:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:53:21 INFO - Installing collected packages: psutil 04:53:21 INFO - Running setup.py install for psutil 04:53:21 INFO - building 'psutil._psutil_osx' extension 04:53:21 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:53:21 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:53:21 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:53:21 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:53:21 INFO - building 'psutil._psutil_posix' extension 04:53:21 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:53:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:53:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:53:21 INFO - ^ 04:53:22 INFO - 1 warning generated. 04:53:22 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:53:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:53:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:53:22 INFO - Successfully installed psutil 04:53:22 INFO - Cleaning up... 04:53:22 INFO - Return code: 0 04:53:22 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:53:22 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:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:22 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:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:22 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 0x1072c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd17bd836a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716cd30>, '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 0x106f90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107163b20>, '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.VoyLUF0NxP/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.SKkyZz59yK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:53:22 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:53:22 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:53:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoyLUF0NxP/Render', 04:53:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:22 INFO - 'HOME': '/Users/cltbld', 04:53:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:22 INFO - 'LOGNAME': 'cltbld', 04:53:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:22 INFO - 'MOZ_NO_REMOTE': '1', 04:53:22 INFO - 'NO_EM_RESTART': '1', 04:53:22 INFO - 'PAGER': '/bin/cat', 04:53:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:22 INFO - 'PWD': '/builds/slave/test', 04:53:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:22 INFO - 'SHELL': '/bin/bash', 04:53:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SKkyZz59yK/Listeners', 04:53:22 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:53:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:22 INFO - 'USER': 'cltbld', 04:53:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:22 INFO - 'XPC_FLAGS': '0x0', 04:53:22 INFO - 'XPC_SERVICE_NAME': '0', 04:53:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:53:22 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:53:22 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:53:22 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:53:22 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:53:22 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:53:22 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:53:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:53:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:53:25 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:53:25 INFO - Installing collected packages: mozsystemmonitor 04:53:25 INFO - Running setup.py install for mozsystemmonitor 04:53:25 INFO - Successfully installed mozsystemmonitor 04:53:25 INFO - Cleaning up... 04:53:25 INFO - Return code: 0 04:53:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:25 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 0x1072c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd17bd836a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716cd30>, '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 0x106f90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107163b20>, '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.VoyLUF0NxP/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.SKkyZz59yK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:53:25 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:53:25 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:53:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoyLUF0NxP/Render', 04:53:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:25 INFO - 'HOME': '/Users/cltbld', 04:53:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:25 INFO - 'LOGNAME': 'cltbld', 04:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:25 INFO - 'MOZ_NO_REMOTE': '1', 04:53:25 INFO - 'NO_EM_RESTART': '1', 04:53:25 INFO - 'PAGER': '/bin/cat', 04:53:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:25 INFO - 'PWD': '/builds/slave/test', 04:53:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:25 INFO - 'SHELL': '/bin/bash', 04:53:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SKkyZz59yK/Listeners', 04:53:25 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:53:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:25 INFO - 'USER': 'cltbld', 04:53:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:25 INFO - 'XPC_FLAGS': '0x0', 04:53:25 INFO - 'XPC_SERVICE_NAME': '0', 04:53:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:25 INFO - Downloading/unpacking blobuploader==1.2.4 04:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:27 INFO - Downloading blobuploader-1.2.4.tar.gz 04:53:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:53:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:53:28 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:53:28 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:53:28 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:53:28 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:53:28 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:53:28 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:53:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:53:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:53:28 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:53:28 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:53:28 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:53:28 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:53:28 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:53:28 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:53:29 INFO - Downloading docopt-0.6.1.tar.gz 04:53:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:53:29 INFO - Installing collected packages: blobuploader, requests, docopt 04:53:29 INFO - Running setup.py install for blobuploader 04:53:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:53:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:53:29 INFO - Running setup.py install for requests 04:53:29 INFO - Running setup.py install for docopt 04:53:29 INFO - Successfully installed blobuploader requests docopt 04:53:29 INFO - Cleaning up... 04:53:30 INFO - Return code: 0 04:53:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53: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:53:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53: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:53:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:30 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 0x1072c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd17bd836a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716cd30>, '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 0x106f90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107163b20>, '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.VoyLUF0NxP/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.SKkyZz59yK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:53:30 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:53:30 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:53:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoyLUF0NxP/Render', 04:53:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:30 INFO - 'HOME': '/Users/cltbld', 04:53:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:30 INFO - 'LOGNAME': 'cltbld', 04:53:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:30 INFO - 'MOZ_NO_REMOTE': '1', 04:53:30 INFO - 'NO_EM_RESTART': '1', 04:53:30 INFO - 'PAGER': '/bin/cat', 04:53:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:30 INFO - 'PWD': '/builds/slave/test', 04:53:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:30 INFO - 'SHELL': '/bin/bash', 04:53:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SKkyZz59yK/Listeners', 04:53:30 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:53:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:30 INFO - 'USER': 'cltbld', 04:53:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:30 INFO - 'XPC_FLAGS': '0x0', 04:53:30 INFO - 'XPC_SERVICE_NAME': '0', 04:53:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:53:30 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-KdKWwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:53:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:53:30 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-QGDCw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:53:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:53:30 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-5yOS4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:53:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:53:30 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-WC7Nqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:53:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:53:30 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-XLP3RT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:53:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:53:30 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-sqYA0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:53:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:53:30 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-qI6b1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:53:31 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-COpX7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:53:31 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-sWVijP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:53:31 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-1rlv3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:53:31 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Akvi8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:53:31 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Mbse33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:53:31 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-6g27Ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:53:31 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-32QNFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:31 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-mV5ZkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:53:31 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-OmMdMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:53:32 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-PtUofn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:53:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:53:32 INFO - Running setup.py install for manifestparser 04:53:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:53:32 INFO - Running setup.py install for mozcrash 04:53:32 INFO - Running setup.py install for mozdebug 04:53:32 INFO - Running setup.py install for mozdevice 04:53:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:53:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:53:32 INFO - Running setup.py install for mozfile 04:53:32 INFO - Running setup.py install for mozhttpd 04:53:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:53:32 INFO - Running setup.py install for mozinfo 04:53:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:53:33 INFO - Running setup.py install for mozInstall 04:53:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:53:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:53:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:53:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:53:33 INFO - Running setup.py install for mozleak 04:53:33 INFO - Running setup.py install for mozlog 04:53:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:53:33 INFO - Running setup.py install for moznetwork 04:53:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:53:33 INFO - Running setup.py install for mozprocess 04:53:33 INFO - Running setup.py install for mozprofile 04:53:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:53:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:53:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:53:34 INFO - Running setup.py install for mozrunner 04:53:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:53:34 INFO - Running setup.py install for mozscreenshot 04:53:34 INFO - Running setup.py install for moztest 04:53:34 INFO - Running setup.py install for mozversion 04:53:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:53:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:53:34 INFO - Cleaning up... 04:53:34 INFO - Return code: 0 04:53:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:34 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 0x1072c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd17bd836a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716cd30>, '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 0x106f90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107163b20>, '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.VoyLUF0NxP/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.SKkyZz59yK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:53:34 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:53:34 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:53:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoyLUF0NxP/Render', 04:53:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:34 INFO - 'HOME': '/Users/cltbld', 04:53:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:34 INFO - 'LOGNAME': 'cltbld', 04:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:34 INFO - 'MOZ_NO_REMOTE': '1', 04:53:34 INFO - 'NO_EM_RESTART': '1', 04:53:34 INFO - 'PAGER': '/bin/cat', 04:53:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:34 INFO - 'PWD': '/builds/slave/test', 04:53:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:34 INFO - 'SHELL': '/bin/bash', 04:53:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SKkyZz59yK/Listeners', 04:53:34 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:53:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:34 INFO - 'USER': 'cltbld', 04:53:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:34 INFO - 'XPC_FLAGS': '0x0', 04:53:34 INFO - 'XPC_SERVICE_NAME': '0', 04:53:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:53:34 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-BvQihR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:53:35 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:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:53:35 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-2fXYQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:53:35 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:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:53:35 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-DDk9LN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:53:35 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:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:53:35 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-q_lIvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:53:35 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:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:53:35 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Tzoe2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:53:35 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:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:53:35 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-C0zIoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:53:35 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:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:53:35 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-G9Wj_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:53:35 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:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:53:35 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Ay4ob6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:53:35 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:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:53:35 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-uyoQxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:53:35 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:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:53:35 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-uGVYI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:53:36 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:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:53:36 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-00w_LF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:53:36 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:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:53:36 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-HxmHdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:53:36 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:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:53:36 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-sfg7Py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:53:36 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:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:53:36 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-_IsPkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:53:36 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:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:36 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Hu5gh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:36 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:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:53:36 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-VjEZB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:53:36 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:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:53:36 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-AU5MWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:53:36 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:53:36 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:53:36 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:53:36 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:53:36 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:53:36 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:53:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:53:36 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:53:36 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:53:36 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:53:36 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:53:36 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:53:36 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:53:39 INFO - Downloading blessings-1.6.tar.gz 04:53:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:53:39 INFO - Installing collected packages: blessings 04:53:39 INFO - Running setup.py install for blessings 04:53:39 INFO - Successfully installed blessings 04:53:39 INFO - Cleaning up... 04:53:39 INFO - Return code: 0 04:53:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:53:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:39 INFO - Reading from file tmpfile_stdout 04:53:39 INFO - Current package versions: 04:53:39 INFO - blessings == 1.6 04:53:39 INFO - blobuploader == 1.2.4 04:53:39 INFO - docopt == 0.6.1 04:53:39 INFO - manifestparser == 1.1 04:53:39 INFO - mozInstall == 1.12 04:53:39 INFO - mozcrash == 0.17 04:53:39 INFO - mozdebug == 0.1 04:53:39 INFO - mozdevice == 0.48 04:53:39 INFO - mozfile == 1.2 04:53:39 INFO - mozhttpd == 0.7 04:53:39 INFO - mozinfo == 0.9 04:53:39 INFO - mozleak == 0.1 04:53:39 INFO - mozlog == 3.1 04:53:39 INFO - moznetwork == 0.27 04:53:39 INFO - mozprocess == 0.22 04:53:39 INFO - mozprofile == 0.28 04:53:39 INFO - mozrunner == 6.11 04:53:39 INFO - mozscreenshot == 0.1 04:53:39 INFO - mozsystemmonitor == 0.0 04:53:39 INFO - moztest == 0.7 04:53:39 INFO - mozversion == 1.4 04:53:39 INFO - psutil == 3.1.1 04:53:39 INFO - requests == 1.2.3 04:53:39 INFO - wsgiref == 0.1.2 04:53:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:39 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:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:39 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:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:39 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 0x1072c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd17bd836a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716cd30>, '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 0x106f90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107163b20>, '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.VoyLUF0NxP/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.SKkyZz59yK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:53:39 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:53:39 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:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoyLUF0NxP/Render', 04:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:39 INFO - 'HOME': '/Users/cltbld', 04:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:39 INFO - 'LOGNAME': 'cltbld', 04:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:39 INFO - 'MOZ_NO_REMOTE': '1', 04:53:39 INFO - 'NO_EM_RESTART': '1', 04:53:39 INFO - 'PAGER': '/bin/cat', 04:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:39 INFO - 'PWD': '/builds/slave/test', 04:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:39 INFO - 'SHELL': '/bin/bash', 04:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SKkyZz59yK/Listeners', 04:53:39 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:39 INFO - 'USER': 'cltbld', 04:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:39 INFO - 'XPC_FLAGS': '0x0', 04:53:39 INFO - 'XPC_SERVICE_NAME': '0', 04:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:39 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:53:39 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:53:39 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:53:39 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:53:39 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:53:39 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:53:39 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:53:39 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:53:39 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:53:39 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:53:39 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:53:39 INFO - Cleaning up... 04:53:39 INFO - Return code: 0 04:53:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:53:39 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:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:39 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:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:39 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 0x1072c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd17bd836a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716cd30>, '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 0x106f90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107163b20>, '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.VoyLUF0NxP/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.SKkyZz59yK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:53:39 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:53:39 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:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoyLUF0NxP/Render', 04:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:39 INFO - 'HOME': '/Users/cltbld', 04:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:39 INFO - 'LOGNAME': 'cltbld', 04:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:39 INFO - 'MOZ_NO_REMOTE': '1', 04:53:39 INFO - 'NO_EM_RESTART': '1', 04:53:39 INFO - 'PAGER': '/bin/cat', 04:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:39 INFO - 'PWD': '/builds/slave/test', 04:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:39 INFO - 'SHELL': '/bin/bash', 04:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SKkyZz59yK/Listeners', 04:53:39 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:39 INFO - 'USER': 'cltbld', 04:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:39 INFO - 'XPC_FLAGS': '0x0', 04:53:39 INFO - 'XPC_SERVICE_NAME': '0', 04:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:40 INFO - Downloading/unpacking jsonschema 04:53:40 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:53:40 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:53:40 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:53:40 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:53:40 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:53:40 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:53:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:53:42 INFO - Downloading/unpacking functools32 (from jsonschema) 04:53:42 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:53:42 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:53:42 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:53:42 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:53:42 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:53:42 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:53:42 INFO - Downloading functools32-3.2.3-2.tar.gz 04:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:53:43 INFO - warning: no files found matching '*.txt' 04:53:43 INFO - no previously-included directories found matching 'build' 04:53:43 INFO - no previously-included directories found matching 'dist' 04:53:43 INFO - no previously-included directories found matching '.git*' 04:53:43 INFO - Installing collected packages: jsonschema, functools32 04:53:43 INFO - Running setup.py install for functools32 04:53:43 INFO - warning: no files found matching '*.txt' 04:53:43 INFO - no previously-included directories found matching 'build' 04:53:43 INFO - no previously-included directories found matching 'dist' 04:53:43 INFO - no previously-included directories found matching '.git*' 04:53:43 INFO - Successfully installed jsonschema functools32 04:53:43 INFO - Cleaning up... 04:53:43 INFO - Return code: 0 04:53:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:53:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:43 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 0x1072c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd17bd836a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10716cd30>, '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 0x106f90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107163b20>, '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.VoyLUF0NxP/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.SKkyZz59yK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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:53:43 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:53:43 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:53:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoyLUF0NxP/Render', 04:53:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:43 INFO - 'HOME': '/Users/cltbld', 04:53:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:43 INFO - 'LOGNAME': 'cltbld', 04:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:43 INFO - 'MOZ_NO_REMOTE': '1', 04:53:43 INFO - 'NO_EM_RESTART': '1', 04:53:43 INFO - 'PAGER': '/bin/cat', 04:53:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:43 INFO - 'PWD': '/builds/slave/test', 04:53:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:43 INFO - 'SHELL': '/bin/bash', 04:53:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SKkyZz59yK/Listeners', 04:53:43 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:53:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:43 INFO - 'USER': 'cltbld', 04:53:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:43 INFO - 'XPC_FLAGS': '0x0', 04:53:43 INFO - 'XPC_SERVICE_NAME': '0', 04:53:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:43 INFO - Downloading/unpacking flake8 04:53:43 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:53:43 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:53:43 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:53:44 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:53:44 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:53:44 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:53:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:53:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:53:47 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:53:47 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:53:47 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:53:47 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:53:47 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:53:47 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:53:47 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:53:47 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:53:47 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:53:47 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:53:47 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:53:47 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:53:47 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:53:47 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:53:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:53:48 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:53:48 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:53:48 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:53:48 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:53:48 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:53:48 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:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:53:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:53:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:53:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:53:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:53:48 INFO - no previously-included directories found matching 'docs/_build' 04:53:48 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:53:48 INFO - Running setup.py install for pep8 04:53:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:53:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:53:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:53:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:53:48 INFO - no previously-included directories found matching 'docs/_build' 04:53:48 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:53:48 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:53:48 INFO - Cleaning up... 04:53:48 INFO - Return code: 0 04:53:48 INFO - Running post-action listener: _resource_record_post_action 04:53:48 INFO - Running post-action listener: _start_resource_monitoring 04:53:48 INFO - Starting resource monitoring. 04:53:48 INFO - ##### 04:53:48 INFO - ##### Finished create-virtualenv step (success) 04:53:48 INFO - ##### 04:53:48 INFO - ##### 04:53:48 INFO - ##### Running install step. 04:53:48 INFO - ##### 04:53:48 INFO - Running pre-action listener: _resource_record_pre_action 04:53:48 INFO - Running main action method: install 04:53:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:49 INFO - Reading from file tmpfile_stdout 04:53:49 INFO - Detecting whether we're running mozinstall >=1.0... 04:53:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:53:49 INFO - Reading from file tmpfile_stdout 04:53:49 INFO - Output received: 04:53:49 INFO - Usage: mozinstall [options] installer 04:53:49 INFO - Options: 04:53:49 INFO - -h, --help show this help message and exit 04:53:49 INFO - -d DEST, --destination=DEST 04:53:49 INFO - Directory to install application into. [default: 04:53:49 INFO - "/builds/slave/test"] 04:53:49 INFO - --app=APP Application being installed. [default: firefox] 04:53:49 INFO - mkdir: /builds/slave/test/build/application 04:53:49 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:53:49 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:54:22 INFO - Reading from file tmpfile_stdout 04:54:22 INFO - Output received: 04:54:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:54:22 INFO - Running post-action listener: _resource_record_post_action 04:54:22 INFO - ##### 04:54:22 INFO - ##### Finished install step (success) 04:54:22 INFO - ##### 04:54:22 INFO - ##### 04:54:22 INFO - ##### Running run-tests step. 04:54:22 INFO - ##### 04:54:22 INFO - Running pre-action listener: _resource_record_pre_action 04:54:22 INFO - Running main action method: run_tests 04:54:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:54:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:54:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:54:22 INFO - 2016-05-09 04:54:22.610 screenresolution[1929:12171] starting screenresolution argv=screenresolution get 04:54:22 INFO - 2016-05-09 04:54:22.612 screenresolution[1929:12171] Display 0: 1600x1200x32@60 04:54:22 INFO - 2016-05-09 04:54:22.618 screenresolution[1930:12173] starting screenresolution argv=screenresolution list 04:54:22 INFO - Available Modes on Display 0 04:54:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:54:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:54:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:54:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:54:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:54:22 INFO - Intel Iris: 04:54:22 INFO - Chipset Model: Intel Iris 04:54:22 INFO - Type: GPU 04:54:22 INFO - Bus: Built-In 04:54:22 INFO - VRAM (Dynamic, Max): 1536 MB 04:54:22 INFO - Vendor: Intel (0x8086) 04:54:22 INFO - Device ID: 0x0a2e 04:54:22 INFO - Revision ID: 0x0009 04:54:22 INFO - Displays: 04:54:22 INFO - Display: 04:54:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:54:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:54:22 INFO - Display Serial Number: 1600x1200 60 04:54:22 INFO - Main Display: Yes 04:54:22 INFO - Mirror: Off 04:54:22 INFO - Online: Yes 04:54:22 INFO - Rotation: Supported 04:54:22 INFO - Return code: 0 04:54:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:54:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:54:22 INFO - Python 2.7.3 04:54:22 INFO - Return code: 0 04:54:22 INFO - grabbing minidump binary from tooltool 04:54:22 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:22 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 0x10716cd30>, '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 0x106f90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107163b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:54:22 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:54:22 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:54:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:54:22 INFO - Return code: 0 04:54:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:54:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:54:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:54:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:54:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:54:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:54:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:54:24 INFO - Return code: 0 04:54:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--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-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --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-0169 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:54:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoyLUF0NxP/Render', 04:54:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:24 INFO - 'HOME': '/Users/cltbld', 04:54:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:24 INFO - 'LOGNAME': 'cltbld', 04:54:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:54:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:24 INFO - 'MOZ_NO_REMOTE': '1', 04:54:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:54:24 INFO - 'NO_EM_RESTART': '1', 04:54:24 INFO - 'PAGER': '/bin/cat', 04:54:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:24 INFO - 'PWD': '/builds/slave/test', 04:54:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:54:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:24 INFO - 'SHELL': '/bin/bash', 04:54:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SKkyZz59yK/Listeners', 04:54:24 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 04:54:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:24 INFO - 'USER': 'cltbld', 04:54:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:24 INFO - 'XPC_FLAGS': '0x0', 04:54:24 INFO - 'XPC_SERVICE_NAME': '0', 04:54:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--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-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:54:24 INFO - mozversion application_buildid: 20160509024751 04:54:24 INFO - mozversion application_changeset: 7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce 04:54:24 INFO - mozversion application_display_name: Nightly 04:54:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:54:24 INFO - mozversion application_name: Firefox 04:54:24 INFO - mozversion application_remotingname: firefox 04:54:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:54:24 INFO - mozversion application_vendor: Mozilla 04:54:24 INFO - mozversion application_version: 49.0a1 04:54:24 INFO - mozversion platform_buildid: 20160509024751 04:54:24 INFO - mozversion platform_changeset: 7adbdf0fce9ac43118938d8e3dc6c0f500a2ffce 04:54:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:54:24 INFO - mozversion platform_version: 49.0a1 04:54:24 INFO - using testdate: 1462794864 04:54:24 INFO - actual date: 1462794864 04:54:24 INFO - starting webserver on 'localhost:51978' 04:54:24 INFO - SUITE-START | Running 2 tests 04:54:24 INFO - TEST-START | dromaeo_css 04:54:24 INFO - operating with platform_type : mac_ 04:54:24 INFO - Initialising browser for dromaeo_css test... 04:54:24 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpV9OA1f/profile http://localhost:51978/getInfo.html) 04:54:27 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 04:54:27 INFO - PROCESS | 1948 | colorDepth:24 04:54:27 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 04:54:27 INFO - PROCESS | 1948 | __metrics 04:54:27 ERROR - PROCESS | 1948 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 04:54:27 INFO - TEST-INFO | 1948: exit 0 04:54:27 INFO - Browser initialized. 04:54:27 INFO - Running cycle 1/1 for dromaeo_css test... 04:54:27 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpV9OA1f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:54:40 INFO - PROCESS | 1951 | RSS: Main: 250388480 04:54:40 INFO - PROCESS | 1951 | 04:55:29 INFO - PROCESS | 1951 | 1462794929703 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:57:40 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51978/tests/dromaeo/cssquery-ext.html) 04:57:41 INFO - PROCESS | 1951 | RSS: Main: 262389760 04:57:41 INFO - PROCESS | 1951 | 05:00:44 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-ext.html (next: http://localhost:51978/tests/dromaeo/cssquery-jquery.html) 05:00:44 INFO - PROCESS | 1951 | RSS: Main: 274165760 05:00:44 INFO - PROCESS | 1951 | 05:03:46 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51978/tests/dromaeo/cssquery-mootools.html) 05:03:47 INFO - PROCESS | 1951 | RSS: Main: 276140032 05:03:47 INFO - PROCESS | 1951 | 05:06:49 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51978/tests/dromaeo/cssquery-prototype.html) 05:06:49 INFO - PROCESS | 1951 | RSS: Main: 277786624 05:06:49 INFO - PROCESS | 1951 | 05:09:52 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51978/tests/dromaeo/cssquery-yui.html) 05:09:53 INFO - PROCESS | 1951 | RSS: Main: 280625152 05:09:53 INFO - PROCESS | 1951 | 05:12:55 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-yui.html (next: http://localhost:51978/tests/dromaeo/cssquery-dojo.html) 05:12:55 INFO - PROCESS | 1951 | RSS: Main: 282013696 05:12:55 INFO - PROCESS | 1951 | 05:12:55 INFO - PROCESS | 1951 | __start_tp_report 05:12:55 INFO - PROCESS | 1951 | _x_x_mozilla_page_load 05:12:55 INFO - PROCESS | 1951 | _x_x_mozilla_page_load_details 05:12:55 INFO - PROCESS | 1951 | |i|pagename|runs| 05:12:55 INFO - PROCESS | 1951 | |0;dojo.html;3489.3719130556506;3502.4921386398955;3522;3539.716822654188;3540.628234035426;5892.410758924111;5898.823035308937;5901.822945311642;5972.17306340573;6087.512998960084;5759.1937128802;5910.46805787479;5932.110183472481;5949.910751338723;6013.158157857893;5269.710165940872;5316.680999140064;5403.594730395216;5413.945860541403;5516.117421212607;330.66488870468584;5293.232481290211;5368.651037682557;5399.676019438826;5408.215808707755;5345.492178243061;5383.057964856134;5388.272583201277;5394.811181608625;5397.514223719864;5374.543163831079;5398.514133727944;5406.918896216521;5455.699936503491;5485.341758988947;5238.685678859242;5270.736463176864;5325.547328477084;5372.409035006146;5415.64798288114;5713.714314284269;5721.599488035798;5779.653220806765;5819.388964158745;5845.620034697731;5156.535911767922;6203.5037197024585;6259.248890133168;6268.905966410507;6274.058891166316;5293.338332708411;6161.476274516672;6177.1969643945995;6206.689665516707;6290.3709629037185;6044.999999999956;6050.636961782263;6075.908861367083;6076.392360763933;6081.087836824512;5569.415211402826;6238.845813524513;6420.036994450869;6444.13004244421;6451;4863.610911127102;6100.48145907594;6130.703893784946;6165.10605962133;6230.096635987757;5308.442613525524;6221.96889015552;6358.427741503287;6359.459445947146;6361.936380636227;5430.212619170198;6173.259208894961;6175.228096487939;6253.312135665074;6260.499160067184;6190.195274614362;6233.657148856856;6236.968815155895;6241.157443745127;6268.028455589391;5081.4918508148885;5084.77118529667;5091.08360495107;5103.7192954387865;5123.2827404163445;4484.573965473275;4486.506484286726;4568.0863827234025;4573.771311434415;4582.060677561092;5879.911801322984;5956.880862382728;5973.880522389528;5974.731137098841;6039.486643635252;6293.465055470337;6303.527235457359;6328.11406403104;6342.080398342215;6357.396047375492;5898.734556944948;5909.379515150933;5920.615160014585;5938.28740551128;6030.879382412327;5472.534834539029;5476;5488.368837583649;5491.258680078138;5504.944950550524;6440.098386225838;6445.581037232565;6463.4506066983895;6474.546781725234;6477.125588045554;6142.253225532332;6195.597286176384;6221.564490485623;6237.75048998035;6351.30135685065;6506.089147519257;6507.869842603216;6522.28254891952;6533.281339052795;6544.116544266653;6152.846178845564;6245.594036387621;6263.874722505616;6275.497960163268;6283.842903927439;6186.566940314135;6189.195404597373;6237.563370564017;6282.842928926813;6293.087502312231;6071.787487437919;6183;6244.65654389013;6248.218972628421;6256.061590761422;6079.331273559993;6142.468080853617;6241.563090583615;6256.436920677161;6271.78048768291;6034.336223015377;6083;6118.223266370627;6136.335271946613;6138.140660307471;6175.776720380749;6205.906911396423;6216.844578885564;6217.4715149212825;6246.344133865879;6005.549583781147;6085.1480792689945;6093.817185484442;6136.846578835565;6163.075538669235 05:12:55 INFO - PROCESS | 1951 | |1;ext.html;502.8158686293776;516.2952569742301;545.0733752620546;553.6346011632322;564.9322081350233;39776.40335394968;42222.366664500056;42298.788506057426;42354.364684529755;42363.36454953178;42388.576114238924;42693;43078.56921430785;43088.3536746949;43235.13529729404;9630.470324132153;9751.317407781458;9782.413055216673;9809.901900981005;9820.214382849355;8318;9553.474558899257;9643.951780241088;9651.95174024129;9667.75830604237;9628.374155679858;9631.566579503939;9675.225981921429;9688.418694878295;9721.90278097217;9598.088181622734;9629.085236902483;9636.759081022961;9637.325387222898;9666.178374838111;8282.544460054694;8557.828843423158;9588.041195880425;9591.232701383942;9605.951970240174;38748.031299217466;39381.80309098465;39416.408753868716;39718.60281397178;39824.20351592952;2773.9583906241423;3055.6027716396725;3122.4223518649123;3314.3371325734706;3337.9499307510405;26785.598216026774;27584;28253.858730706215;28948.710512894813;29116;2596.402827349701;2650.310919161032;2682.342826007621;2730.8361498310164;2753.6420265365373;338282;426542;429716;430232;432766.83616582345;32839.671603284136;35365.1158721034;36016;36038.639613603526;36610.63389366125;36928.26143477116;37498.43752343717;37707.434388484195;37875.24249514994;38074.619253807665;15112.697746045018;15163.924180379028;15288.770668439982;15304.617384565252;15326.693466130615;12288.262704237774;12362.75274494505;12763.298018609066;12778.61664150077;12824.358782060859;8193.97575303087;8195.180481951877;8249.010118785784;8324.958375208207;8360.83278334442;3028.242939265184;3443.139215196201;3463.2380876207194;3471.687548120681;3478.3565040467593;921.3504479342048;926.8795056642729;1041.463646222196;1082.3884505254507;1131.1742428027585;25179.62230566543;28050;29617;29855.701442985726;30455.543166852516;1095.7917995580815;1096.4517741129434;1111.9110471162287;1157.8610566731877;1194.4326444938586;15178.772318415233;15333.693326133414;15429.845701543065;15628.765568516481;15734.606634834221;683768;686165;695160;699922.0007799958;702617;61229;61456.77086458246;62031;62114.68942655348;62278.0658290126;1458.1907041591805;1481.3482067890093;1492.2687882937282;1503.9022463539834;1504.3155364309214;28864.278393040342;29001.41997160003;29228.415431691672;29665;30068.849655751583;52132.73933630308;52917.73541132424;53245.733771330895;53299.20051199313;53407.7329613365;602821;625436;628602;628751;629315;22720.772792271866;26704.599431008937;27602.447951041268;27656;27717.58423623607;45252.77373613111;45852.77073614611;46167;46918.76540617275;46919;37803.05492362603;37950.62049379581;38196.42705359477;38605.42091868568;38911;37450.43824342582;37740.2451950965;37769.62230377661;38340.61659383482;38466;31746.523802142525;32345.838270809436;37491.81254093712;37835.43246851354;38732.61267387403;32331.838340808146;33601.49597755987;33696.49455258222;33745.81889633949;33801.49297760585;24988.37529061788;25542.10602628899;25604.103856364938;25670.61494077553;25876.48247035011 05:12:55 INFO - PROCESS | 1951 | |2;jquery.html;388.8658412847568;390.23319177815586;390.23513912731045;390.9218156368726;416.02857328138793;18981.71527427088;20288.1884724611;20329.085191166396;20646.793532064676;21502.92485375733;20086.49783755403;20573.868437235946;20731.274405395816;20778;21259.893700531476;5677.630953987988;5838.2702162229725;5958.910616340737;5958.910616340759;5966.910496342558;5592.105263157896;5621.634593751393;5815.156802263669;5841.240638716961;5921.763129474816;5827.125931110346;5851.6196447231005;5855.268091488564;5857.707114644272;5864.384239654839;5566.471185237396;5737.282839645044;5850.970745146268;5864.354920958713;5889.852753681193;5140.357455318085;5172.560332371753;5173.146430838907;5177.171652535614;5178.042062218502;25570.23289301324;25732.48535029308;25782.742172578222;26064.86967565169;26280.68596570183;2076.7404074490687;2120.777318381563;2143.2820005298245;2230.39779259599;2289.645105008726;9612.95193524035;14920.925395372953;14951.775723364159;15118.546443606709;15385.692286154213;1703.769991051217;1716.0218675355052;1781.145050375814;1837.0998210876708;1865.337805079191;131814;133161;133263;135189.32405338009;138338;11786.349911012465;11838.28970261787;11848.170628056127;11983.34091624969;11992.520299187934;15114.244287785787;15143.772843407358;15146.621334466507;15207.771883421758;15212.239388030775;8611.95694021526;8640.870386944202;8685.39202255836;8689.304855611535;8819.029906710257;8484.024337201301;8613.612387442456;8657.047724750273;8711.34664900135;8712.564371781513;3949.499190307722;4616.3537104805355;4689.015306785614;4729.597984171384;4821.975890120527;1970.940871773849;2008.517955690653;2011.5172358634115;2016.6067616814873;2018.8283995860222;905.4431524612316;957.8946315905245;969.6509256667736;983.8573406855966;1016.2276669731058;19028.904855475634;19615.705764413546;20685.379438616867;20833.958302084837;20928.058237379355;931.6366617019368;955.7371722776153;957.8132264208551;976.6093562575027;1042.0985847242082;9033.36766426357;9725.610975560898;10023.747031621047;10860.511276992554;11118.221724479208;580672;634008;641314;646466.767666168;655946.7202663956;145520;148273.25863370614;149865.25067374812;151068;153912;991.6925753016444;1020.3622735790132;1042.0881728487573;1043.9739006524899;1047.580967612976;14397.96832984796;18121.912685238927;18226.36207732723;18243.726344104583;18408.26366945307;54863.177052343446;55545.72227138838;55585.44414555802;56531.15203272036;56546.43453565576;80782.59608702153;83793.58103209444;84310.57844710737;85498.57250713707;86925.56537217273;7887.84224315522;8107.83784324322;8111.513309201467;8130.87803682957;8180.918190818015;11362.829557556477;12149.331786751813;12369.876301237233;12441.813372799596;12449.751004980031;10276;10495;10701.090407315465;10759.731006724582;10769.299995500267;10403;10505.894941050798;10616.469176540988;10646.307989980627;10705;8888.86666700013;10747.516361763895;11027.0075693188;11042.33745975212;11096.00135987765;7931.722389716332;8044.195580442143;8051.557164355782;8120.75637730869;8276.503409795432;10283.588656453958;10616.84074738864;10832.24174307791;10857.239993200716;10860.511276992713 05:12:55 INFO - PROCESS | 1951 | |3;mootools.html;1599.488163787588;1636.4190712297134;1857.7863545692248;1944.368080373879;1981.8612697111207;7814.687412503507;8437.071922088573;8461;8951.55242237888;8960.686375976842;8518.233358997704;8636.136386361377;8774.122587741223;8776.736697899058;8782.25350845179;2390.426297688551;2400.987995060022;2431.4893872286752;2438.548868459332;2441.340837973746;2409.4578719788083;2413.553492603865;2443.4013666651695;2443.9389015274583;2450.7304196538366;2419.5523828091773;2433.6227884677905;2435.488547405047;2446.107170882633;2448.9510209795794;2401.9759802402014;2409.759024097585;2411.3007227903886;2411.590029694947;2434.0628857889665;2096.4968407582223;2327.860328380286;2385.6540801583674;2403.0868270057267;2412.601920683085;8748.387612867102;8808.691695790616;8834.204921557026;8837.690680826205;8852;5287.841364759063;5397.7031263280505;5414.512693857571;5444.183372494118;5449.536789372908;3409.7613167078316;3493.0568746438576;3570.982145089284;3588.049166970729;3601.58581763098;1030.2118879057527;1044.2898828796415;1045.7856139491387;1053.2890299048188;1066.4401189375562;11417.885821141765;11431.428428578538;11531.94234028833;11538.59614913474;11543.88456115445;4527.47933987589;4540.614047805973;4633.976830115827;4680.2449081594405;4686.601638860667;9058.048904865025;9076.909230907739;9090.90909090914;9113.635454581743;9122.996470388252;5176.715280659525;5235.476452354734;5263.578913686896;5272.92090618641;5283.128283833124;4390.604845563915;4769.403824521934;4887.120318342661;4930.0139972005745;4947.48051454592;3769.396896496602;3809.6190380962234;3865.439511270906;3901.7073719471155;3917.6474117329008;1049.41757324684;1215.1918973882446;1216.045404357575;1219.676785651803;1221.7251118498446;930.3952430919821;932.6269492203037;940.3558562384789;959.9856002159974;984.5028260728286;4825.927611085766;5321.893562128813;5791.652500849962;5841.094630332305;6079.669795566371;378.7046104038854;1010.3382284603597;1015.0509273828993;1090.2095980414294;1132.3488993828416;3911.02224443889;3924.3917192835156;3960.4257382679925;3967.2263908538134;4041.6362527372676;10052.64815731446;10624.734381640521;10740.946295268473;10749.78500429987;10762.677119686421;8665.870011949699;10599.364038157735;10601.416922069206;10646.254762166574;10700.625478108228;8082.313003394777;8084;8184.181581841768;8252.958735206286;8268;5694.572907031907;5827.650340979555;5857.736401862012;5919.112133180058;6055.394460553909;9569.234461243223;9571.617135314787;9947.651832185838;10007.299489035697;10019.398836069859;8094.231048050426;10021.448820314952;10038.49807509607;10158.339707918962;10177.49112544355;6814.591124532544;7019.227884932756;7237.384822290164;7432.405407567489;7585.089789225328;8285.54429506383;8317.376196785386;8382.664693412195;8415.537145456814;8503.829923401374;7251.854962900818;7353.632318384166;7441.516301440389;7461.291177338144;7518.135414427411;7218.386437152691;7259.310365515267;7321.194668586558;7321.963390183015;7349.154847192642;6923.719111964303;7020.508564400443;7029.297070293134;7041.43668506508;7041.5422997505;4812.25410061441;4830.227163653799;4857.125717370765;4972.8508144755715;5071.011152825164;4910.042541704333;4991.42598601151;4996.8251111212385;5019.222020586815;5250.632455728062 05:12:55 INFO - PROCESS | 1951 | |4;prototype.html;278.5487510233421;297.4155783884666;313.7615412286662;317.5617647646248;324.8343344894103;5210.713410762407;5288.89422211556;5301.655392399501;5432.72836358182;5534.723263836807;4108.342665173573;4855.7086574805535;5006.899862002758;5101.750071232544;5415.593830462712;4116;4120.847551623011;4142.544320124784;4152.335626299792;4164.500259968808;3590.569131704199;3874.3073563873118;3972.781497017663;4023.295923213426;4117;3486.755927085105;3648.5804132524836;3798.917308567059;3901.6293452122004;4014.3175660137845;3919.549251836047;4098.954766534544;4124.030852749602;4134.1731653669085;4138.841124485119;3584.5698516177963;3610.368185567515;3614.765040272374;3621.203335266237;3636.690881275095;4204.516480604739;4411.867643970686;4946.788036930939;5040.6471546991725;5073.822416215452;4395.85707715993;4707.952920470786;4741.482725527832;4743.150171433143;4748.169070412699;5598.312809851253;5660.245323949589;5665.575081868835;5694.259746233005;5710.457506536873;3607.621199774012;3607.729420293462;3731.7947512887054;4014.859479918218;4039.6970227233073;5131.897362052775;5283.68297902131;5633.8591535211535;5821.922944255284;6009.6394216346725;3915.197384536163;4065.4714887064492;4072.735272207297;4082.4896887889013;4103.958960410358;3978.701597380208;3999.8200080996426;4141.81361838718;4143.274926887782;4168.0205151789305;4235.7670328131635;4715.0805592909055;4734.19518681818;4736.407949006374;4766.261229509431;5015.445211984296;5256.710880901511;5429.022775900376;5658.151277308437;5766.596338256281;1245.4893493667553;1247.4324182496941;1293.7606542789615;1316.7096245679288;1351.6620844788804;857.5540718826176;897.4615230861431;920.4385324951774;934.5467448287575;934.9345545811864;4227.408162857202;4524.683272170916;4742;4906.386701662293;4994.076095922266;4673.854905548163;5341.866453338697;5505.476979686923;5509.696966666792;5547.7780888764;4161.771102589387;4345.478542574848;4373.868783936487;4821.397325334333;4971.229459433794;2508.41052352697;2636.7080130735803;2941.249981254766;2942.4997750382486;2956.4826155422716;4967.925481117786;5809.564282678816;5841.378345425863;5913;5943.791967281124;4624.722516649012;5055.710793747571;5255.868603284948;5597.944020559742;5786.942130578725;5119.436861945183;5669.6314739541795;5868.735906884201;5891.852703682443;5934.910976335358;5430.565554755531;5610.859728506657;5640.802571909963;5667.5465962722055;5676.914846277392;4483.865484035484;4675.509071547439;4726.292857461908;5019.774110164978;5687.203791469115;4849.75751212445;5450.155225939986;5667.773289068391;5845.503132233808;5868.911966320423;3542.273833864;3850.5956874528893;3855.7879316637855;3874.4382064599913;3887.8639247626197;4674.976625116853;5355.250264962986;5518.310211223597;5550.9461499245535;5583.274174357308;5251.448597897319;5537.446255374431;5693.83991884508;5702.287214098238;5722.856928576819;4537.455505339381;4644.58198762113;4826.541478559671;5695.572832037697;5702.6293290935955;4923.163062279349;5269.4730526947;5453.400125986218;5553.4724201200825;5569.554435645057;4973.726445045557;5565.471280228372;5665.688387138747;5682.403347648603;5697.857553561028;4334.9812793994015;5122.282880396673;5346.411894691503;5348.572114230775;5444.012935278674 05:12:55 INFO - PROCESS | 1951 | |5;yui.html;611.7858749437695;649.4577028181468;729.2561587181078;740.1451323721102;761.9009528761263;14928.552143435702;15121.47074852379;15309.85176111792;16572.502824915242;16940;15819.209039548005;16266.186690665478;16286.592835179126;16370.672586548262;16409.261583228752;737.0124033794714;929.8651695504165;938.699616122841;941.0589410589411;942.9198518125934;890.9198172164504;910.1535571918114;932.7434955387255;942.1002942190227;946.2240962410826;948.4167237149138;971.7764914069768;975.1564896364664;983.6409710455667;989.154273096503;928.7074571509953;928.9396189247677;931.1479995803832;944.2163004706077;978.750418643245;708.9184743754482;722.4148439763756;723.1864152828068;723.464636169231;726.2555880222814;14599.124052556774;14674.559763207015;14859.628509287246;14863.182524961123;14865.331060102213;12195.45120469572;12210.75578488434;12215.755684886253;12275.140740148194;12479.688007799787;9763.90236097644;9897.90102098977;9947.602095916154;9962.701118966443;10290.228232882564;3406.897793066187;3419.5554577904963;3440.122768693967;3443.1736383268085;3527.0829584308262;85674.28651426936;85892;86856.565717171;86862.13137868667;87312.12687873104;9142.451452912846;9262.953685231665;9296;9499.715008549754;9565.330426870052;21248.36254912355;21366.78633213648;21391.358259252247;21413.67879481809;21560.892195538923;7143.9285607143265;7186;7386.815329616695;7436.702531898772;7534.133574638877;3239.4169049570833;3892.5523564789846;3973.761574305493;3980.7014473914574;4065.6340929316502;3373.797572145679;3440.2431465077643;3450.6204317525053;3455.585329760445;3477;568.5480043365452;599.6851652882272;620.1225266248298;633.9492840572743;634.0362648773838;502.4549510255977;577.4398833131855;591.5858898770878;598.1252789763463;608.4189598933008;7210.242924492957;7638.388928885676;8231.300339471212;8371.958140209383;8573.314134869195;506.2836086936943;534.3507638219504;569.9316082070095;586.9640085249538;593.4214141212284;3735.9252814944093;3748.981255093707;3801.5248093988253;3826.3686491728554;3839.2897313997;73113;78799.81800273001;82032.58983705043;82200;84990.57504712437;56925.71537142288;74565.25434745691;74752;74858.25141748512;75468.24531754722;63052.684736576026;65863;66078.00882986758;66544.66727666331;67002.3299766996;7599.164091949991;7671.923280767121;7737.14891361961;7778.88331675014;7837.37301015906;43338.69983900488;43574.56425435705;43704;44062.01720922502;44536.331955020054;41589;48392;52755.73622131865;54231.45768542264;55083.72458137684;10238.897611024093;10345.74135646615;11036.613718519813;11110.222284440011;11160.330380177214;11891.464884080064;11968.222065565711;12170.817437738619;12426.813597796221;12606.936965315115;11127.88872111301;11270.887291126985;11541.192116551762;11559.248648837798;12275.386230688251;11321.490532926204;11397.658070257905;11523;11707.239029463057;11729.530818767154;11075.169362297698;11287.7742445149;11534.43375056108;11640.243384180001;11938.761224775282;9432.481213533047;9480.014849647321;9629.855552166864;9633.32566720323;9936.751581210528;9221.970927278322;9434.679144919579;9565.569549370433;9572.473513956798;9785.315027947976 05:12:55 INFO - PROCESS | 1951 | __end_tp_report 05:12:55 INFO - PROCESS | 1951 | __start_cc_report 05:12:55 INFO - PROCESS | 1951 | _x_x_mozilla_cycle_collect,407 05:12:55 INFO - PROCESS | 1951 | __end_cc_report 05:12:55 INFO - PROCESS | 1951 | __startTimestamp1462795975836__endTimestamp 05:12:55 INFO - PROCESS | 1951 | 05:12:55 INFO - PROCESS | 1951 | ------- Summary: start ------- 05:12:55 INFO - PROCESS | 1951 | Number of tests: 6 05:12:55 INFO - PROCESS | 1951 | 05:12:55 INFO - PROCESS | 1951 | [#0] dojo.html Cycles:165 Average:5804.59 Median:6079.33 stddev:752.24 (12.4%) stddev-sans-first:732.30 05:12:55 INFO - PROCESS | 1951 | Values: 3489.4 3502.5 3522.0 3539.7 3540.6 5892.4 5898.8 5901.8 5972.2 6087.5 5759.2 5910.5 5932.1 5949.9 6013.2 5269.7 5316.7 5403.6 5413.9 5516.1 330.7 5293.2 5368.7 5399.7 5408.2 5345.5 5383.1 5388.3 5394.8 5397.5 5374.5 5398.5 5406.9 5455.7 5485.3 5238.7 5270.7 5325.5 5372.4 5415.6 5713.7 5721.6 5779.7 5819.4 5845.6 5156.5 6203.5 6259.2 6268.9 6274.1 5293.3 6161.5 6177.2 6206.7 6290.4 6045.0 6050.6 6075.9 6076.4 6081.1 5569.4 6238.8 6420.0 6444.1 6451.0 4863.6 6100.5 6130.7 6165.1 6230.1 5308.4 6222.0 6358.4 6359.5 6361.9 5430.2 6173.3 6175.2 6253.3 6260.5 6190.2 6233.7 6237.0 6241.2 6268.0 5081.5 5084.8 5091.1 5103.7 5123.3 4484.6 4486.5 4568.1 4573.8 4582.1 5879.9 5956.9 5973.9 5974.7 6039.5 6293.5 6303.5 6328.1 6342.1 6357.4 5898.7 5909.4 5920.6 5938.3 6030.9 5472.5 5476.0 5488.4 5491.3 5504.9 6440.1 6445.6 6463.5 6474.5 6477.1 6142.3 6195.6 6221.6 6237.8 6351.3 6506.1 6507.9 6522.3 6533.3 6544.1 6152.8 6245.6 6263.9 6275.5 6283.8 6186.6 6189.2 6237.6 6282.8 6293.1 6071.8 6183.0 6244.7 6248.2 6256.1 6079.3 6142.5 6241.6 6256.4 6271.8 6034.3 6083.0 6118.2 6136.3 6138.1 6175.8 6205.9 6216.8 6217.5 6246.3 6005.5 6085.1 6093.8 6136.8 6163.1 05:12:55 INFO - PROCESS | 1951 | 05:12:55 INFO - PROCESS | 1951 | [#1] ext.html Cycles:180 Average:68947.65 Median:37912.93 stddev:158175.54 (417.2%) stddev-sans-first:158535.78 05:12:55 INFO - PROCESS | 1951 | Values: 502.8 516.3 545.1 553.6 564.9 39776.4 42222.4 42298.8 42354.4 42363.4 42388.6 42693.0 43078.6 43088.4 43235.1 9630.5 9751.3 9782.4 9809.9 9820.2 8318.0 9553.5 9644.0 9652.0 9667.8 9628.4 9631.6 9675.2 9688.4 9721.9 9598.1 9629.1 9636.8 9637.3 9666.2 8282.5 8557.8 9588.0 9591.2 9606.0 38748.0 39381.8 39416.4 39718.6 39824.2 2774.0 3055.6 3122.4 3314.3 3337.9 26785.6 27584.0 28253.9 28948.7 29116.0 2596.4 2650.3 2682.3 2730.8 2753.6 338282.0 426542.0 429716.0 430232.0 432766.8 32839.7 35365.1 36016.0 36038.6 36610.6 36928.3 37498.4 37707.4 37875.2 38074.6 15112.7 15163.9 15288.8 15304.6 15326.7 12288.3 12362.8 12763.3 12778.6 12824.4 8194.0 8195.2 8249.0 8325.0 8360.8 3028.2 3443.1 3463.2 3471.7 3478.4 921.4 926.9 1041.5 1082.4 1131.2 25179.6 28050.0 29617.0 29855.7 30455.5 1095.8 1096.5 1111.9 1157.9 1194.4 15178.8 15333.7 15429.8 15628.8 15734.6 683768.0 686165.0 695160.0 699922.0 702617.0 61229.0 61456.8 62031.0 62114.7 62278.1 1458.2 1481.3 1492.3 1503.9 1504.3 28864.3 29001.4 29228.4 29665.0 30068.8 52132.7 52917.7 53245.7 53299.2 53407.7 602821.0 625436.0 628602.0 628751.0 629315.0 22720.8 26704.6 27602.4 27656.0 27717.6 45252.8 45852.8 46167.0 46918.8 46919.0 37803.1 37950.6 38196.4 38605.4 38911.0 37450.4 37740.2 37769.6 38340.6 38466.0 31746.5 32345.8 37491.8 37835.4 38732.6 32331.8 33601.5 33696.5 33745.8 33801.5 24988.4 25542.1 25604.1 25670.6 25876.5 05:12:55 INFO - PROCESS | 1951 | 05:12:55 INFO - PROCESS | 1951 | [#2] jquery.html Cycles:180 Average:37279.48 Median:2260.02 stddev:106280.09 (4702.6%) stddev-sans-first:106542.14 05:12:55 INFO - PROCESS | 1951 | Values: 388.9 390.2 390.2 390.9 416.0 18981.7 20288.2 20329.1 20646.8 21502.9 20086.5 20573.9 20731.3 20778.0 21259.9 5677.6 5838.3 5958.9 5958.9 5966.9 5592.1 5621.6 5815.2 5841.2 5921.8 5827.1 5851.6 5855.3 5857.7 5864.4 5566.5 5737.3 5851.0 5864.4 5889.9 5140.4 5172.6 5173.1 5177.2 5178.0 25570.2 25732.5 25782.7 26064.9 26280.7 2076.7 2120.8 2143.3 2230.4 2289.6 9613.0 14920.9 14951.8 15118.5 15385.7 1703.8 1716.0 1781.1 1837.1 1865.3 131814.0 133161.0 133263.0 135189.3 138338.0 11786.3 11838.3 11848.2 11983.3 11992.5 15114.2 15143.8 15146.6 15207.8 15212.2 8612.0 8640.9 8685.4 8689.3 8819.0 8484.0 8613.6 8657.0 8711.3 8712.6 3949.5 4616.4 4689.0 4729.6 4822.0 1970.9 2008.5 2011.5 2016.6 2018.8 905.4 957.9 969.7 983.9 1016.2 19028.9 19615.7 20685.4 20834.0 20928.1 931.6 955.7 957.8 976.6 1042.1 9033.4 9725.6 10023.7 10860.5 11118.2 580672.0 634008.0 641314.0 646466.8 655946.7 145520.0 148273.3 149865.3 151068.0 153912.0 991.7 1020.4 1042.1 1044.0 1047.6 14398.0 18121.9 18226.4 18243.7 18408.3 54863.2 55545.7 55585.4 56531.2 56546.4 80782.6 83793.6 84310.6 85498.6 86925.6 7887.8 8107.8 8111.5 8130.9 8180.9 11362.8 12149.3 12369.9 12441.8 12449.8 10276.0 10495.0 10701.1 10759.7 10769.3 10403.0 10505.9 10616.5 10646.3 10705.0 8888.9 10747.5 11027.0 11042.3 11096.0 7931.7 8044.2 8051.6 8120.8 8276.5 10283.6 10616.8 10832.2 10857.2 10860.5 05:12:55 INFO - PROCESS | 1951 | 05:12:55 INFO - PROCESS | 1951 | [#3] mootools.html Cycles:180 Average:5643.68 Median:4872.12 stddev:3106.20 (63.8%) stddev-sans-first:3100.04 05:12:55 INFO - PROCESS | 1951 | Values: 1599.5 1636.4 1857.8 1944.4 1981.9 7814.7 8437.1 8461.0 8951.6 8960.7 8518.2 8636.1 8774.1 8776.7 8782.3 2390.4 2401.0 2431.5 2438.5 2441.3 2409.5 2413.6 2443.4 2443.9 2450.7 2419.6 2433.6 2435.5 2446.1 2449.0 2402.0 2409.8 2411.3 2411.6 2434.1 2096.5 2327.9 2385.7 2403.1 2412.6 8748.4 8808.7 8834.2 8837.7 8852.0 5287.8 5397.7 5414.5 5444.2 5449.5 3409.8 3493.1 3571.0 3588.0 3601.6 1030.2 1044.3 1045.8 1053.3 1066.4 11417.9 11431.4 11531.9 11538.6 11543.9 4527.5 4540.6 4634.0 4680.2 4686.6 9058.0 9076.9 9090.9 9113.6 9123.0 5176.7 5235.5 5263.6 5272.9 5283.1 4390.6 4769.4 4887.1 4930.0 4947.5 3769.4 3809.6 3865.4 3901.7 3917.6 1049.4 1215.2 1216.0 1219.7 1221.7 930.4 932.6 940.4 960.0 984.5 4825.9 5321.9 5791.7 5841.1 6079.7 378.7 1010.3 1015.1 1090.2 1132.3 3911.0 3924.4 3960.4 3967.2 4041.6 10052.6 10624.7 10740.9 10749.8 10762.7 8665.9 10599.4 10601.4 10646.3 10700.6 8082.3 8084.0 8184.2 8253.0 8268.0 5694.6 5827.7 5857.7 5919.1 6055.4 9569.2 9571.6 9947.7 10007.3 10019.4 8094.2 10021.4 10038.5 10158.3 10177.5 6814.6 7019.2 7237.4 7432.4 7585.1 8285.5 8317.4 8382.7 8415.5 8503.8 7251.9 7353.6 7441.5 7461.3 7518.1 7218.4 7259.3 7321.2 7322.0 7349.2 6923.7 7020.5 7029.3 7041.4 7041.5 4812.3 4830.2 4857.1 4972.9 5071.0 4910.0 4991.4 4996.8 5019.2 5250.6 05:12:55 INFO - PROCESS | 1951 | 05:12:55 INFO - PROCESS | 1951 | [#4] prototype.html Cycles:180 Average:4465.45 Median:4852.73 stddev:1344.76 (27.7%) stddev-sans-first:1311.30 05:12:55 INFO - PROCESS | 1951 | Values: 278.5 297.4 313.8 317.6 324.8 5210.7 5288.9 5301.7 5432.7 5534.7 4108.3 4855.7 5006.9 5101.8 5415.6 4116.0 4120.8 4142.5 4152.3 4164.5 3590.6 3874.3 3972.8 4023.3 4117.0 3486.8 3648.6 3798.9 3901.6 4014.3 3919.5 4099.0 4124.0 4134.2 4138.8 3584.6 3610.4 3614.8 3621.2 3636.7 4204.5 4411.9 4946.8 5040.6 5073.8 4395.9 4708.0 4741.5 4743.2 4748.2 5598.3 5660.2 5665.6 5694.3 5710.5 3607.6 3607.7 3731.8 4014.9 4039.7 5131.9 5283.7 5633.9 5821.9 6009.6 3915.2 4065.5 4072.7 4082.5 4104.0 3978.7 3999.8 4141.8 4143.3 4168.0 4235.8 4715.1 4734.2 4736.4 4766.3 5015.4 5256.7 5429.0 5658.2 5766.6 1245.5 1247.4 1293.8 1316.7 1351.7 857.6 897.5 920.4 934.5 934.9 4227.4 4524.7 4742.0 4906.4 4994.1 4673.9 5341.9 5505.5 5509.7 5547.8 4161.8 4345.5 4373.9 4821.4 4971.2 2508.4 2636.7 2941.2 2942.5 2956.5 4967.9 5809.6 5841.4 5913.0 5943.8 4624.7 5055.7 5255.9 5597.9 5786.9 5119.4 5669.6 5868.7 5891.9 5934.9 5430.6 5610.9 5640.8 5667.5 5676.9 4483.9 4675.5 4726.3 5019.8 5687.2 4849.8 5450.2 5667.8 5845.5 5868.9 3542.3 3850.6 3855.8 3874.4 3887.9 4675.0 5355.3 5518.3 5550.9 5583.3 5251.4 5537.4 5693.8 5702.3 5722.9 4537.5 4644.6 4826.5 5695.6 5702.6 4923.2 5269.5 5453.4 5553.5 5569.6 4973.7 5565.5 5665.7 5682.4 5697.9 4335.0 5122.3 5346.4 5348.6 5444.0 05:12:55 INFO - PROCESS | 1951 | 05:12:55 INFO - PROCESS | 1951 | [#5] yui.html Cycles:180 Average:17194.17 Median:595.77 stddev:23548.35 (3952.6%) stddev-sans-first:23581.49 05:12:55 INFO - PROCESS | 1951 | Values: 611.8 649.5 729.3 740.1 761.9 14928.6 15121.5 15309.9 16572.5 16940.0 15819.2 16266.2 16286.6 16370.7 16409.3 737.0 929.9 938.7 941.1 942.9 890.9 910.2 932.7 942.1 946.2 948.4 971.8 975.2 983.6 989.2 928.7 928.9 931.1 944.2 978.8 708.9 722.4 723.2 723.5 726.3 14599.1 14674.6 14859.6 14863.2 14865.3 12195.5 12210.8 12215.8 12275.1 12479.7 9763.9 9897.9 9947.6 9962.7 10290.2 3406.9 3419.6 3440.1 3443.2 3527.1 85674.3 85892.0 86856.6 86862.1 87312.1 9142.5 9263.0 9296.0 9499.7 9565.3 21248.4 21366.8 21391.4 21413.7 21560.9 7143.9 7186.0 7386.8 7436.7 7534.1 3239.4 3892.6 3973.8 3980.7 4065.6 3373.8 3440.2 3450.6 3455.6 3477.0 568.5 599.7 620.1 633.9 634.0 502.5 577.4 591.6 598.1 608.4 7210.2 7638.4 8231.3 8372.0 8573.3 506.3 534.4 569.9 587.0 593.4 3735.9 3749.0 3801.5 3826.4 3839.3 73113.0 78799.8 82032.6 82200.0 84990.6 56925.7 74565.3 74752.0 74858.3 75468.2 63052.7 65863.0 66078.0 66544.7 67002.3 7599.2 7671.9 7737.1 7778.9 7837.4 43338.7 43574.6 43704.0 44062.0 44536.3 41589.0 48392.0 52755.7 54231.5 55083.7 10238.9 10345.7 11036.6 11110.2 11160.3 11891.5 11968.2 12170.8 12426.8 12606.9 11127.9 11270.9 11541.2 11559.2 12275.4 11321.5 11397.7 11523.0 11707.2 11729.5 11075.2 11287.8 11534.4 11640.2 11938.8 9432.5 9480.0 9629.9 9633.3 9936.8 9222.0 9434.7 9565.6 9572.5 9785.3 05:12:55 INFO - PROCESS | 1951 | -------- Summary: end -------- 05:12:55 INFO - PROCESS | 1951 | 05:12:56 INFO - TEST-INFO | 1951: exit 0 05:12:56 INFO - TEST-OK | dromaeo_css | took 1112108ms 05:12:56 INFO - TEST-START | kraken 05:12:56 INFO - operating with platform_type : mac_ 05:12:56 INFO - Initialising browser for kraken test... 05:12:56 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpurOssH/profile http://localhost:51978/getInfo.html) 05:12:59 INFO - PROCESS | 1957 | __metrics Screen width/height:1600/1200 05:12:59 INFO - PROCESS | 1957 | colorDepth:24 05:12:59 INFO - PROCESS | 1957 | Browser inner width/height: 1024/689 05:12:59 INFO - PROCESS | 1957 | __metrics 05:12:59 ERROR - PROCESS | 1957 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 05:12:59 INFO - TEST-INFO | 1957: exit 0 05:12:59 INFO - Browser initialized. 05:12:59 INFO - Running cycle 1/1 for kraken test... 05:12:59 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpurOssH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:13:11 INFO - PROCESS | 1960 | RSS: Main: 250503168 05:13:11 INFO - PROCESS | 1960 | 05:13:14 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-beat-detection) 05:13:15 INFO - PROCESS | 1960 | RSS: Main: 262352896 05:13:15 INFO - PROCESS | 1960 | 05:13:18 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-dft) 05:13:19 INFO - PROCESS | 1960 | RSS: Main: 267018240 05:13:19 INFO - PROCESS | 1960 | 05:13:22 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-fft) 05:13:23 INFO - PROCESS | 1960 | RSS: Main: 267902976 05:13:23 INFO - PROCESS | 1960 | 05:13:25 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-oscillator) 05:13:25 INFO - PROCESS | 1960 | RSS: Main: 268210176 05:13:25 INFO - PROCESS | 1960 | 05:13:27 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51978/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:13:28 INFO - PROCESS | 1960 | RSS: Main: 268972032 05:13:28 INFO - PROCESS | 1960 | 05:13:31 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51978/tests/kraken/driver.html?testName=imaging-darkroom) 05:13:32 INFO - PROCESS | 1960 | RSS: Main: 268693504 05:13:32 INFO - PROCESS | 1960 | 05:13:35 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51978/tests/kraken/driver.html?testName=imaging-desaturate) 05:13:35 INFO - PROCESS | 1960 | RSS: Main: 268939264 05:13:35 INFO - PROCESS | 1960 | 05:13:39 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51978/tests/kraken/driver.html?testName=json-parse-financial) 05:13:40 INFO - PROCESS | 1960 | RSS: Main: 269037568 05:13:40 INFO - PROCESS | 1960 | 05:13:41 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51978/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:13:42 INFO - PROCESS | 1960 | RSS: Main: 268685312 05:13:42 INFO - PROCESS | 1960 | 05:13:44 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:13:44 INFO - PROCESS | 1960 | RSS: Main: 268795904 05:13:44 INFO - PROCESS | 1960 | 05:13:47 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:13:48 INFO - PROCESS | 1960 | RSS: Main: 269246464 05:13:48 INFO - PROCESS | 1960 | 05:13:51 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:13:51 INFO - PROCESS | 1960 | RSS: Main: 269189120 05:13:51 INFO - PROCESS | 1960 | 05:13:54 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:13:55 INFO - PROCESS | 1960 | RSS: Main: 269443072 05:13:55 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51978/tests/kraken/driver.html?testName=ai-astar) 05:13:57 INFO - PROCESS | 1960 | RSS: Main: 271712256 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | __start_tp_report 05:13:57 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 05:13:57 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 05:13:57 INFO - PROCESS | 1960 | |i|pagename|runs| 05:13:57 INFO - PROCESS | 1960 | |0;ai-astar;122;171;87;170;166;168;107;117;115;118 05:13:57 INFO - PROCESS | 1960 | |1;audio-beat-detection;151;121;140;196;122;136;134;124;167;117 05:13:57 INFO - PROCESS | 1960 | |2;audio-dft;197;200;232;258;199;201;197;198;200;197 05:13:57 INFO - PROCESS | 1960 | |3;audio-fft;72;78;77;176;58;57;57;72;161;55 05:13:57 INFO - PROCESS | 1960 | |4;audio-oscillator;79;87;147;77;80;79;124;110;114;130 05:13:57 INFO - PROCESS | 1960 | |5;imaging-gaussian-blur;90;87;88;78;136;130;90;86;102;130 05:13:57 INFO - PROCESS | 1960 | |6;imaging-darkroom;94;96;96;97;96;96;96;97;96;97 05:13:57 INFO - PROCESS | 1960 | |7;imaging-desaturate;142;143;96;142;146;97;116;148;91;92 05:13:57 INFO - PROCESS | 1960 | |8;json-parse-financial;61;61;60;62;61;62;65;126;63;60 05:13:57 INFO - PROCESS | 1960 | |9;json-stringify-tinderbox;69;66;67;81;78;71;68;68;69;68 05:13:57 INFO - PROCESS | 1960 | |10;stanford-crypto-aes;87;87;109;107;113;108;85;85;88;77 05:13:57 INFO - PROCESS | 1960 | |11;stanford-crypto-ccm;148;148;145;153;156;127;147;136;235;128 05:13:57 INFO - PROCESS | 1960 | |12;stanford-crypto-pbkdf2;169;165;241;227;204;170;170;167;173;168 05:13:57 INFO - PROCESS | 1960 | |13;stanford-crypto-sha256-iterative;73;71;66;70;68;68;66;66;68;67 05:13:57 INFO - PROCESS | 1960 | __end_tp_report 05:13:57 INFO - PROCESS | 1960 | __start_cc_report 05:13:57 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,1068 05:13:57 INFO - PROCESS | 1960 | __end_cc_report 05:13:57 INFO - PROCESS | 1960 | __startTimestamp1462796037460__endTimestamp 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | ------- Summary: start ------- 05:13:57 INFO - PROCESS | 1960 | Number of tests: 14 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#0] ai-astar Cycles:10 Average:134.10 Median:167.00 stddev:31.33 (18.8%) stddev-sans-first:32.92 05:13:57 INFO - PROCESS | 1960 | Values: 122.0 171.0 87.0 170.0 166.0 168.0 107.0 117.0 115.0 118.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:138.00 stddev:24.72 (17.9%) stddev-sans-first:25.95 05:13:57 INFO - PROCESS | 1960 | Values: 151.0 121.0 140.0 196.0 122.0 136.0 134.0 124.0 167.0 117.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#2] audio-dft Cycles:10 Average:207.90 Median:200.00 stddev:20.54 (10.3%) stddev-sans-first:21.40 05:13:57 INFO - PROCESS | 1960 | Values: 197.0 200.0 232.0 258.0 199.0 201.0 197.0 198.0 200.0 197.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#3] audio-fft Cycles:10 Average:86.30 Median:65.00 stddev:44.33 (68.2%) stddev-sans-first:46.72 05:13:57 INFO - PROCESS | 1960 | Values: 72.0 78.0 77.0 176.0 58.0 57.0 57.0 72.0 161.0 55.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:78.00 stddev:25.58 (32.8%) stddev-sans-first:25.65 05:13:57 INFO - PROCESS | 1960 | Values: 79.0 87.0 147.0 77.0 80.0 79.0 124.0 110.0 114.0 130.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#5] imaging-gaussian-blur Cycles:10 Average:101.70 Median:86.50 stddev:21.77 (25.2%) stddev-sans-first:22.67 05:13:57 INFO - PROCESS | 1960 | Values: 90.0 87.0 88.0 78.0 136.0 130.0 90.0 86.0 102.0 130.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:96.00 stddev:0.88 (0.9%) stddev-sans-first:0.50 05:13:57 INFO - PROCESS | 1960 | Values: 94.0 96.0 96.0 97.0 96.0 96.0 96.0 97.0 96.0 97.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#7] imaging-desaturate Cycles:10 Average:121.30 Median:119.50 stddev:25.14 (21.0%) stddev-sans-first:25.52 05:13:57 INFO - PROCESS | 1960 | Values: 142.0 143.0 96.0 142.0 146.0 97.0 116.0 148.0 91.0 92.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#8] json-parse-financial Cycles:10 Average:68.10 Median:61.50 stddev:20.40 (33.2%) stddev-sans-first:21.47 05:13:57 INFO - PROCESS | 1960 | Values: 61.0 61.0 60.0 62.0 61.0 62.0 65.0 126.0 63.0 60.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:69.00 stddev:4.97 (7.2%) stddev-sans-first:5.24 05:13:57 INFO - PROCESS | 1960 | Values: 69.0 66.0 67.0 81.0 78.0 71.0 68.0 68.0 69.0 68.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#10] stanford-crypto-aes Cycles:10 Average:94.60 Median:85.00 stddev:13.05 (15.4%) stddev-sans-first:13.55 05:13:57 INFO - PROCESS | 1960 | Values: 87.0 87.0 109.0 107.0 113.0 108.0 85.0 85.0 88.0 77.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:148.00 stddev:30.67 (20.7%) stddev-sans-first:32.50 05:13:57 INFO - PROCESS | 1960 | Values: 148.0 148.0 145.0 153.0 156.0 127.0 147.0 136.0 235.0 128.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:171.50 stddev:28.13 (16.4%) stddev-sans-first:29.21 05:13:57 INFO - PROCESS | 1960 | Values: 169.0 165.0 241.0 227.0 204.0 170.0 170.0 167.0 173.0 168.0 05:13:57 INFO - PROCESS | 1960 | 05:13:57 INFO - PROCESS | 1960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:2.36 (3.5%) stddev-sans-first:1.79 05:13:57 INFO - PROCESS | 1960 | Values: 73.0 71.0 66.0 70.0 68.0 68.0 66.0 66.0 68.0 67.0 05:13:57 INFO - PROCESS | 1960 | -------- Summary: end -------- 05:13:57 INFO - PROCESS | 1960 | 05:13:58 INFO - TEST-INFO | 1960: exit 0 05:13:58 INFO - TEST-OK | kraken | took 61851ms 05:13:58 INFO - SUITE-END | took 1173s 05:13:58 INFO - Completed test suite (00:19:34) 05:13:58 INFO - javascript benchmark 05:13:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3489.3719130556506, 3502.4921386398955, 3522.0, 3539.716822654188, 3540.628234035426, 5892.410758924111, 5898.823035308937, 5901.822945311642, 5972.17306340573, 6087.512998960084, 5759.1937128802, 5910.46805787479, 5932.110183472481, 5949.910751338723, 6013.158157857893, 5269.710165940872, 5316.680999140064, 5403.594730395216, 5413.945860541403, 5516.117421212607, 330.66488870468584, 5293.232481290211, 5368.651037682557, 5399.676019438826, 5408.215808707755, 5345.492178243061, 5383.057964856134, 5388.272583201277, 5394.811181608625, 5397.514223719864, 5374.543163831079, 5398.514133727944, 5406.918896216521, 5455.699936503491, 5485.341758988947, 5238.685678859242, 5270.736463176864, 5325.547328477084, 5372.409035006146, 5415.64798288114, 5713.714314284269, 5721.599488035798, 5779.653220806765, 5819.388964158745, 5845.620034697731, 5156.535911767922, 6203.5037197024585, 6259.248890133168, 6268.905966410507, 6274.058891166316, 5293.338332708411, 6161.476274516672, 6177.1969643945995, 6206.689665516707, 6290.3709629037185, 6044.999999999956, 6050.636961782263, 6075.908861367083, 6076.392360763933, 6081.087836824512, 5569.415211402826, 6238.845813524513, 6420.036994450869, 6444.13004244421, 6451.0, 4863.610911127102, 6100.48145907594, 6130.703893784946, 6165.10605962133, 6230.096635987757, 5308.442613525524, 6221.96889015552, 6358.427741503287, 6359.459445947146, 6361.936380636227, 5430.212619170198, 6173.259208894961, 6175.228096487939, 6253.312135665074, 6260.499160067184, 6190.195274614362, 6233.657148856856, 6236.968815155895, 6241.157443745127, 6268.028455589391, 5081.4918508148885, 5084.77118529667, 5091.08360495107, 5103.7192954387865, 5123.2827404163445, 4484.573965473275, 4486.506484286726, 4568.0863827234025, 4573.771311434415, 4582.060677561092, 5879.911801322984, 5956.880862382728, 5973.880522389528, 5974.731137098841, 6039.486643635252, 6293.465055470337, 6303.527235457359, 6328.11406403104, 6342.080398342215, 6357.396047375492, 5898.734556944948, 5909.379515150933, 5920.615160014585, 5938.28740551128, 6030.879382412327, 5472.534834539029, 5476.0, 5488.368837583649, 5491.258680078138, 5504.944950550524, 6440.098386225838, 6445.581037232565, 6463.4506066983895, 6474.546781725234, 6477.125588045554, 6142.253225532332, 6195.597286176384, 6221.564490485623, 6237.75048998035, 6351.30135685065, 6506.089147519257, 6507.869842603216, 6522.28254891952, 6533.281339052795, 6544.116544266653, 6152.846178845564, 6245.594036387621, 6263.874722505616, 6275.497960163268, 6283.842903927439, 6186.566940314135, 6189.195404597373, 6237.563370564017, 6282.842928926813, 6293.087502312231, 6071.787487437919, 6183.0, 6244.65654389013, 6248.218972628421, 6256.061590761422, 6079.331273559993, 6142.468080853617, 6241.563090583615, 6256.436920677161, 6271.78048768291, 6034.336223015377, 6083.0, 6118.223266370627, 6136.335271946613, 6138.140660307471, 6175.776720380749, 6205.906911396423, 6216.844578885564, 6217.4715149212825, 6246.344133865879, 6005.549583781147, 6085.1480792689945, 6093.817185484442, 6136.846578835565, 6163.075538669235], "value": 5762.847526739024, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.8158686293776, 516.2952569742301, 545.0733752620546, 553.6346011632322, 564.9322081350233, 39776.40335394968, 42222.366664500056, 42298.788506057426, 42354.364684529755, 42363.36454953178, 42388.576114238924, 42693.0, 43078.56921430785, 43088.3536746949, 43235.13529729404, 9630.470324132153, 9751.317407781458, 9782.413055216673, 9809.901900981005, 9820.214382849355, 8318.0, 9553.474558899257, 9643.951780241088, 9651.95174024129, 9667.75830604237, 9628.374155679858, 9631.566579503939, 9675.225981921429, 9688.418694878295, 9721.90278097217, 9598.088181622734, 9629.085236902483, 9636.759081022961, 9637.325387222898, 9666.178374838111, 8282.544460054694, 8557.828843423158, 9588.041195880425, 9591.232701383942, 9605.951970240174, 38748.031299217466, 39381.80309098465, 39416.408753868716, 39718.60281397178, 39824.20351592952, 2773.9583906241423, 3055.6027716396725, 3122.4223518649123, 3314.3371325734706, 3337.9499307510405, 26785.598216026774, 27584.0, 28253.858730706215, 28948.710512894813, 29116.0, 2596.402827349701, 2650.310919161032, 2682.342826007621, 2730.8361498310164, 2753.6420265365373, 338282.0, 426542.0, 429716.0, 430232.0, 432766.83616582345, 32839.671603284136, 35365.1158721034, 36016.0, 36038.639613603526, 36610.63389366125, 36928.26143477116, 37498.43752343717, 37707.434388484195, 37875.24249514994, 38074.619253807665, 15112.697746045018, 15163.924180379028, 15288.770668439982, 15304.617384565252, 15326.693466130615, 12288.262704237774, 12362.75274494505, 12763.298018609066, 12778.61664150077, 12824.358782060859, 8193.97575303087, 8195.180481951877, 8249.010118785784, 8324.958375208207, 8360.83278334442, 3028.242939265184, 3443.139215196201, 3463.2380876207194, 3471.687548120681, 3478.3565040467593, 921.3504479342048, 926.8795056642729, 1041.463646222196, 1082.3884505254507, 1131.1742428027585, 25179.62230566543, 28050.0, 29617.0, 29855.701442985726, 30455.543166852516, 1095.7917995580815, 1096.4517741129434, 1111.9110471162287, 1157.8610566731877, 1194.4326444938586, 15178.772318415233, 15333.693326133414, 15429.845701543065, 15628.765568516481, 15734.606634834221, 683768.0, 686165.0, 695160.0, 699922.0007799958, 702617.0, 61229.0, 61456.77086458246, 62031.0, 62114.68942655348, 62278.0658290126, 1458.1907041591805, 1481.3482067890093, 1492.2687882937282, 1503.9022463539834, 1504.3155364309214, 28864.278393040342, 29001.41997160003, 29228.415431691672, 29665.0, 30068.849655751583, 52132.73933630308, 52917.73541132424, 53245.733771330895, 53299.20051199313, 53407.7329613365, 602821.0, 625436.0, 628602.0, 628751.0, 629315.0, 22720.772792271866, 26704.599431008937, 27602.447951041268, 27656.0, 27717.58423623607, 45252.77373613111, 45852.77073614611, 46167.0, 46918.76540617275, 46919.0, 37803.05492362603, 37950.62049379581, 38196.42705359477, 38605.42091868568, 38911.0, 37450.43824342582, 37740.2451950965, 37769.62230377661, 38340.61659383482, 38466.0, 31746.523802142525, 32345.838270809436, 37491.81254093712, 37835.43246851354, 38732.61267387403, 32331.838340808146, 33601.49597755987, 33696.49455258222, 33745.81889633949, 33801.49297760585, 24988.37529061788, 25542.10602628899, 25604.103856364938, 25670.61494077553, 25876.48247035011], "value": 18412.996494884486, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.8658412847568, 390.23319177815586, 390.23513912731045, 390.9218156368726, 416.02857328138793, 18981.71527427088, 20288.1884724611, 20329.085191166396, 20646.793532064676, 21502.92485375733, 20086.49783755403, 20573.868437235946, 20731.274405395816, 20778.0, 21259.893700531476, 5677.630953987988, 5838.2702162229725, 5958.910616340737, 5958.910616340759, 5966.910496342558, 5592.105263157896, 5621.634593751393, 5815.156802263669, 5841.240638716961, 5921.763129474816, 5827.125931110346, 5851.6196447231005, 5855.268091488564, 5857.707114644272, 5864.384239654839, 5566.471185237396, 5737.282839645044, 5850.970745146268, 5864.354920958713, 5889.852753681193, 5140.357455318085, 5172.560332371753, 5173.146430838907, 5177.171652535614, 5178.042062218502, 25570.23289301324, 25732.48535029308, 25782.742172578222, 26064.86967565169, 26280.68596570183, 2076.7404074490687, 2120.777318381563, 2143.2820005298245, 2230.39779259599, 2289.645105008726, 9612.95193524035, 14920.925395372953, 14951.775723364159, 15118.546443606709, 15385.692286154213, 1703.769991051217, 1716.0218675355052, 1781.145050375814, 1837.0998210876708, 1865.337805079191, 131814.0, 133161.0, 133263.0, 135189.32405338009, 138338.0, 11786.349911012465, 11838.28970261787, 11848.170628056127, 11983.34091624969, 11992.520299187934, 15114.244287785787, 15143.772843407358, 15146.621334466507, 15207.771883421758, 15212.239388030775, 8611.95694021526, 8640.870386944202, 8685.39202255836, 8689.304855611535, 8819.029906710257, 8484.024337201301, 8613.612387442456, 8657.047724750273, 8711.34664900135, 8712.564371781513, 3949.499190307722, 4616.3537104805355, 4689.015306785614, 4729.597984171384, 4821.975890120527, 1970.940871773849, 2008.517955690653, 2011.5172358634115, 2016.6067616814873, 2018.8283995860222, 905.4431524612316, 957.8946315905245, 969.6509256667736, 983.8573406855966, 1016.2276669731058, 19028.904855475634, 19615.705764413546, 20685.379438616867, 20833.958302084837, 20928.058237379355, 931.6366617019368, 955.7371722776153, 957.8132264208551, 976.6093562575027, 1042.0985847242082, 9033.36766426357, 9725.610975560898, 10023.747031621047, 10860.511276992554, 11118.221724479208, 580672.0, 634008.0, 641314.0, 646466.767666168, 655946.7202663956, 145520.0, 148273.25863370614, 149865.25067374812, 151068.0, 153912.0, 991.6925753016444, 1020.3622735790132, 1042.0881728487573, 1043.9739006524899, 1047.580967612976, 14397.96832984796, 18121.912685238927, 18226.36207732723, 18243.726344104583, 18408.26366945307, 54863.177052343446, 55545.72227138838, 55585.44414555802, 56531.15203272036, 56546.43453565576, 80782.59608702153, 83793.58103209444, 84310.57844710737, 85498.57250713707, 86925.56537217273, 7887.84224315522, 8107.83784324322, 8111.513309201467, 8130.87803682957, 8180.918190818015, 11362.829557556477, 12149.331786751813, 12369.876301237233, 12441.813372799596, 12449.751004980031, 10276.0, 10495.0, 10701.090407315465, 10759.731006724582, 10769.299995500267, 10403.0, 10505.894941050798, 10616.469176540988, 10646.307989980627, 10705.0, 8888.86666700013, 10747.516361763895, 11027.0075693188, 11042.33745975212, 11096.00135987765, 7931.722389716332, 8044.195580442143, 8051.557164355782, 8120.75637730869, 8276.503409795432, 10283.588656453958, 10616.84074738864, 10832.24174307791, 10857.239993200716, 10860.511276992713], "value": 9719.112074755923, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1599.488163787588, 1636.4190712297134, 1857.7863545692248, 1944.368080373879, 1981.8612697111207, 7814.687412503507, 8437.071922088573, 8461.0, 8951.55242237888, 8960.686375976842, 8518.233358997704, 8636.136386361377, 8774.122587741223, 8776.736697899058, 8782.25350845179, 2390.426297688551, 2400.987995060022, 2431.4893872286752, 2438.548868459332, 2441.340837973746, 2409.4578719788083, 2413.553492603865, 2443.4013666651695, 2443.9389015274583, 2450.7304196538366, 2419.5523828091773, 2433.6227884677905, 2435.488547405047, 2446.107170882633, 2448.9510209795794, 2401.9759802402014, 2409.759024097585, 2411.3007227903886, 2411.590029694947, 2434.0628857889665, 2096.4968407582223, 2327.860328380286, 2385.6540801583674, 2403.0868270057267, 2412.601920683085, 8748.387612867102, 8808.691695790616, 8834.204921557026, 8837.690680826205, 8852.0, 5287.841364759063, 5397.7031263280505, 5414.512693857571, 5444.183372494118, 5449.536789372908, 3409.7613167078316, 3493.0568746438576, 3570.982145089284, 3588.049166970729, 3601.58581763098, 1030.2118879057527, 1044.2898828796415, 1045.7856139491387, 1053.2890299048188, 1066.4401189375562, 11417.885821141765, 11431.428428578538, 11531.94234028833, 11538.59614913474, 11543.88456115445, 4527.47933987589, 4540.614047805973, 4633.976830115827, 4680.2449081594405, 4686.601638860667, 9058.048904865025, 9076.909230907739, 9090.90909090914, 9113.635454581743, 9122.996470388252, 5176.715280659525, 5235.476452354734, 5263.578913686896, 5272.92090618641, 5283.128283833124, 4390.604845563915, 4769.403824521934, 4887.120318342661, 4930.0139972005745, 4947.48051454592, 3769.396896496602, 3809.6190380962234, 3865.439511270906, 3901.7073719471155, 3917.6474117329008, 1049.41757324684, 1215.1918973882446, 1216.045404357575, 1219.676785651803, 1221.7251118498446, 930.3952430919821, 932.6269492203037, 940.3558562384789, 959.9856002159974, 984.5028260728286, 4825.927611085766, 5321.893562128813, 5791.652500849962, 5841.094630332305, 6079.669795566371, 378.7046104038854, 1010.3382284603597, 1015.0509273828993, 1090.2095980414294, 1132.3488993828416, 3911.02224443889, 3924.3917192835156, 3960.4257382679925, 3967.2263908538134, 4041.6362527372676, 10052.64815731446, 10624.734381640521, 10740.946295268473, 10749.78500429987, 10762.677119686421, 8665.870011949699, 10599.364038157735, 10601.416922069206, 10646.254762166574, 10700.625478108228, 8082.313003394777, 8084.0, 8184.181581841768, 8252.958735206286, 8268.0, 5694.572907031907, 5827.650340979555, 5857.736401862012, 5919.112133180058, 6055.394460553909, 9569.234461243223, 9571.617135314787, 9947.651832185838, 10007.299489035697, 10019.398836069859, 8094.231048050426, 10021.448820314952, 10038.49807509607, 10158.339707918962, 10177.49112544355, 6814.591124532544, 7019.227884932756, 7237.384822290164, 7432.405407567489, 7585.089789225328, 8285.54429506383, 8317.376196785386, 8382.664693412195, 8415.537145456814, 8503.829923401374, 7251.854962900818, 7353.632318384166, 7441.516301440389, 7461.291177338144, 7518.135414427411, 7218.386437152691, 7259.310365515267, 7321.194668586558, 7321.963390183015, 7349.154847192642, 6923.719111964303, 7020.508564400443, 7029.297070293134, 7041.43668506508, 7041.5422997505, 4812.25410061441, 4830.227163653799, 4857.125717370765, 4972.8508144755715, 5071.011152825164, 4910.042541704333, 4991.42598601151, 4996.8251111212385, 5019.222020586815, 5250.632455728062], "value": 4570.10159723367, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.5487510233421, 297.4155783884666, 313.7615412286662, 317.5617647646248, 324.8343344894103, 5210.713410762407, 5288.89422211556, 5301.655392399501, 5432.72836358182, 5534.723263836807, 4108.342665173573, 4855.7086574805535, 5006.899862002758, 5101.750071232544, 5415.593830462712, 4116.0, 4120.847551623011, 4142.544320124784, 4152.335626299792, 4164.500259968808, 3590.569131704199, 3874.3073563873118, 3972.781497017663, 4023.295923213426, 4117.0, 3486.755927085105, 3648.5804132524836, 3798.917308567059, 3901.6293452122004, 4014.3175660137845, 3919.549251836047, 4098.954766534544, 4124.030852749602, 4134.1731653669085, 4138.841124485119, 3584.5698516177963, 3610.368185567515, 3614.765040272374, 3621.203335266237, 3636.690881275095, 4204.516480604739, 4411.867643970686, 4946.788036930939, 5040.6471546991725, 5073.822416215452, 4395.85707715993, 4707.952920470786, 4741.482725527832, 4743.150171433143, 4748.169070412699, 5598.312809851253, 5660.245323949589, 5665.575081868835, 5694.259746233005, 5710.457506536873, 3607.621199774012, 3607.729420293462, 3731.7947512887054, 4014.859479918218, 4039.6970227233073, 5131.897362052775, 5283.68297902131, 5633.8591535211535, 5821.922944255284, 6009.6394216346725, 3915.197384536163, 4065.4714887064492, 4072.735272207297, 4082.4896887889013, 4103.958960410358, 3978.701597380208, 3999.8200080996426, 4141.81361838718, 4143.274926887782, 4168.0205151789305, 4235.7670328131635, 4715.0805592909055, 4734.19518681818, 4736.407949006374, 4766.261229509431, 5015.445211984296, 5256.710880901511, 5429.022775900376, 5658.151277308437, 5766.596338256281, 1245.4893493667553, 1247.4324182496941, 1293.7606542789615, 1316.7096245679288, 1351.6620844788804, 857.5540718826176, 897.4615230861431, 920.4385324951774, 934.5467448287575, 934.9345545811864, 4227.408162857202, 4524.683272170916, 4742.0, 4906.386701662293, 4994.076095922266, 4673.854905548163, 5341.866453338697, 5505.476979686923, 5509.696966666792, 5547.7780888764, 4161.771102589387, 4345.478542574848, 4373.868783936487, 4821.397325334333, 4971.229459433794, 2508.41052352697, 2636.7080130735803, 2941.249981254766, 2942.4997750382486, 2956.4826155422716, 4967.925481117786, 5809.564282678816, 5841.378345425863, 5913.0, 5943.791967281124, 4624.722516649012, 5055.710793747571, 5255.868603284948, 5597.944020559742, 5786.942130578725, 5119.436861945183, 5669.6314739541795, 5868.735906884201, 5891.852703682443, 5934.910976335358, 5430.565554755531, 5610.859728506657, 5640.802571909963, 5667.5465962722055, 5676.914846277392, 4483.865484035484, 4675.509071547439, 4726.292857461908, 5019.774110164978, 5687.203791469115, 4849.75751212445, 5450.155225939986, 5667.773289068391, 5845.503132233808, 5868.911966320423, 3542.273833864, 3850.5956874528893, 3855.7879316637855, 3874.4382064599913, 3887.8639247626197, 4674.976625116853, 5355.250264962986, 5518.310211223597, 5550.9461499245535, 5583.274174357308, 5251.448597897319, 5537.446255374431, 5693.83991884508, 5702.287214098238, 5722.856928576819, 4537.455505339381, 4644.58198762113, 4826.541478559671, 5695.572832037697, 5702.6293290935955, 4923.163062279349, 5269.4730526947, 5453.400125986218, 5553.4724201200825, 5569.554435645057, 4973.726445045557, 5565.471280228372, 5665.688387138747, 5682.403347648603, 5697.857553561028, 4334.9812793994015, 5122.282880396673, 5346.411894691503, 5348.572114230775, 5444.012935278674], "value": 4039.7400019865777, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.7858749437695, 649.4577028181468, 729.2561587181078, 740.1451323721102, 761.9009528761263, 14928.552143435702, 15121.47074852379, 15309.85176111792, 16572.502824915242, 16940.0, 15819.209039548005, 16266.186690665478, 16286.592835179126, 16370.672586548262, 16409.261583228752, 737.0124033794714, 929.8651695504165, 938.699616122841, 941.0589410589411, 942.9198518125934, 890.9198172164504, 910.1535571918114, 932.7434955387255, 942.1002942190227, 946.2240962410826, 948.4167237149138, 971.7764914069768, 975.1564896364664, 983.6409710455667, 989.154273096503, 928.7074571509953, 928.9396189247677, 931.1479995803832, 944.2163004706077, 978.750418643245, 708.9184743754482, 722.4148439763756, 723.1864152828068, 723.464636169231, 726.2555880222814, 14599.124052556774, 14674.559763207015, 14859.628509287246, 14863.182524961123, 14865.331060102213, 12195.45120469572, 12210.75578488434, 12215.755684886253, 12275.140740148194, 12479.688007799787, 9763.90236097644, 9897.90102098977, 9947.602095916154, 9962.701118966443, 10290.228232882564, 3406.897793066187, 3419.5554577904963, 3440.122768693967, 3443.1736383268085, 3527.0829584308262, 85674.28651426936, 85892.0, 86856.565717171, 86862.13137868667, 87312.12687873104, 9142.451452912846, 9262.953685231665, 9296.0, 9499.715008549754, 9565.330426870052, 21248.36254912355, 21366.78633213648, 21391.358259252247, 21413.67879481809, 21560.892195538923, 7143.9285607143265, 7186.0, 7386.815329616695, 7436.702531898772, 7534.133574638877, 3239.4169049570833, 3892.5523564789846, 3973.761574305493, 3980.7014473914574, 4065.6340929316502, 3373.797572145679, 3440.2431465077643, 3450.6204317525053, 3455.585329760445, 3477.0, 568.5480043365452, 599.6851652882272, 620.1225266248298, 633.9492840572743, 634.0362648773838, 502.4549510255977, 577.4398833131855, 591.5858898770878, 598.1252789763463, 608.4189598933008, 7210.242924492957, 7638.388928885676, 8231.300339471212, 8371.958140209383, 8573.314134869195, 506.2836086936943, 534.3507638219504, 569.9316082070095, 586.9640085249538, 593.4214141212284, 3735.9252814944093, 3748.981255093707, 3801.5248093988253, 3826.3686491728554, 3839.2897313997, 73113.0, 78799.81800273001, 82032.58983705043, 82200.0, 84990.57504712437, 56925.71537142288, 74565.25434745691, 74752.0, 74858.25141748512, 75468.24531754722, 63052.684736576026, 65863.0, 66078.00882986758, 66544.66727666331, 67002.3299766996, 7599.164091949991, 7671.923280767121, 7737.14891361961, 7778.88331675014, 7837.37301015906, 43338.69983900488, 43574.56425435705, 43704.0, 44062.01720922502, 44536.331955020054, 41589.0, 48392.0, 52755.73622131865, 54231.45768542264, 55083.72458137684, 10238.897611024093, 10345.74135646615, 11036.613718519813, 11110.222284440011, 11160.330380177214, 11891.464884080064, 11968.222065565711, 12170.817437738619, 12426.813597796221, 12606.936965315115, 11127.88872111301, 11270.887291126985, 11541.192116551762, 11559.248648837798, 12275.386230688251, 11321.490532926204, 11397.658070257905, 11523.0, 11707.239029463057, 11729.530818767154, 11075.169362297698, 11287.7742445149, 11534.43375056108, 11640.243384180001, 11938.761224775282, 9432.481213533047, 9480.014849647321, 9629.855552166864, 9633.32566720323, 9936.751581210528, 9221.970927278322, 9434.679144919579, 9565.569549370433, 9572.473513956798, 9785.315027947976], "value": 6753.810292420332, "unit": "score"}], "name": "dromaeo_css", "value": 7104.660577393162, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 171.0, 87.0, 170.0, 166.0, 168.0, 107.0, 117.0, 115.0, 118.0], "value": 134.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 121.0, 140.0, 196.0, 122.0, 136.0, 134.0, 124.0, 167.0, 117.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 232.0, 258.0, 199.0, 201.0, 197.0, 198.0, 200.0, 197.0], "value": 207.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 78.0, 77.0, 176.0, 58.0, 57.0, 57.0, 72.0, 161.0, 55.0], "value": 86.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 87.0, 147.0, 77.0, 80.0, 79.0, 124.0, 110.0, 114.0, 130.0], "value": 102.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 88.0, 78.0, 136.0, 130.0, 90.0, 86.0, 102.0, 130.0], "value": 101.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 97.0, 96.0, 97.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 143.0, 96.0, 142.0, 146.0, 97.0, 116.0, 148.0, 91.0, 92.0], "value": 121.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 62.0, 61.0, 62.0, 65.0, 126.0, 63.0, 60.0], "value": 68.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 67.0, 81.0, 78.0, 71.0, 68.0, 68.0, 69.0, 68.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 109.0, 107.0, 113.0, 108.0, 85.0, 85.0, 88.0, 77.0], "value": 94.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 145.0, 153.0, 156.0, 127.0, 147.0, 136.0, 235.0, 128.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 165.0, 241.0, 227.0, 204.0, 170.0, 170.0, 167.0, 173.0, 168.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 66.0, 70.0, 68.0, 68.0, 66.0, 66.0, 68.0, 67.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1630.1, "alertThreshold": 2.0}]} 05:13:58 INFO - Return code: 0 05:13:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:13:58 ERROR - # TBPL SUCCESS # 05:13:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:13:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:13:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:13:58 INFO - 2016-05-09 05:13:58.786 screenresolution[1965:14666] starting screenresolution argv=screenresolution get 05:13:58 INFO - 2016-05-09 05:13:58.788 screenresolution[1965:14666] Display 0: 1600x1200x32@60 05:13:58 INFO - 2016-05-09 05:13:58.794 screenresolution[1966:14669] starting screenresolution argv=screenresolution list 05:13:58 INFO - Available Modes on Display 0 05:13:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:13:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:13:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:13:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:13:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:13:59 INFO - Intel Iris: 05:13:59 INFO - Chipset Model: Intel Iris 05:13:59 INFO - Type: GPU 05:13:59 INFO - Bus: Built-In 05:13:59 INFO - VRAM (Dynamic, Max): 1536 MB 05:13:59 INFO - Vendor: Intel (0x8086) 05:13:59 INFO - Device ID: 0x0a2e 05:13:59 INFO - Revision ID: 0x0009 05:13:59 INFO - Displays: 05:13:59 INFO - Display: 05:13:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:13:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:13:59 INFO - Display Serial Number: 1600x1200 60 05:13:59 INFO - Main Display: Yes 05:13:59 INFO - Mirror: Off 05:13:59 INFO - Online: Yes 05:13:59 INFO - Rotation: Supported 05:13:59 INFO - Return code: 0 05:13:59 INFO - Running post-action listener: _resource_record_post_action 05:13:59 INFO - ##### 05:13:59 INFO - ##### Finished run-tests step (success) 05:13:59 INFO - ##### 05:13:59 INFO - Running post-run listener: _resource_record_post_run 05:14:00 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 569344; Write bytes: 698992640; Read time: 11; Write time: 2159 05:14:00 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 384184832; Write bytes: 379574272; Read time: 28303; Write time: 743 05:14:00 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 569344; Write bytes: 297963520; Read time: 11; Write time: 1304 05:14:00 INFO - Running post-run listener: _upload_blobber_files 05:14:00 INFO - Blob upload gear active. 05:14:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:14:00 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:00 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:00 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:00 INFO - (blobuploader) - INFO - Open directory for files ... 05:14:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:14:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:14:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:01 INFO - (blobuploader) - INFO - Done attempting. 05:14:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:14:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:14:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:02 INFO - (blobuploader) - INFO - Done attempting. 05:14:02 INFO - (blobuploader) - INFO - Iteration through files over. 05:14:02 INFO - Return code: 0 05:14:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:14:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:14:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/375ab00da93f50e52b39f70203b6e40af429cdad4211e4f359de17e0a2f081a3fc293e126138e562ddf1a5cc6758355b87fe8be9f058b082fce704f64fe51a2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7f1b32d8425e9af1ec8da4aa9f766e3de15e2e5733e362eb950f330aa306509d697d6eca467c7c6f4f6d17608daa9f9474476795e722eb39f2528275c213713"} 05:14:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:14:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:14:02 INFO - Contents: 05:14:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/375ab00da93f50e52b39f70203b6e40af429cdad4211e4f359de17e0a2f081a3fc293e126138e562ddf1a5cc6758355b87fe8be9f058b082fce704f64fe51a2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7f1b32d8425e9af1ec8da4aa9f766e3de15e2e5733e362eb950f330aa306509d697d6eca467c7c6f4f6d17608daa9f9474476795e722eb39f2528275c213713"} 05:14:02 INFO - Running post-run listener: copy_logs_to_upload_dir 05:14:02 INFO - Copying logs to upload dir... 05:14:02 INFO - mkdir: /builds/slave/test/build/upload/logs 05:14:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.915415 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-09 05:14:02.722342) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 05:14:02.727917) ========= 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.VoyLUF0NxP/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.SKkyZz59yK/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/375ab00da93f50e52b39f70203b6e40af429cdad4211e4f359de17e0a2f081a3fc293e126138e562ddf1a5cc6758355b87fe8be9f058b082fce704f64fe51a2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7f1b32d8425e9af1ec8da4aa9f766e3de15e2e5733e362eb950f330aa306509d697d6eca467c7c6f4f6d17608daa9f9474476795e722eb39f2528275c213713"} 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.011345 build_url: 'https://queue.taskcluster.net/v1/task/JSpieCh8TjaZdhVZeINOyg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/375ab00da93f50e52b39f70203b6e40af429cdad4211e4f359de17e0a2f081a3fc293e126138e562ddf1a5cc6758355b87fe8be9f058b082fce704f64fe51a2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7f1b32d8425e9af1ec8da4aa9f766e3de15e2e5733e362eb950f330aa306509d697d6eca467c7c6f4f6d17608daa9f9474476795e722eb39f2528275c213713"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 05:14:02.759397) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:14:02.759767) ========= 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.VoyLUF0NxP/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.SKkyZz59yK/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.004530 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:14:02.811819) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 05:14:02.812177) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 05:14:02.812544) ========= ========= Total master_lag: 0.19 =========