builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0175 starttime: 1463488229.75 results: success (0) buildid: 20160515203829 builduid: fd462b370e5544bdae0208c7f82b5fc7 revision: d0be57e84807ce0853b2406de7ff6abb195ac898 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:29.754097) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:29.754535) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:29.754852) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:29.775447) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:29.775780) ========= 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.EZfroAETp7/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.wJnDGx7gN5/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-17 05:30:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 78.2M=0s 2016-05-17 05:30:29 (78.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.074918 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:29.870600) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:29.871124) ========= 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.EZfroAETp7/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.wJnDGx7gN5/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.026289 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:29.920201) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:29.920587) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d0be57e84807ce0853b2406de7ff6abb195ac898 --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 d0be57e84807ce0853b2406de7ff6abb195ac898 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EZfroAETp7/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.wJnDGx7gN5/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-17 05:30:29,998 truncating revision to first 12 chars 2016-05-17 05:30:29,998 Setting DEBUG logging. 2016-05-17 05:30:29,998 attempt 1/10 2016-05-17 05:30:29,998 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d0be57e84807?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 05:30:30,368 unpacking tar archive at: mozilla-central-d0be57e84807/testing/mozharness/ program finished with exit code 0 elapsedTime=0.621702 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:30.558051) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:30.558361) ========= script_repo_revision: d0be57e84807ce0853b2406de7ff6abb195ac898 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:30.558746) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:30.559006) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 05:30:30.571485) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-17 05:30:30.571764) ========= /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.EZfroAETp7/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.wJnDGx7gN5/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:30:30 INFO - MultiFileLogger online at 20160517 05:30:30 in /builds/slave/test 05:30:30 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 05:30:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:30:30 INFO - {'append_to_log': False, 05:30:30 INFO - 'base_work_dir': '/builds/slave/test', 05:30:30 INFO - 'blob_upload_branch': 'Firefox', 05:30:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:30:30 INFO - 'branch': 'Firefox', 05:30:30 INFO - 'buildbot_json_path': 'buildprops.json', 05:30:30 INFO - 'config_files': ('talos/mac_config.py',), 05:30:30 INFO - 'default_actions': ('clobber', 05:30:30 INFO - 'read-buildbot-config', 05:30:30 INFO - 'download-and-extract', 05:30:30 INFO - 'populate-webroot', 05:30:30 INFO - 'create-virtualenv', 05:30:30 INFO - 'install', 05:30:30 INFO - 'run-tests'), 05:30:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:30:30 INFO - 'download_minidump_stackwalk': True, 05:30:30 INFO - 'download_symbols': 'ondemand', 05:30:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:30:30 INFO - 'tooltool.py': '/tools/tooltool.py', 05:30:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:30:30 INFO - '/tools/misc-python/virtualenv.py')}, 05:30:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:30:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:30:30 INFO - 'installer_path': 'installer.exe', 05:30:30 INFO - 'log_level': 'info', 05:30:30 INFO - 'log_name': 'talos', 05:30:30 INFO - 'log_to_console': True, 05:30:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:30:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:30:30 INFO - 'opt_config_files': (), 05:30:30 INFO - 'pip_index': False, 05:30:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:30 INFO - 'cmd': ('bash', 05:30:30 INFO - '-c', 05:30:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:30 INFO - 'enabled': True, 05:30:30 INFO - 'halt_on_failure': False, 05:30:30 INFO - 'name': 'check_screen_resolution'},), 05:30:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:30 INFO - 'cmd': ('bash', 05:30:30 INFO - '-c', 05:30:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:30 INFO - 'enabled': True, 05:30:30 INFO - 'halt_on_failure': False, 05:30:30 INFO - 'name': 'check_screen_resolution'},), 05:30:30 INFO - 'run_cmd_checks_enabled': True, 05:30:30 INFO - 'sps_profile': False, 05:30:30 INFO - 'sps_profile_interval': 0, 05:30:30 INFO - 'suite': 'dromaeojs-e10s', 05:30:30 INFO - 'system_bits': '32', 05:30:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:30:30 INFO - 'title': 't-yosemite-r7-0175', 05:30:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:30:30 INFO - 'use_talos_json': True, 05:30:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:30:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:30:30 INFO - 'work_dir': 'build'} 05:30:30 INFO - ##### 05:30:30 INFO - ##### Running clobber step. 05:30:30 INFO - ##### 05:30:30 INFO - Running pre-action listener: _resource_record_pre_action 05:30:30 INFO - Running main action method: clobber 05:30:30 INFO - rmtree: /builds/slave/test/build 05:30:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:30:32 INFO - Running post-action listener: _resource_record_post_action 05:30:32 INFO - ##### 05:30:32 INFO - ##### Finished clobber step (success) 05:30:32 INFO - ##### 05:30:32 INFO - ##### 05:30:32 INFO - ##### Running read-buildbot-config step. 05:30:32 INFO - ##### 05:30:32 INFO - Running pre-action listener: _resource_record_pre_action 05:30:32 INFO - Running main action method: read_buildbot_config 05:30:32 INFO - Using buildbot properties: 05:30:32 INFO - { 05:30:32 INFO - "project": "", 05:30:32 INFO - "product": "firefox", 05:30:32 INFO - "script_repo_revision": "production", 05:30:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:30:32 INFO - "repository": "", 05:30:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:30:32 INFO - "buildid": "20160515203829", 05:30:32 INFO - "pgo_build": "False", 05:30:32 INFO - "basedir": "/builds/slave/test", 05:30:32 INFO - "buildnumber": 1, 05:30:32 INFO - "slavename": "t-yosemite-r7-0175", 05:30:32 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:30:32 INFO - "platform": "yosemite_r7", 05:30:32 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:30:32 INFO - "branch": "mozilla-central", 05:30:32 INFO - "repo_path": "mozilla-central", 05:30:32 INFO - "revision": "d0be57e84807ce0853b2406de7ff6abb195ac898", 05:30:32 INFO - "stage_platform": "macosx64", 05:30:32 INFO - "builduid": "fd462b370e5544bdae0208c7f82b5fc7", 05:30:32 INFO - "slavebuilddir": "test" 05:30:32 INFO - } 05:30:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:30:32 INFO - Running post-action listener: _resource_record_post_action 05:30:32 INFO - ##### 05:30:32 INFO - ##### Finished read-buildbot-config step (success) 05:30:32 INFO - ##### 05:30:32 INFO - ##### 05:30:32 INFO - ##### Running download-and-extract step. 05:30:32 INFO - ##### 05:30:32 INFO - Running pre-action listener: _resource_record_pre_action 05:30:32 INFO - Running main action method: download_and_extract 05:30:32 INFO - mkdir: /builds/slave/test/build/tests 05:30:32 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')]} 05:30:32 INFO - https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:30:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:30:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:30:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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 05:30:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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 05:30:33 INFO - Downloaded 1199 bytes. 05:30:33 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:30:33 INFO - Using the following test package requirements: 05:30:33 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:30:33 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:33 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:30:33 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:33 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:30:33 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:33 INFO - u'jsshell-mac.zip'], 05:30:33 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:33 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:30:33 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:30:33 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:33 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:30:33 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:33 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:30:33 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:33 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:30:33 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:33 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:30:33 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:30:33 INFO - https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:30:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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 05:30:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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 05:30:35 INFO - Downloaded 18267670 bytes. 05:30:35 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'] 05:30:35 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 05:30:38 INFO - Return code: 0 05:30:38 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 05:30:38 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')]} 05:30:38 INFO - https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:30:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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 05:30:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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 05:30:39 INFO - Downloaded 18267670 bytes. 05:30:39 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'] 05:30:39 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 05:30:43 INFO - Return code: 0 05:30: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')]} 05:30:43 INFO - https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:30:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:30:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:30:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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 05:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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 05:30:44 INFO - Downloaded 10840194 bytes. 05:30:44 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'] 05:30:44 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 05:30:44 INFO - Return code: 0 05:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:44 INFO - https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:30:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:30:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:30:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:30:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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 05:30:47 INFO - Downloaded 110069099 bytes. 05:30:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:30:47 INFO - mkdir: /builds/slave/test/properties 05:30:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:30:47 INFO - Writing to file /builds/slave/test/properties/build_url 05:30:47 INFO - Contents: 05:30:47 INFO - build_url:https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:30:49 INFO - Running post-action listener: _resource_record_post_action 05:30:49 INFO - Running post-action listener: set_extra_try_arguments 05:30:49 INFO - ##### 05:30:49 INFO - ##### Finished download-and-extract step (success) 05:30:49 INFO - ##### 05:30:49 INFO - ##### 05:30:49 INFO - ##### Running populate-webroot step. 05:30:49 INFO - ##### 05:30:49 INFO - Running pre-action listener: _resource_record_pre_action 05:30:49 INFO - Running main action method: populate_webroot 05:30:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:30:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:30:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:30:49 INFO - '--tppagecycles', 05:30:49 INFO - '7'], 05:30:49 INFO - 'tests': ['tsvgm']}}, 05:30:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:30:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:30:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:30:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:30:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:30:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:30:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:49 INFO - 'talos_options': ['--disable-e10s'], 05:30:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:49 INFO - 'talos_options': ['--disable-e10s'], 05:30:49 INFO - 'tests': ['damp', 'tps']}, 05:30:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:49 INFO - 'tests': ['damp', 'tps']}, 05:30:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:30:49 INFO - 'tests': ['dromaeo_dom']}, 05:30:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:30:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:30:49 INFO - 'tests': ['a11yr', 05:30:49 INFO - 'ts_paint', 05:30:49 INFO - 'tpaint', 05:30:49 INFO - 'sessionrestore', 05:30:49 INFO - 'sessionrestore_no_auto_restore', 05:30:49 INFO - 'tabpaint']}, 05:30:49 INFO - 'other-e10s': {'tests': ['a11yr', 05:30:49 INFO - 'ts_paint', 05:30:49 INFO - 'tpaint', 05:30:49 INFO - 'sessionrestore', 05:30:49 INFO - 'sessionrestore_no_auto_restore', 05:30:49 INFO - 'tabpaint']}, 05:30:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:30:49 INFO - 'ts_paint', 05:30:49 INFO - 'tpaint', 05:30:49 INFO - 'sessionrestore', 05:30:49 INFO - 'sessionrestore_no_auto_restore', 05:30:49 INFO - 'tabpaint']}, 05:30:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:30:49 INFO - 'ts_paint', 05:30:49 INFO - 'tpaint', 05:30:49 INFO - 'sessionrestore', 05:30:49 INFO - 'sessionrestore_no_auto_restore', 05:30:49 INFO - 'tabpaint']}, 05:30:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:30:49 INFO - 'tests': ['a11yr', 05:30:49 INFO - 'ts_paint', 05:30:49 INFO - 'tpaint', 05:30:49 INFO - 'sessionrestore', 05:30:49 INFO - 'sessionrestore_no_auto_restore', 05:30:49 INFO - 'tabpaint']}, 05:30:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:30:49 INFO - 'tests': ['a11yr', 05:30:49 INFO - 'ts_paint', 05:30:49 INFO - 'tpaint', 05:30:49 INFO - 'sessionrestore', 05:30:49 INFO - 'sessionrestore_no_auto_restore', 05:30:49 INFO - 'tabpaint']}, 05:30:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:30:49 INFO - 'tests': ['tsvgx', 05:30:49 INFO - 'tsvgr_opacity', 05:30:49 INFO - 'tart', 05:30:49 INFO - 'tscrollx', 05:30:49 INFO - 'cart']}, 05:30:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:30:49 INFO - 'tsvgr_opacity', 05:30:49 INFO - 'tart', 05:30:49 INFO - 'tscrollx', 05:30:49 INFO - 'cart']}, 05:30:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:49 INFO - 'talos_options': ['--disable-e10s'], 05:30:49 INFO - 'tests': ['tp5o']}, 05:30:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:49 INFO - 'tests': ['tp5o']}, 05:30:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:49 INFO - 'talos_options': ['--disable-e10s', 05:30:49 INFO - '--xperf_path', 05:30:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:49 INFO - 'tests': ['tp5n']}, 05:30:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:49 INFO - 'talos_options': ['--xperf_path', 05:30:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:49 INFO - 'tests': ['tp5n']}}, 05:30:49 INFO - 'talos.zip': {'path': '', 05:30:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:30:49 INFO - Running post-action listener: _resource_record_post_action 05:30:49 INFO - ##### 05:30:49 INFO - ##### Finished populate-webroot step (success) 05:30:49 INFO - ##### 05:30:49 INFO - ##### 05:30:49 INFO - ##### Running create-virtualenv step. 05:30:49 INFO - ##### 05:30:49 INFO - Running pre-action listener: _resource_record_pre_action 05:30:49 INFO - Running main action method: create_virtualenv 05:30:49 INFO - Creating virtualenv /builds/slave/test/build/venv 05:30:49 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 05:30:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:30:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:30:49 INFO - Using real prefix '/tools/python27' 05:30:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:30:49 INFO - Installing distribute.............................................................................................................................................................................................done. 05:30:52 INFO - Installing pip.................done. 05:30:52 INFO - Return code: 0 05:30:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:30:52 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')]} 05:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:52 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')]} 05:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:53 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 0x106054a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef48cc57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8d30>, '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 0x105d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eefb20>, '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.EZfroAETp7/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.wJnDGx7gN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:30:53 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 05:30:53 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 05:30:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EZfroAETp7/Render', 05:30:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:53 INFO - 'HOME': '/Users/cltbld', 05:30:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:53 INFO - 'LOGNAME': 'cltbld', 05:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:53 INFO - 'MOZ_NO_REMOTE': '1', 05:30:53 INFO - 'NO_EM_RESTART': '1', 05:30:53 INFO - 'PAGER': '/bin/cat', 05:30:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:53 INFO - 'PWD': '/builds/slave/test', 05:30:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:53 INFO - 'SHELL': '/bin/bash', 05:30:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJnDGx7gN5/Listeners', 05:30:53 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:30:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:53 INFO - 'USER': 'cltbld', 05:30:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:53 INFO - 'XPC_FLAGS': '0x0', 05:30:53 INFO - 'XPC_SERVICE_NAME': '0', 05:30:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:53 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 05:30:53 INFO - Cleaning up... 05:30:53 INFO - Return code: 0 05:30:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:30:53 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')]} 05:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:53 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')]} 05:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106054a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef48cc57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8d30>, '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 0x105d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eefb20>, '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.EZfroAETp7/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.wJnDGx7gN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:30:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:30:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EZfroAETp7/Render', 05:30:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:53 INFO - 'HOME': '/Users/cltbld', 05:30:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:53 INFO - 'LOGNAME': 'cltbld', 05:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:53 INFO - 'MOZ_NO_REMOTE': '1', 05:30:53 INFO - 'NO_EM_RESTART': '1', 05:30:53 INFO - 'PAGER': '/bin/cat', 05:30:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:53 INFO - 'PWD': '/builds/slave/test', 05:30:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:53 INFO - 'SHELL': '/bin/bash', 05:30:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJnDGx7gN5/Listeners', 05:30:53 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:30:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:53 INFO - 'USER': 'cltbld', 05:30:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:53 INFO - 'XPC_FLAGS': '0x0', 05:30:53 INFO - 'XPC_SERVICE_NAME': '0', 05:30:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:53 INFO - Downloading/unpacking psutil>=3.1.1 05:30:53 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 05:30:53 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 05:30:53 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 05:30:53 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 05:30:53 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 05:30:53 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 05:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:30:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:56 INFO - Installing collected packages: psutil 05:30:56 INFO - Running setup.py install for psutil 05:30:56 INFO - building 'psutil._psutil_osx' extension 05:30:56 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 05:30:57 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 05:30:57 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 05:30:57 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 05:30:57 INFO - building 'psutil._psutil_posix' extension 05:30:57 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 05:30:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:30:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:30:57 INFO - ^ 05:30:57 INFO - 1 warning generated. 05:30:57 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 05:30:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:57 INFO - Successfully installed psutil 05:30:57 INFO - Cleaning up... 05:30:57 INFO - Return code: 0 05:30:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106054a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef48cc57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8d30>, '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 0x105d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eefb20>, '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.EZfroAETp7/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.wJnDGx7gN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:30:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:30:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:30:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EZfroAETp7/Render', 05:30:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:57 INFO - 'HOME': '/Users/cltbld', 05:30:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:57 INFO - 'LOGNAME': 'cltbld', 05:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:57 INFO - 'MOZ_NO_REMOTE': '1', 05:30:57 INFO - 'NO_EM_RESTART': '1', 05:30:57 INFO - 'PAGER': '/bin/cat', 05:30:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:57 INFO - 'PWD': '/builds/slave/test', 05:30:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:57 INFO - 'SHELL': '/bin/bash', 05:30:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJnDGx7gN5/Listeners', 05:30:57 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:30:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:57 INFO - 'USER': 'cltbld', 05:30:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:57 INFO - 'XPC_FLAGS': '0x0', 05:30:57 INFO - 'XPC_SERVICE_NAME': '0', 05:30:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:30:58 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 05:30:58 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 05:30:58 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 05:30:58 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 05:30:58 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 05:30:58 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 05:31:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:31:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:31:00 INFO - Installing collected packages: mozsystemmonitor 05:31:00 INFO - Running setup.py install for mozsystemmonitor 05:31:00 INFO - Successfully installed mozsystemmonitor 05:31:00 INFO - Cleaning up... 05:31:00 INFO - Return code: 0 05:31:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:31:00 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')]} 05:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:00 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')]} 05:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:00 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 0x106054a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef48cc57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8d30>, '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 0x105d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eefb20>, '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.EZfroAETp7/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.wJnDGx7gN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:31:00 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 05:31:00 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 05:31:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EZfroAETp7/Render', 05:31:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:00 INFO - 'HOME': '/Users/cltbld', 05:31:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:00 INFO - 'LOGNAME': 'cltbld', 05:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:00 INFO - 'MOZ_NO_REMOTE': '1', 05:31:00 INFO - 'NO_EM_RESTART': '1', 05:31:00 INFO - 'PAGER': '/bin/cat', 05:31:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:00 INFO - 'PWD': '/builds/slave/test', 05:31:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:00 INFO - 'SHELL': '/bin/bash', 05:31:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJnDGx7gN5/Listeners', 05:31:00 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:31:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:00 INFO - 'USER': 'cltbld', 05:31:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:00 INFO - 'XPC_FLAGS': '0x0', 05:31:00 INFO - 'XPC_SERVICE_NAME': '0', 05:31:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:01 INFO - Downloading/unpacking blobuploader==1.2.4 05:31:01 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 05:31:01 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 05:31:01 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 05:31:01 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 05:31:01 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 05:31:01 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 05:31:03 INFO - Downloading blobuploader-1.2.4.tar.gz 05:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:31:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:31:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:31:04 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 05:31:04 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 05:31:04 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 05:31:04 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 05:31:04 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 05:31:04 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 05:31:04 INFO - Downloading docopt-0.6.1.tar.gz 05:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:31:04 INFO - Installing collected packages: blobuploader, requests, docopt 05:31:04 INFO - Running setup.py install for blobuploader 05:31:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:31:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:31:05 INFO - Running setup.py install for requests 05:31:05 INFO - Running setup.py install for docopt 05:31:05 INFO - Successfully installed blobuploader requests docopt 05:31:05 INFO - Cleaning up... 05:31:05 INFO - Return code: 0 05:31:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:05 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 0x106054a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef48cc57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8d30>, '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 0x105d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eefb20>, '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.EZfroAETp7/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.wJnDGx7gN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:31:05 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 05:31:05 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 05:31:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EZfroAETp7/Render', 05:31:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:05 INFO - 'HOME': '/Users/cltbld', 05:31:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:05 INFO - 'LOGNAME': 'cltbld', 05:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:05 INFO - 'MOZ_NO_REMOTE': '1', 05:31:05 INFO - 'NO_EM_RESTART': '1', 05:31:05 INFO - 'PAGER': '/bin/cat', 05:31:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:05 INFO - 'PWD': '/builds/slave/test', 05:31:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:05 INFO - 'SHELL': '/bin/bash', 05:31:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJnDGx7gN5/Listeners', 05:31:05 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:31:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:05 INFO - 'USER': 'cltbld', 05:31:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:05 INFO - 'XPC_FLAGS': '0x0', 05:31:05 INFO - 'XPC_SERVICE_NAME': '0', 05:31:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:06 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-LNarZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:06 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-WWMpmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:06 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-jiPlf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:06 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-1bJhMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:06 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Vyi6Ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:06 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-mxNZ4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:06 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-TI098j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:06 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ZSt8cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:06 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Gddltz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:06 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-pbGL8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:07 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-AJI6Hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:07 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-l9UWPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:07 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-1wP_gt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:07 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-vV3LBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:07 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Q_dUjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:07 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-X0qQ3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:07 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-GgLdyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:31:07 INFO - Running setup.py install for manifestparser 05:31:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:31:07 INFO - Running setup.py install for mozcrash 05:31:08 INFO - Running setup.py install for mozdebug 05:31:08 INFO - Running setup.py install for mozdevice 05:31:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:31:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:31:08 INFO - Running setup.py install for mozfile 05:31:08 INFO - Running setup.py install for mozhttpd 05:31:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:31:08 INFO - Running setup.py install for mozinfo 05:31:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:31:08 INFO - Running setup.py install for mozInstall 05:31:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:31:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:31:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:31:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:31:09 INFO - Running setup.py install for mozleak 05:31:09 INFO - Running setup.py install for mozlog 05:31:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:31:09 INFO - Running setup.py install for moznetwork 05:31:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:31:09 INFO - Running setup.py install for mozprocess 05:31:09 INFO - Running setup.py install for mozprofile 05:31:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:31:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:31:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:31:09 INFO - Running setup.py install for mozrunner 05:31:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:31:09 INFO - Running setup.py install for mozscreenshot 05:31:10 INFO - Running setup.py install for moztest 05:31:10 INFO - Running setup.py install for mozversion 05:31:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:31:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:31:10 INFO - Cleaning up... 05:31:10 INFO - Return code: 0 05:31:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:10 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 0x106054a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef48cc57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8d30>, '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 0x105d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eefb20>, '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.EZfroAETp7/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.wJnDGx7gN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:31:10 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 05:31:10 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 05:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EZfroAETp7/Render', 05:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:10 INFO - 'HOME': '/Users/cltbld', 05:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:10 INFO - 'LOGNAME': 'cltbld', 05:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:10 INFO - 'MOZ_NO_REMOTE': '1', 05:31:10 INFO - 'NO_EM_RESTART': '1', 05:31:10 INFO - 'PAGER': '/bin/cat', 05:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:10 INFO - 'PWD': '/builds/slave/test', 05:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:10 INFO - 'SHELL': '/bin/bash', 05:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJnDGx7gN5/Listeners', 05:31:10 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:10 INFO - 'USER': 'cltbld', 05:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:10 INFO - 'XPC_FLAGS': '0x0', 05:31:10 INFO - 'XPC_SERVICE_NAME': '0', 05:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-XcWmZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:10 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)) 05:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-U2SIOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:10 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)) 05:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-pa5TsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:11 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)) 05:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-6Fle_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:11 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)) 05:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-lkdPBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:11 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)) 05:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-JP7Dnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:11 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)) 05:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-dkHfZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:11 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)) 05:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-kuePkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:11 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)) 05:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-AgwoC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:11 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)) 05:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-w1MnT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-bbQ61c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:11 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)) 05:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-1QP0xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:11 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)) 05:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:12 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-XXQjm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:12 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)) 05:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:12 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-A5oMpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:12 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)) 05:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:12 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-L_zwlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:12 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)) 05:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:12 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-UVQoxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:12 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)) 05:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:12 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-1D0Xnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:12 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)) 05:31:12 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)) 05:31:12 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)) 05:31:12 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)) 05:31:12 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)) 05:31:12 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)) 05:31:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:14 INFO - Downloading blessings-1.6.tar.gz 05:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:31:15 INFO - Installing collected packages: blessings 05:31:15 INFO - Running setup.py install for blessings 05:31:15 INFO - Successfully installed blessings 05:31:15 INFO - Cleaning up... 05:31:15 INFO - Return code: 0 05:31:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:31:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:15 INFO - Reading from file tmpfile_stdout 05:31:15 INFO - Current package versions: 05:31:15 INFO - blessings == 1.6 05:31:15 INFO - blobuploader == 1.2.4 05:31:15 INFO - docopt == 0.6.1 05:31:15 INFO - manifestparser == 1.1 05:31:15 INFO - mozInstall == 1.12 05:31:15 INFO - mozcrash == 0.17 05:31:15 INFO - mozdebug == 0.1 05:31:15 INFO - mozdevice == 0.48 05:31:15 INFO - mozfile == 1.2 05:31:15 INFO - mozhttpd == 0.7 05:31:15 INFO - mozinfo == 0.9 05:31:15 INFO - mozleak == 0.1 05:31:15 INFO - mozlog == 3.2 05:31:15 INFO - moznetwork == 0.27 05:31:15 INFO - mozprocess == 0.22 05:31:15 INFO - mozprofile == 0.28 05:31:15 INFO - mozrunner == 6.11 05:31:15 INFO - mozscreenshot == 0.1 05:31:15 INFO - mozsystemmonitor == 0.3 05:31:15 INFO - moztest == 0.7 05:31:15 INFO - mozversion == 1.4 05:31:15 INFO - psutil == 3.1.1 05:31:15 INFO - requests == 1.2.3 05:31:15 INFO - wsgiref == 0.1.2 05:31:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:15 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')]} 05:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:15 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')]} 05:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:15 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 0x106054a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef48cc57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8d30>, '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 0x105d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eefb20>, '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.EZfroAETp7/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.wJnDGx7gN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:31:15 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 05:31:15 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 05:31:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EZfroAETp7/Render', 05:31:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:15 INFO - 'HOME': '/Users/cltbld', 05:31:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:15 INFO - 'LOGNAME': 'cltbld', 05:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:15 INFO - 'MOZ_NO_REMOTE': '1', 05:31:15 INFO - 'NO_EM_RESTART': '1', 05:31:15 INFO - 'PAGER': '/bin/cat', 05:31:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:15 INFO - 'PWD': '/builds/slave/test', 05:31:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:15 INFO - 'SHELL': '/bin/bash', 05:31:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJnDGx7gN5/Listeners', 05:31:15 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:31:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:15 INFO - 'USER': 'cltbld', 05:31:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:15 INFO - 'XPC_FLAGS': '0x0', 05:31:15 INFO - 'XPC_SERVICE_NAME': '0', 05:31:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:15 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)) 05:31:15 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)) 05:31:15 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)) 05:31:15 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)) 05:31:15 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)) 05:31:15 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)) 05:31:15 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)) 05:31:15 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)) 05:31:15 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)) 05:31:15 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)) 05:31:15 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)) 05:31:15 INFO - Cleaning up... 05:31:15 INFO - Return code: 0 05:31:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:31:15 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')]} 05:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:15 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')]} 05:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:15 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 0x106054a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef48cc57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8d30>, '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 0x105d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eefb20>, '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.EZfroAETp7/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.wJnDGx7gN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:31:15 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 05:31:15 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 05:31:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EZfroAETp7/Render', 05:31:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:15 INFO - 'HOME': '/Users/cltbld', 05:31:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:15 INFO - 'LOGNAME': 'cltbld', 05:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:15 INFO - 'MOZ_NO_REMOTE': '1', 05:31:15 INFO - 'NO_EM_RESTART': '1', 05:31:15 INFO - 'PAGER': '/bin/cat', 05:31:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:15 INFO - 'PWD': '/builds/slave/test', 05:31:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:15 INFO - 'SHELL': '/bin/bash', 05:31:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJnDGx7gN5/Listeners', 05:31:15 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:31:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:15 INFO - 'USER': 'cltbld', 05:31:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:15 INFO - 'XPC_FLAGS': '0x0', 05:31:15 INFO - 'XPC_SERVICE_NAME': '0', 05:31:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:16 INFO - Downloading/unpacking jsonschema 05:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:31:18 INFO - Downloading/unpacking functools32 (from jsonschema) 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:18 INFO - Downloading functools32-3.2.3-2.tar.gz 05:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:31:19 INFO - warning: no files found matching '*.txt' 05:31:19 INFO - no previously-included directories found matching 'build' 05:31:19 INFO - no previously-included directories found matching 'dist' 05:31:19 INFO - no previously-included directories found matching '.git*' 05:31:19 INFO - Installing collected packages: jsonschema, functools32 05:31:19 INFO - Running setup.py install for functools32 05:31:19 INFO - warning: no files found matching '*.txt' 05:31:19 INFO - no previously-included directories found matching 'build' 05:31:19 INFO - no previously-included directories found matching 'dist' 05:31:19 INFO - no previously-included directories found matching '.git*' 05:31:19 INFO - Successfully installed jsonschema functools32 05:31:19 INFO - Cleaning up... 05:31:19 INFO - Return code: 0 05:31:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:19 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 0x106054a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10605a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef48cc57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8d30>, '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 0x105d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eefb20>, '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.EZfroAETp7/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.wJnDGx7gN5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 05:31:19 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 05:31:19 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 05:31:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EZfroAETp7/Render', 05:31:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:19 INFO - 'HOME': '/Users/cltbld', 05:31:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:19 INFO - 'LOGNAME': 'cltbld', 05:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:19 INFO - 'MOZ_NO_REMOTE': '1', 05:31:19 INFO - 'NO_EM_RESTART': '1', 05:31:19 INFO - 'PAGER': '/bin/cat', 05:31:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:19 INFO - 'PWD': '/builds/slave/test', 05:31:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:19 INFO - 'SHELL': '/bin/bash', 05:31:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJnDGx7gN5/Listeners', 05:31:19 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:31:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:19 INFO - 'USER': 'cltbld', 05:31:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:19 INFO - 'XPC_FLAGS': '0x0', 05:31:19 INFO - 'XPC_SERVICE_NAME': '0', 05:31:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:19 INFO - Downloading/unpacking flake8 05:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:31:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:31:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:31:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:31:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:31:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:31:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:31:23 INFO - no previously-included directories found matching 'docs/_build' 05:31:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:31:23 INFO - Running setup.py install for pep8 05:31:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:31:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:31:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:31:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:31:23 INFO - no previously-included directories found matching 'docs/_build' 05:31:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:31:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:31:23 INFO - Cleaning up... 05:31:23 INFO - Return code: 0 05:31:23 INFO - Running post-action listener: _resource_record_post_action 05:31:23 INFO - Running post-action listener: _start_resource_monitoring 05:31:23 INFO - Starting resource monitoring. 05:31:23 INFO - ##### 05:31:23 INFO - ##### Finished create-virtualenv step (success) 05:31:23 INFO - ##### 05:31:23 INFO - ##### 05:31:23 INFO - ##### Running install step. 05:31:23 INFO - ##### 05:31:23 INFO - Running pre-action listener: _resource_record_pre_action 05:31:23 INFO - Running main action method: install 05:31:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:24 INFO - Reading from file tmpfile_stdout 05:31:24 INFO - Detecting whether we're running mozinstall >=1.0... 05:31:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:31:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:31:24 INFO - Reading from file tmpfile_stdout 05:31:24 INFO - Output received: 05:31:24 INFO - Usage: mozinstall [options] installer 05:31:24 INFO - Options: 05:31:24 INFO - -h, --help show this help message and exit 05:31:24 INFO - -d DEST, --destination=DEST 05:31:24 INFO - Directory to install application into. [default: 05:31:24 INFO - "/builds/slave/test"] 05:31:24 INFO - --app=APP Application being installed. [default: firefox] 05:31:24 INFO - mkdir: /builds/slave/test/build/application 05:31:24 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'] 05:31:24 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 05:31:57 INFO - Reading from file tmpfile_stdout 05:31:57 INFO - Output received: 05:31:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:31:57 INFO - Running post-action listener: _resource_record_post_action 05:31:57 INFO - ##### 05:31:57 INFO - ##### Finished install step (success) 05:31:57 INFO - ##### 05:31:57 INFO - ##### 05:31:57 INFO - ##### Running run-tests step. 05:31:57 INFO - ##### 05:31:57 INFO - Running pre-action listener: _resource_record_pre_action 05:31:57 INFO - Running main action method: run_tests 05:31:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:31:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:31:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:31:57 INFO - 2016-05-17 05:31:57.460 screenresolution[1640:7446] starting screenresolution argv=screenresolution get 05:31:57 INFO - 2016-05-17 05:31:57.462 screenresolution[1640:7446] Display 0: 1600x1200x32@60 05:31:57 INFO - 2016-05-17 05:31:57.468 screenresolution[1641:7448] starting screenresolution argv=screenresolution list 05:31:57 INFO - Available Modes on Display 0 05:31:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:31:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:31:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:31:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:31:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:31:57 INFO - Intel Iris: 05:31:57 INFO - Chipset Model: Intel Iris 05:31:57 INFO - Type: GPU 05:31:57 INFO - Bus: Built-In 05:31:57 INFO - VRAM (Dynamic, Max): 1536 MB 05:31:57 INFO - Vendor: Intel (0x8086) 05:31:57 INFO - Device ID: 0x0a2e 05:31:57 INFO - Revision ID: 0x0009 05:31:57 INFO - Displays: 05:31:57 INFO - Display: 05:31:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:31:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:31:57 INFO - Display Serial Number: 1600x1200 60 05:31:57 INFO - Main Display: Yes 05:31:57 INFO - Mirror: Off 05:31:57 INFO - Online: Yes 05:31:57 INFO - Rotation: Supported 05:31:57 INFO - Return code: 0 05:31:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:31:57 INFO - Python 2.7.3 05:31:57 INFO - Return code: 0 05:31:57 INFO - grabbing minidump binary from tooltool 05:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:57 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8d30>, '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 0x105d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105eefb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:31:57 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 05:31:57 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 05:31:57 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:31:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:31:57 INFO - Return code: 0 05:31:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:31:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:31:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:31:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:31:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:31:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:31:58 INFO - Return code: 0 05:31:58 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/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 05:31:58 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/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0175 --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 05:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EZfroAETp7/Render', 05:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:58 INFO - 'HOME': '/Users/cltbld', 05:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:58 INFO - 'LOGNAME': 'cltbld', 05:31:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:31:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:58 INFO - 'MOZ_NO_REMOTE': '1', 05:31:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:31:58 INFO - 'NO_EM_RESTART': '1', 05:31:58 INFO - 'PAGER': '/bin/cat', 05:31:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:58 INFO - 'PWD': '/builds/slave/test', 05:31:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:58 INFO - 'SHELL': '/bin/bash', 05:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJnDGx7gN5/Listeners', 05:31:58 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:58 INFO - 'USER': 'cltbld', 05:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:58 INFO - 'XPC_FLAGS': '0x0', 05:31:58 INFO - 'XPC_SERVICE_NAME': '0', 05:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:58 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/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 05:31:59 INFO - mozversion application_buildid: 20160515203829 05:31:59 INFO - mozversion application_changeset: d0be57e84807ce0853b2406de7ff6abb195ac898 05:31:59 INFO - mozversion application_display_name: Nightly 05:31:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:31:59 INFO - mozversion application_name: Firefox 05:31:59 INFO - mozversion application_remotingname: firefox 05:31:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:31:59 INFO - mozversion application_vendor: Mozilla 05:31:59 INFO - mozversion application_version: 49.0a1 05:31:59 INFO - mozversion platform_buildid: 20160515203829 05:31:59 INFO - mozversion platform_changeset: d0be57e84807ce0853b2406de7ff6abb195ac898 05:31:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:31:59 INFO - mozversion platform_version: 49.0a1 05:31:59 INFO - using testdate: 1463488319 05:31:59 INFO - actual date: 1463488319 05:31:59 INFO - starting webserver on 'localhost:49270' 05:31:59 INFO - SUITE-START | Running 2 tests 05:31:59 INFO - TEST-START | dromaeo_css 05:31:59 INFO - operating with platform_type : mac_ 05:31:59 INFO - Initialising browser for dromaeo_css test... 05:31:59 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpyMgZd8/profile http://localhost:49270/getInfo.html) 05:32:02 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 05:32:02 INFO - PROCESS | 1659 | colorDepth:24 05:32:02 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 05:32:02 INFO - PROCESS | 1659 | __metrics 05:32:02 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:32:03 INFO - TEST-INFO | 1659: exit 0 05:32:03 INFO - Browser initialized. 05:32:03 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:03 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpyMgZd8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:15 INFO - PROCESS | 1662 | RSS: Main: 250097664 05:32:15 INFO - PROCESS | 1662 | 05:33:04 INFO - PROCESS | 1662 | 1463488384706 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] 05:35:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 05:35:17 INFO - PROCESS | 1662 | RSS: Main: 263598080 05:35:17 INFO - PROCESS | 1662 | 05:38:19 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 05:38:20 INFO - PROCESS | 1662 | RSS: Main: 275574784 05:38:20 INFO - PROCESS | 1662 | 05:41:22 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 05:41:22 INFO - PROCESS | 1662 | RSS: Main: 277536768 05:41:22 INFO - PROCESS | 1662 | 05:44:24 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 05:44:25 INFO - PROCESS | 1662 | RSS: Main: 279617536 05:44:25 INFO - PROCESS | 1662 | 05:47:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 05:47:28 INFO - PROCESS | 1662 | RSS: Main: 282005504 05:47:28 INFO - PROCESS | 1662 | 05:50:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 05:50:31 INFO - PROCESS | 1662 | RSS: Main: 283136000 05:50:31 INFO - PROCESS | 1662 | 05:50:31 INFO - PROCESS | 1662 | __start_tp_report 05:50:31 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 05:50:31 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 05:50:31 INFO - PROCESS | 1662 | |i|pagename|runs| 05:50:31 INFO - PROCESS | 1662 | |0;dojo.html;3397.490376443533;3443.156426675464;3467.9479807802886;3514.103903504606;3540.3981323175058;5766.913496297559;5807.709614519268;5876.324222714389;5895.911561326584;5900.586958912878;5715.457031581993;5789.131630255453;5802.738876750545;5814.4766970972605;5823.5341172706085;5160.458151894053;5206.921896171561;5285.048691235576;5304.655197412156;5336;406.9258351034249;5291.444398338177;5300.787968481256;5308.92036619451;5353.223782551528;5209.348831396075;5234.371875374942;5254.975279820436;5279.2872962150195;5301.549368303698;5146.253793200002;5307.920381194285;5317.388500322474;5330.946690533084;5345.09133447311;5173.611979101544;5185.740712964337;5196.246544251062;5201.349831271091;5213.348331458567;5664.971675141639;5675.602707810455;5812.099124635688;5837.854053648693;5846.444587764156;6154.1999540059505;6182.381761823826;6205.658688772116;6272.15325930998;6286.9056964145575;6222.595531290452;6244.188255526798;6248.87502249957;6250.843728906768;6270.372962703739;5889;5900;5904.940950590525;5945.613535120204;5958.582899197015;6186.288576813634;6302.558820882586;6309.179806625201;6325.019621958604;6369.426751592379;5151.5878729701535;6269.749210031548;6283.246010478681;6286.402791734778;6299.338569450142;6225.408586184305;6254.959995607756;6301.117843501914;6304.810855674337;6312.873742525124;5014.473480284518;6128.105930682418;6194.690265486708;6212.751489940442;6245.375462453719;5414.6209765316835;5992.520598352121;6132.263729246456;6209.875802483925;6266.934621114421;4854.417469903564;4968;5007.39911210657;5021.774020169101;5088.363954505687;4403.207422663952;4444.200043992112;4456.821727130879;4458.821647134143;4514.34541991416;5456.590755693339;6003.909941350883;6004.849878753066;6031;6057.212562366864;6197.5351848611535;6328.493720502349;6343.651099189497;6351.015592583157;6352.872942541123;5966.552508561788;5980.0132978059;6005.849853753691;6018.067199583984;6063.787767428119;5504.2294078829;5519.2824932758485;5520.558355331564;5535.446455354432;5549.889002219933;6306.716197771019;6448.25845027809;6520.021996700533;6539.378759017885;6542.901856472156;5243.239730239017;6200.0699895016105;6265.8120256392385;6280.246370435577;6282.780102696383;6447.4197322241225;6504.349565043458;6517.543771936109;6520.706568204537;6527.249366322933;6154.846128846814;6174.876502469836;6184.474319682698;6237.376262373727;6280.0893870389455;6257.731497811597;6267.467265282502;6278.6546739929745;6292.11910332563;6305.959516679696;6012.278526576839;6249.281332646804;6271.184745982993;6271.874562508816;6275.560710750204;6124.958757011408;6128.686460748492;6150.415710507495;6188.257409110935;6244.781432650017;5901.173835663097;5962.821115366546;5980.252468441445;6061.848453788691;6137.018077107641;6170.475509581735;6178.752849886134;6225.595336303126;6245.812625621239;6254.937450625617;6022.36765139654;6100.481459076028;6115.388461153849;6136.815895523141;6150.477209437069 05:50:31 INFO - PROCESS | 1662 | |1;ext.html;507.94158671752734;545.2856757647482;550.5925615044868;550.6283258800308;571.5456212311212;35816;42789;42813.143737125276;42877.78561107194;42930.7853460733;43524;44193.55806441935;44197.779011104976;44210;44328.556714432925;9761.609535618585;9928.503574821267;9938;9955.502224888764;9957.302988790787;8367.372447066464;9714.417134971889;9772.267079968995;9808.019198080206;9808.068233517804;8486.726990951376;9620.567074481665;9765.804683906317;9786.217102631808;9798.461084640341;8244.381671374671;9698.70903872885;9726.562304696306;9736.026397360278;9772;9765.365251258645;9867.50662466881;9908.108270255712;9933.55299011539;9974.351667141613;40380.394294085614;40705.59294407048;40816;40885.18229635391;40930.79534602338;3280.1799550112473;3294.5222942673417;3360.0087974047715;3429.4341433663417;3565.4651802229614;29863.552046719316;29941.550876736866;29949.850250748827;30204.09387718372;30538.38923221523;2713.864306784653;2731.139690997349;2743.2867454461784;2848.5584734366207;2855.1822901127725;527437;534188;536611;536932;537938;59511.107333390035;59651.806963861345;60300;60300.39699602948;60443.39556604378;29346.266343341587;36053;36552;37908.81045594809;37955;17092.40176593813;17269.913650431667;17270.568235793955;17288.827111728722;17291.394801181897;12618;12927.741445171045;12971.805422918664;12986.026048046435;13051.021173412031;19945.301914432926;20011.59976800456;20101.99490025493;20147.597048058957;20173.394798156078;8024.879626805603;8145.144759800136;8166.550839703873;8180.304674102649;8220.301274391744;917.9403338782957;1013.6097602422973;1020.6784862768204;1093.2019625673145;1100.8403781451646;25512.872435637702;30112.84943575312;30646.693533064383;30673.38653226923;31295.619984596386;1080.3679847289266;1088.4448931045065;1098.983515247272;1138.9088872890316;1139.3676509537286;13660.726785464236;15523.922380388252;15785.763213551805;15930.442434514736;15953.840461595235;315187.6646784449;322210.38894805376;370548;373252;678496;61492.69253653793;61513.38486615166;62268.06597901035;62269.75460490765;62330;1286.5304163980268;1409.8519655436237;1422.5732280315865;1468.4493315006873;1468.8237411510688;26063.609045864705;29952.70047299499;29963.55054674138;30269.152990395767;30550;58382.70808645929;59153;59202.70398647979;60060.399396007226;60091.69954150376;591516;613537;614960;615163;615638;41965.58034419617;42519.5748042528;42524.72425827106;42814.35778463388;43216.35175472433;35692;43601.78199108984;46103.53896460992;46638.43488608159;49374.012519750126;38187;38504;38643.227135457695;38952.41571376488;39124;38523.53288204075;38792.61207388003;38969.8051509752;39072.609273908034;39898.40152397774;33047.50428743619;37863.86408407752;38375.42436863505;38672;38756;27513.44973100567;30221.09336719902;33246;33820.661793381754;34236.48645270273;22276.108955641594;22512.774872251724;24863.87568062148;24934.50130997406;25260.621090683286 05:50:31 INFO - PROCESS | 1662 | |2;jquery.html;390.1280637774573;395.2352198495911;397.93434083376246;397.940308953657;416.8686863637954;14773.778393324083;19978.90010549947;20620.484487887774;21340.57318853622;21357.786422135792;20661;21095.15619375227;21644.02601882915;21676.783232167672;21690.132394704193;4955.826546070888;5310.283111779918;5315.521603055724;5371.462853714617;5383.219433182187;4845.345878306436;5085.771140298695;5290.470952904717;5440.374356948962;5467.398586155519;5358.571314294866;5409.486098820606;5423.240746295522;5439.564834813205;5464.153056276284;5108.69347839129;5413.675179489223;5433.212184233264;5447.1829225616075;5455.672659640414;5288.629795914288;5289.576833853283;5295.814646487354;5301.284326615896;5307.761150748187;11804.527818887234;13525.18848869071;25170.74146292678;26641.600375994374;26831.73168268312;2074.1495986645405;2075.190675636503;2238.1383167480635;2284.0292875527834;2465.5438743832447;8307.501549907025;9423.82202224722;9973.401595904268;10256.641017564349;14834.554963351116;1683.966320673592;1796.4700413378084;1812.420025591811;1832.0290246169552;1859.3120545398153;116101;116579;117465;120668;126010.10984835327;9606.759831004281;11551.88448115508;11898.10764192689;11907.106966977512;11914.404279785977;12570.560030398892;15173.848261517243;15230.162341071125;15299.388024478896;15326.463573774865;8780.956095219482;8798.340124490689;8807.383483156245;8874.245689116497;8892.555372231363;6989.580625162506;8620.13798620133;8714.95642521796;8743.868841967376;8797.47215167092;4657.301404789308;4685.929711054337;4764;4768.451628062817;4812.109759694397;1725.6980028494963;2021.86857854239;2027.5640737241563;2029.401326608654;2035.5827055453597;891.5453118909403;940.3934462271797;994.3685759542742;1006.3307900246255;1015.1624909449733;19819.207231710858;20356.18575257003;20451.795482045287;20860.58278834415;20909.581808363746;943.1700103908516;972.7713987212999;981.5337714585514;990.5393991793851;991.8859331176862;10348.793024139475;11208.943955280172;11293;11295.604653837076;11505.884941150649;598931;611615;613262;623637;625729;139138.30430847782;140289;142107.289463552;142994.2850285742;143168.19875776398;887.1837909123622;1010.5604062232952;1021.759886426704;1033.4161198922584;1034.2139973620103;13453.730925381633;18506.722399164293;18555;18692.43922682322;18817.435476935712;54751.89360798036;55968.24269937101;55982;57266.42733572611;58251;80373;83161.16838831776;83685.48943531461;84159.5792021036;84812;8156.673733050611;8205.95897020511;8347.83304333922;8410.411271210956;8418.747437576883;8771.254443372129;8835.425697329652;8868.246199073152;9314.487703176119;9411.717648470556;6244.781432649836;7605.23947605235;7954.403419743659;8009.078955919911;8309.00291964968;6742.932570674427;7733.110692270461;8195.95902020486;8289.336853051862;8367.916320836714;8122.7157049502985;8199.836003280021;8378;8385.1195624461;8508.659653613786;12009.699757506132;12385.690357740768;12398.00815934705;12481.375931203222;12739;9958.651447199314;11191.66425007251;11301.208915376174;12089;12121.757564848478 05:50:31 INFO - PROCESS | 1662 | |3;mootools.html;1400.13082534816;1650.4800987688873;1728.7406888966661;1912.5505506206039;2008.889511076891;8370.995480542328;8812.691555795549;8834.823303533925;9315.720528384143;9328.44029358239;8924.33067519937;8986;9143.679971201009;9157.725268241948;9201.907980920188;2450.0322372662786;2469.790067844235;2473.3940184654784;2476.9752302477013;2485.8135639826996;2426.296374051523;2483.801295896324;2484.8633325167107;2490.2404766546197;2494.326531836403;2370.288913326004;2378.82158838087;2476.05909754294;2480.3675062858947;2480.7519248075137;2055.4142069510117;2444.156765915747;2444.1934161726676;2453.7546245375497;2459.213051823417;2427.296084135616;2429.344077099191;2429.671994280767;2431.222008957134;2431.477232395026;9120.498372589505;9135.862962055575;9169.220616247627;9174.08259174084;9215.815683686289;4695.88260293492;5445.50990410861;5599.440055994409;5607.551395888319;5633.577481688849;3565.0552603560077;3615.8553657853918;3642.05306620278;3663.7435379523445;3674.430463278196;1036.740814796301;1041.8383502394852;1046.984295235572;1048.6644273832376;1051.5636011055403;11952.64142075739;12087.27476351413;12119.515219391302;12155.149139560146;12177.634670959886;4859.757012149378;4869.537393947569;4978.178600530944;4978.850634480971;5003.524665156804;10201.74495637615;10230.948845255725;10266.743331416774;10270.743231419125;10271.126954208827;5472.151816468374;5472.863178420572;5556.944430555724;5560.193771903051;5569.498745112959;4520.977395113004;4899.779509922062;5074.594032477393;5105.361829771278;5107.693538387709;4023.511300818716;4113.979430102891;4125.690573207022;4158.979205103955;4195.538490766014;1157.3518829455531;1351.3243378310844;1373.230990645242;1379.2276325257887;1385.764420048594;797.4178849439942;915.9725208243764;924.3390975452584;938.2447130060367;945.3996712087874;5728.770849165987;5917.5857689961285;6065.636061836216;6065.848353791102;6102.058340323486;393.51597535032073;459.70349124814305;463.1732357741415;470.6540692590943;472.26326929989324;3382.6955573997966;3964.147708242742;4042.757434553936;4064.861838685173;4110.424540564323;10595.788084238273;10880.455977201109;10892.292001019909;11009.779804403866;11039.944800275947;9943.850842237372;10833.67498975032;11023.889761102446;11050.557977680963;11079;8109.594520273963;8400.453970491899;8473.788155296166;8481.27909127731;8500.319974402033;5724.942750572608;5899.9675056865735;5976.850578735566;5988.431099045583;6003.3996600339615;10207.897921020694;10274.589016439259;10308.175345972155;10315.48422578853;10333.37999719989;8673.696420625249;9826.508674566387;10353.84469232947;10371.740706482398;10585.576576936835;7077.433805295667;7507.211742766931;7723.382129429519;7751.147373788992;7869.016372953381;8620.439671421338;8642.913570864463;8708.69519566812;8749.300055995378;8762.474251544672;7557.622118894144;7701.422393320636;7773.183815699496;7777.805554861174;7806.102298235548;6110.633361998294;7573.697052117854;7581.166071732216;7684.923150768421;7709.113451952873;7108.3602475777425;7176.282371762782;7182.066331376887;7193.856122877409;7206.89189662166;4767.73777442244;4928.531789479994;5016.097102521435;5040.143175660073;5075.51782580669;4998.575121114601;5022.924656130234;5061.645684802029;5085.135526960499;5263.947360526346 05:50:31 INFO - PROCESS | 1662 | |4;prototype.html;308.5310328300981;318.19337978225195;318.2378204200939;324.26553855517244;325.201629998354;5509.4105770288;5515.710979943318;5543.528800052;5564.248826408434;5574.488072514316;4239.025024244418;5019.590596513675;5028.2709007193935;5467.289252397183;5471.945280547194;3524.7885126892406;3985;4088.2028004539125;4097.221527909703;4111.218868415007;4034.1326614777818;4079.97960010201;4092.6930480213955;4098.569650186732;4129.483814523184;3407.1822762537063;3755.75587586806;4099.692523060782;4113.300738874384;4116.526599441073;3782.470454136396;4040.323265844671;4100.261952848486;4104.897377565585;4123.680422264876;2939.970600293991;3500.842462089212;3512.771669841452;3523.9956612365354;3531.170175008871;4147.668186545069;4901.857930049103;4967.627427942919;5045.763787871958;5155.639105262633;4337.349397590355;4646.9070618587775;4705.764711764432;4719.07977944301;4730.787114579852;5286.471352864722;5715.828525144252;5734.311882574076;5734.369219385864;5774.855628609276;3406.67636574525;3787.594877252937;3897.4738410314526;3899.8245078971513;3916.373380259145;5940.6435613863305;6059.727312270914;6088.421599948042;6107.4197951194565;6134.178277992129;3318.3197444523817;3800.733948623625;3947.3092208863336;3972.140059157402;4005.559388467266;3424.8116353600294;4023.4509713759826;4065.715399921977;4066.532348779869;4086.6730661547003;4693;4710.199266124767;4710.929336059962;4711;4714.669973101919;5089.236614507779;5352.384475785257;5504.036793561111;5524.944750552523;5781.306243250837;1190.33936165429;1375.1542801177204;1377.5316392426623;1378.1662094432925;1379.7930310453312;815.2784785464788;817.6116344736203;853.4537895746727;856.9828603427896;894.8344556257114;4716.622670186377;4901.289313049588;5257.7896884124975;5324.7870085196155;5330.227117068003;4963.5036496350795;5510.173473978935;5819.476247137778;5830.679312637845;5916.49709774674;4331.480222373272;4725.881852953704;4836.911694868626;5289.867753306121;5521.309836270466;2875.5686647003117;3097.3650401667605;3098.380323935222;3099.1477343730653;3136.619887249603;5703.287089113861;5800.709964501758;5829.8251052468495;6200.689965501707;6244.812655620339;5212.661177023481;5458.563314934796;5695.781883236041;6070.209288259974;6109.877802443926;5946.256717910262;6141.477974372139;6156.815295541141;6210.223722034836;6225.688715564113;5272.815451459181;5364.758585863724;5705.34388545306;5903.970480147572;5932.2584676915385;5583.720813959367;5806.02595117662;5893.528517718658;5894.38108998549;5911.231539899957;5838.678872662044;5947.613405128653;6193.721282542207;6227.128202051807;6237.906431403623;3109.626844778641;3337.8998630041137;3363.1928337199383;3978.1645854370904;4001.7398869073418;5246.160614301693;5414.539764120094;5528.668279903219;5915.024021036481;5990.520758339235;4825.806967721252;5401.648892821954;5586.972065139811;5719.342275638189;5778.306603207642;5077.619178561548;5647.9949617886705;5757.7121143941795;5762.942370576408;5787.813498232937;4931.235658472942;5032.521910418504;5412.675239485643;5591.021571224938;5665.413684168439;5637.548996080386;5780.682062486436;5834.854128646818;5839.89042082003;5857.414258574108;4826.710397376169;5713.571482138773;5725.8568535786935;5754.367019627922;5760.884782304414 05:50:31 INFO - PROCESS | 1662 | |5;yui.html;635.7615894039735;667.6194569095616;693.8681650486408;732.6593134192599;742.5099434373311;12025.518979240831;16280.104594247312;16608.66782664346;16877.746833797504;16918.49244522663;15228.314725837336;15320.463783767571;15414.84585154148;15677.84322156778;15696.450624228155;748.3265061444716;917.0920788419484;931.8276118917988;949.2121539122546;954.0078318548701;938.4979036215626;938.9577469013876;940.8870935487716;941.4257303045136;951.8191543606728;998.181491177235;1001.4992503748126;1002.4085789384262;1006.1548299428479;1006.1598565198068;966.429806414212;978.8091322192175;989.9950500247488;994.5226291380105;997.7106639074659;733.0470388494989;741.2587412587412;742.6138408027796;743.1379599664364;744.4900243333279;13163.210207387492;13194.340282985811;13262.933685331607;13289.003324750585;13445.731085378238;10686.893131068668;12527.38320628845;12660.620181394574;12696.238225706487;12774.361281935866;8694.347923905732;9389.154976052188;9957.75105622358;10056.748581285454;10058.89941100587;3376.6116896556964;3486.9476957845654;3555.253396786678;3564.625714299987;3590.299891521154;58969;73180.1704957375;82388.58805705933;83287;90099.64850527313;9229.261659067259;9230.630774769068;9258.861117083248;9282.953585232166;9642.903570964341;21096.47258818511;21099.05054272531;21121;21124.577508449744;21130;6661.367170118928;6966.268541803038;7171.318724721144;7206.171290301578;7375.668094935741;4000.179963107556;4064.349704047338;4064.7764372959773;4070.3283958147167;4086.3257562501853;3513.2446523997464;3618.0773902655183;3619.746617736786;3620.221652344706;3648.4892115103908;652.4780175859294;679.4835924697167;682.1507223506766;694.944404447643;697.8046147078826;516.2823675091626;578.5082679722202;599.6581948289434;607.2439812433548;614.827848202504;8005.719799807095;8259.174082591813;8322.042965059096;8328.5002899825;8474.3644226682;574.4341823304043;581.7963712700606;602.1239097113689;602.1904177945333;619.4579742725116;3853.6339047790416;3870.845166193321;3940.113474468279;3963.048868271652;4008.318585840414;76981.61509192418;96005.51997239969;99228.5038574817;99774;101206;64056.07831765054;77894.610526947;80762.59618701869;87007.56496217605;87233.12766872377;69916.6504167476;70473;71363.64318178376;71603;72951.90572141422;7513.699452021858;7674.232576742281;7856.685732570633;7878.251566101211;7928.246816552418;40225.597744023355;45678.54321456743;46398.53601463942;46802.29796552986;46931.592052238484;50657;62937.68531157315;64310.67844660747;64389.678051609444;64594.031089534634;10475.319104258198;10485.26603137773;10798.028177464066;10837.566497340333;11074.612388566362;11233.045191158517;11339;11450.912163344468;11454.370009649549;11589.362585057903;10487.685369438928;10573.682789516326;10672.092872105957;10795.352278863293;10916.781664366827;10473.214508912015;10531.89468105309;10691.358518488916;10743.623973161215;10960.725981850517;10290.176785857264;10389.06498415116;10560.536888694245;10620.309679870783;10642.41466719338;8805.383623146574;8934.061923497939;8959.955200223958;9014.278857691237;9438.716838494856;8841.381103322705;8949.31769033809;9326.393784403992;9453.149216570542;9473.384230025027 05:50:31 INFO - PROCESS | 1662 | __end_tp_report 05:50:31 INFO - PROCESS | 1662 | __start_cc_report 05:50:31 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,421 05:50:31 INFO - PROCESS | 1662 | __end_cc_report 05:50:31 INFO - PROCESS | 1662 | __startTimestamp1463489431329__endTimestamp 05:50:31 INFO - PROCESS | 1662 | 05:50:31 INFO - PROCESS | 1662 | ------- Summary: start ------- 05:50:31 INFO - PROCESS | 1662 | Number of tests: 6 05:50:31 INFO - PROCESS | 1662 | 05:50:31 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5776.51 Median:6031.00 stddev:767.78 (12.7%) stddev-sans-first:747.11 05:50:31 INFO - PROCESS | 1662 | Values: 3397.5 3443.2 3467.9 3514.1 3540.4 5766.9 5807.7 5876.3 5895.9 5900.6 5715.5 5789.1 5802.7 5814.5 5823.5 5160.5 5206.9 5285.0 5304.7 5336.0 406.9 5291.4 5300.8 5308.9 5353.2 5209.3 5234.4 5255.0 5279.3 5301.5 5146.3 5307.9 5317.4 5330.9 5345.1 5173.6 5185.7 5196.2 5201.3 5213.3 5665.0 5675.6 5812.1 5837.9 5846.4 6154.2 6182.4 6205.7 6272.2 6286.9 6222.6 6244.2 6248.9 6250.8 6270.4 5889.0 5900.0 5904.9 5945.6 5958.6 6186.3 6302.6 6309.2 6325.0 6369.4 5151.6 6269.7 6283.2 6286.4 6299.3 6225.4 6255.0 6301.1 6304.8 6312.9 5014.5 6128.1 6194.7 6212.8 6245.4 5414.6 5992.5 6132.3 6209.9 6266.9 4854.4 4968.0 5007.4 5021.8 5088.4 4403.2 4444.2 4456.8 4458.8 4514.3 5456.6 6003.9 6004.8 6031.0 6057.2 6197.5 6328.5 6343.7 6351.0 6352.9 5966.6 5980.0 6005.8 6018.1 6063.8 5504.2 5519.3 5520.6 5535.4 5549.9 6306.7 6448.3 6520.0 6539.4 6542.9 5243.2 6200.1 6265.8 6280.2 6282.8 6447.4 6504.3 6517.5 6520.7 6527.2 6154.8 6174.9 6184.5 6237.4 6280.1 6257.7 6267.5 6278.7 6292.1 6306.0 6012.3 6249.3 6271.2 6271.9 6275.6 6125.0 6128.7 6150.4 6188.3 6244.8 5901.2 5962.8 5980.3 6061.8 6137.0 6170.5 6178.8 6225.6 6245.8 6254.9 6022.4 6100.5 6115.4 6136.8 6150.5 05:50:31 INFO - PROCESS | 1662 | 05:50:31 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:66084.37 Median:38961.11 stddev:141888.41 (364.2%) stddev-sans-first:142201.02 05:50:31 INFO - PROCESS | 1662 | Values: 507.9 545.3 550.6 550.6 571.5 35816.0 42789.0 42813.1 42877.8 42930.8 43524.0 44193.6 44197.8 44210.0 44328.6 9761.6 9928.5 9938.0 9955.5 9957.3 8367.4 9714.4 9772.3 9808.0 9808.1 8486.7 9620.6 9765.8 9786.2 9798.5 8244.4 9698.7 9726.6 9736.0 9772.0 9765.4 9867.5 9908.1 9933.6 9974.4 40380.4 40705.6 40816.0 40885.2 40930.8 3280.2 3294.5 3360.0 3429.4 3565.5 29863.6 29941.6 29949.9 30204.1 30538.4 2713.9 2731.1 2743.3 2848.6 2855.2 527437.0 534188.0 536611.0 536932.0 537938.0 59511.1 59651.8 60300.0 60300.4 60443.4 29346.3 36053.0 36552.0 37908.8 37955.0 17092.4 17269.9 17270.6 17288.8 17291.4 12618.0 12927.7 12971.8 12986.0 13051.0 19945.3 20011.6 20102.0 20147.6 20173.4 8024.9 8145.1 8166.6 8180.3 8220.3 917.9 1013.6 1020.7 1093.2 1100.8 25512.9 30112.8 30646.7 30673.4 31295.6 1080.4 1088.4 1099.0 1138.9 1139.4 13660.7 15523.9 15785.8 15930.4 15953.8 315187.7 322210.4 370548.0 373252.0 678496.0 61492.7 61513.4 62268.1 62269.8 62330.0 1286.5 1409.9 1422.6 1468.4 1468.8 26063.6 29952.7 29963.6 30269.2 30550.0 58382.7 59153.0 59202.7 60060.4 60091.7 591516.0 613537.0 614960.0 615163.0 615638.0 41965.6 42519.6 42524.7 42814.4 43216.4 35692.0 43601.8 46103.5 46638.4 49374.0 38187.0 38504.0 38643.2 38952.4 39124.0 38523.5 38792.6 38969.8 39072.6 39898.4 33047.5 37863.9 38375.4 38672.0 38756.0 27513.4 30221.1 33246.0 33820.7 34236.5 22276.1 22512.8 24863.9 24934.5 25260.6 05:50:31 INFO - PROCESS | 1662 | 05:50:31 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:35711.19 Median:4828.73 stddev:103022.97 (2133.5%) stddev-sans-first:103277.83 05:50:31 INFO - PROCESS | 1662 | Values: 390.1 395.2 397.9 397.9 416.9 14773.8 19978.9 20620.5 21340.6 21357.8 20661.0 21095.2 21644.0 21676.8 21690.1 4955.8 5310.3 5315.5 5371.5 5383.2 4845.3 5085.8 5290.5 5440.4 5467.4 5358.6 5409.5 5423.2 5439.6 5464.2 5108.7 5413.7 5433.2 5447.2 5455.7 5288.6 5289.6 5295.8 5301.3 5307.8 11804.5 13525.2 25170.7 26641.6 26831.7 2074.1 2075.2 2238.1 2284.0 2465.5 8307.5 9423.8 9973.4 10256.6 14834.6 1684.0 1796.5 1812.4 1832.0 1859.3 116101.0 116579.0 117465.0 120668.0 126010.1 9606.8 11551.9 11898.1 11907.1 11914.4 12570.6 15173.8 15230.2 15299.4 15326.5 8781.0 8798.3 8807.4 8874.2 8892.6 6989.6 8620.1 8715.0 8743.9 8797.5 4657.3 4685.9 4764.0 4768.5 4812.1 1725.7 2021.9 2027.6 2029.4 2035.6 891.5 940.4 994.4 1006.3 1015.2 19819.2 20356.2 20451.8 20860.6 20909.6 943.2 972.8 981.5 990.5 991.9 10348.8 11208.9 11293.0 11295.6 11505.9 598931.0 611615.0 613262.0 623637.0 625729.0 139138.3 140289.0 142107.3 142994.3 143168.2 887.2 1010.6 1021.8 1033.4 1034.2 13453.7 18506.7 18555.0 18692.4 18817.4 54751.9 55968.2 55982.0 57266.4 58251.0 80373.0 83161.2 83685.5 84159.6 84812.0 8156.7 8206.0 8347.8 8410.4 8418.7 8771.3 8835.4 8868.2 9314.5 9411.7 6244.8 7605.2 7954.4 8009.1 8309.0 6742.9 7733.1 8196.0 8289.3 8367.9 8122.7 8199.8 8378.0 8385.1 8508.7 12009.7 12385.7 12398.0 12481.4 12739.0 9958.7 11191.7 11301.2 12089.0 12121.8 05:50:31 INFO - PROCESS | 1662 | 05:50:31 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5845.09 Median:4864.65 stddev:3299.11 (67.8%) stddev-sans-first:3291.45 05:50:31 INFO - PROCESS | 1662 | Values: 1400.1 1650.5 1728.7 1912.6 2008.9 8371.0 8812.7 8834.8 9315.7 9328.4 8924.3 8986.0 9143.7 9157.7 9201.9 2450.0 2469.8 2473.4 2477.0 2485.8 2426.3 2483.8 2484.9 2490.2 2494.3 2370.3 2378.8 2476.1 2480.4 2480.8 2055.4 2444.2 2444.2 2453.8 2459.2 2427.3 2429.3 2429.7 2431.2 2431.5 9120.5 9135.9 9169.2 9174.1 9215.8 4695.9 5445.5 5599.4 5607.6 5633.6 3565.1 3615.9 3642.1 3663.7 3674.4 1036.7 1041.8 1047.0 1048.7 1051.6 11952.6 12087.3 12119.5 12155.1 12177.6 4859.8 4869.5 4978.2 4978.9 5003.5 10201.7 10230.9 10266.7 10270.7 10271.1 5472.2 5472.9 5556.9 5560.2 5569.5 4521.0 4899.8 5074.6 5105.4 5107.7 4023.5 4114.0 4125.7 4159.0 4195.5 1157.4 1351.3 1373.2 1379.2 1385.8 797.4 916.0 924.3 938.2 945.4 5728.8 5917.6 6065.6 6065.8 6102.1 393.5 459.7 463.2 470.7 472.3 3382.7 3964.1 4042.8 4064.9 4110.4 10595.8 10880.5 10892.3 11009.8 11039.9 9943.9 10833.7 11023.9 11050.6 11079.0 8109.6 8400.5 8473.8 8481.3 8500.3 5724.9 5900.0 5976.9 5988.4 6003.4 10207.9 10274.6 10308.2 10315.5 10333.4 8673.7 9826.5 10353.8 10371.7 10585.6 7077.4 7507.2 7723.4 7751.1 7869.0 8620.4 8642.9 8708.7 8749.3 8762.5 7557.6 7701.4 7773.2 7777.8 7806.1 6110.6 7573.7 7581.2 7684.9 7709.1 7108.4 7176.3 7182.1 7193.9 7206.9 4767.7 4928.5 5016.1 5040.1 5075.5 4998.6 5022.9 5061.6 5085.1 5263.9 05:50:31 INFO - PROCESS | 1662 | 05:50:31 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4620.04 Median:5229.41 stddev:1453.44 (27.8%) stddev-sans-first:1421.03 05:50:31 INFO - PROCESS | 1662 | Values: 308.5 318.2 318.2 324.3 325.2 5509.4 5515.7 5543.5 5564.2 5574.5 4239.0 5019.6 5028.3 5467.3 5471.9 3524.8 3985.0 4088.2 4097.2 4111.2 4034.1 4080.0 4092.7 4098.6 4129.5 3407.2 3755.8 4099.7 4113.3 4116.5 3782.5 4040.3 4100.3 4104.9 4123.7 2940.0 3500.8 3512.8 3524.0 3531.2 4147.7 4901.9 4967.6 5045.8 5155.6 4337.3 4646.9 4705.8 4719.1 4730.8 5286.5 5715.8 5734.3 5734.4 5774.9 3406.7 3787.6 3897.5 3899.8 3916.4 5940.6 6059.7 6088.4 6107.4 6134.2 3318.3 3800.7 3947.3 3972.1 4005.6 3424.8 4023.5 4065.7 4066.5 4086.7 4693.0 4710.2 4710.9 4711.0 4714.7 5089.2 5352.4 5504.0 5524.9 5781.3 1190.3 1375.2 1377.5 1378.2 1379.8 815.3 817.6 853.5 857.0 894.8 4716.6 4901.3 5257.8 5324.8 5330.2 4963.5 5510.2 5819.5 5830.7 5916.5 4331.5 4725.9 4836.9 5289.9 5521.3 2875.6 3097.4 3098.4 3099.1 3136.6 5703.3 5800.7 5829.8 6200.7 6244.8 5212.7 5458.6 5695.8 6070.2 6109.9 5946.3 6141.5 6156.8 6210.2 6225.7 5272.8 5364.8 5705.3 5904.0 5932.3 5583.7 5806.0 5893.5 5894.4 5911.2 5838.7 5947.6 6193.7 6227.1 6237.9 3109.6 3337.9 3363.2 3978.2 4001.7 5246.2 5414.5 5528.7 5915.0 5990.5 4825.8 5401.6 5587.0 5719.3 5778.3 5077.6 5648.0 5757.7 5762.9 5787.8 4931.2 5032.5 5412.7 5591.0 5665.4 5637.5 5780.7 5834.9 5839.9 5857.4 4826.7 5713.6 5725.9 5754.4 5760.9 05:50:31 INFO - PROCESS | 1662 | 05:50:31 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:17886.02 Median:604.72 stddev:25562.25 (4227.1%) stddev-sans-first:25601.14 05:50:31 INFO - PROCESS | 1662 | Values: 635.8 667.6 693.9 732.7 742.5 12025.5 16280.1 16608.7 16877.7 16918.5 15228.3 15320.5 15414.8 15677.8 15696.5 748.3 917.1 931.8 949.2 954.0 938.5 939.0 940.9 941.4 951.8 998.2 1001.5 1002.4 1006.2 1006.2 966.4 978.8 990.0 994.5 997.7 733.0 741.3 742.6 743.1 744.5 13163.2 13194.3 13262.9 13289.0 13445.7 10686.9 12527.4 12660.6 12696.2 12774.4 8694.3 9389.2 9957.8 10056.7 10058.9 3376.6 3486.9 3555.3 3564.6 3590.3 58969.0 73180.2 82388.6 83287.0 90099.6 9229.3 9230.6 9258.9 9283.0 9642.9 21096.5 21099.1 21121.0 21124.6 21130.0 6661.4 6966.3 7171.3 7206.2 7375.7 4000.2 4064.3 4064.8 4070.3 4086.3 3513.2 3618.1 3619.7 3620.2 3648.5 652.5 679.5 682.2 694.9 697.8 516.3 578.5 599.7 607.2 614.8 8005.7 8259.2 8322.0 8328.5 8474.4 574.4 581.8 602.1 602.2 619.5 3853.6 3870.8 3940.1 3963.0 4008.3 76981.6 96005.5 99228.5 99774.0 101206.0 64056.1 77894.6 80762.6 87007.6 87233.1 69916.7 70473.0 71363.6 71603.0 72951.9 7513.7 7674.2 7856.7 7878.3 7928.2 40225.6 45678.5 46398.5 46802.3 46931.6 50657.0 62937.7 64310.7 64389.7 64594.0 10475.3 10485.3 10798.0 10837.6 11074.6 11233.0 11339.0 11450.9 11454.4 11589.4 10487.7 10573.7 10672.1 10795.4 10916.8 10473.2 10531.9 10691.4 10743.6 10960.7 10290.2 10389.1 10560.5 10620.3 10642.4 8805.4 8934.1 8960.0 9014.3 9438.7 8841.4 8949.3 9326.4 9453.1 9473.4 05:50:31 INFO - PROCESS | 1662 | -------- Summary: end -------- 05:50:31 INFO - PROCESS | 1662 | 05:50:31 INFO - TEST-INFO | 1662: exit 0 05:50:31 INFO - TEST-OK | dromaeo_css | took 1112697ms 05:50:31 INFO - TEST-START | kraken 05:50:31 INFO - operating with platform_type : mac_ 05:50:31 INFO - Initialising browser for kraken test... 05:50:31 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp2e6xYJ/profile http://localhost:49270/getInfo.html) 05:50:34 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 05:50:34 INFO - PROCESS | 1673 | colorDepth:24 05:50:34 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 05:50:34 INFO - PROCESS | 1673 | __metrics 05:50:34 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:50:35 INFO - TEST-INFO | 1673: exit 0 05:50:35 INFO - Browser initialized. 05:50:35 INFO - Running cycle 1/1 for kraken test... 05:50:35 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp2e6xYJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:50:47 INFO - PROCESS | 1676 | RSS: Main: 249851904 05:50:47 INFO - PROCESS | 1676 | 05:50:50 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 05:50:51 INFO - PROCESS | 1676 | RSS: Main: 261742592 05:50:51 INFO - PROCESS | 1676 | 05:50:53 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 05:50:54 INFO - PROCESS | 1676 | RSS: Main: 266936320 05:50:54 INFO - PROCESS | 1676 | 05:50:58 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 05:50:58 INFO - PROCESS | 1676 | RSS: Main: 266870784 05:50:58 INFO - PROCESS | 1676 | 05:51:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 05:51:01 INFO - PROCESS | 1676 | RSS: Main: 267517952 05:51:01 INFO - PROCESS | 1676 | 05:51:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:04 INFO - PROCESS | 1676 | RSS: Main: 268173312 05:51:04 INFO - PROCESS | 1676 | 05:51:07 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:08 INFO - PROCESS | 1676 | RSS: Main: 269725696 05:51:08 INFO - PROCESS | 1676 | 05:51:11 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:11 INFO - PROCESS | 1676 | RSS: Main: 270884864 05:51:11 INFO - PROCESS | 1676 | 05:51:14 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 05:51:15 INFO - PROCESS | 1676 | RSS: Main: 270725120 05:51:15 INFO - PROCESS | 1676 | 05:51:17 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:51:17 INFO - PROCESS | 1676 | RSS: Main: 270295040 05:51:17 INFO - PROCESS | 1676 | 05:51:19 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:20 INFO - PROCESS | 1676 | RSS: Main: 270270464 05:51:20 INFO - PROCESS | 1676 | 05:51:22 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:23 INFO - PROCESS | 1676 | RSS: Main: 270368768 05:51:23 INFO - PROCESS | 1676 | 05:51:26 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:51:26 INFO - PROCESS | 1676 | RSS: Main: 270397440 05:51:26 INFO - PROCESS | 1676 | 05:51:29 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:51:30 INFO - PROCESS | 1676 | RSS: Main: 270389248 05:51:30 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 05:51:32 INFO - PROCESS | 1676 | RSS: Main: 272195584 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | __start_tp_report 05:51:32 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 05:51:32 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 05:51:32 INFO - PROCESS | 1676 | |i|pagename|runs| 05:51:32 INFO - PROCESS | 1676 | |0;ai-astar;140;151;109;114;118;115;102;145;122;154 05:51:32 INFO - PROCESS | 1676 | |1;audio-beat-detection;157;135;159;200;122;148;131;123;175;123 05:51:32 INFO - PROCESS | 1676 | |2;audio-dft;267;251;197;195;197;201;195;198;192;199 05:51:32 INFO - PROCESS | 1676 | |3;audio-fft;81;111;102;173;67;69;60;57;163;53 05:51:32 INFO - PROCESS | 1676 | |4;audio-oscillator;81;86;160;79;81;82;121;81;83;124 05:51:32 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;134;98;88;85;89;86;116;84;135;130 05:51:32 INFO - PROCESS | 1676 | |6;imaging-darkroom;96;94;94;94;94;93;94;95;95;122 05:51:32 INFO - PROCESS | 1676 | |7;imaging-desaturate;93;93;93;93;90;90;89;96;91;91 05:51:32 INFO - PROCESS | 1676 | |8;json-parse-financial;59;61;61;62;59;69;94;172;64;59 05:51:32 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;63;65;67;72;78;73;68;67;67;68 05:51:32 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;87;89;90;91;101;103;85;84;89;87 05:51:32 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;165;144;121;146;138;145;147;148;217;175 05:51:32 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;167;169;218;226;163;169;164;176;164;181 05:51:32 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;65;68;64;67;67;67;67;67;66;65 05:51:32 INFO - PROCESS | 1676 | __end_tp_report 05:51:32 INFO - PROCESS | 1676 | __start_cc_report 05:51:32 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1069 05:51:32 INFO - PROCESS | 1676 | __end_cc_report 05:51:32 INFO - PROCESS | 1676 | __startTimestamp1463489492463__endTimestamp 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | ------- Summary: start ------- 05:51:32 INFO - PROCESS | 1676 | Number of tests: 14 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:127.00 Median:131.00 stddev:18.76 (14.3%) stddev-sans-first:19.29 05:51:32 INFO - PROCESS | 1676 | Values: 140.0 151.0 109.0 114.0 118.0 115.0 102.0 145.0 122.0 154.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:147.30 Median:152.50 stddev:25.85 (17.0%) stddev-sans-first:27.18 05:51:32 INFO - PROCESS | 1676 | Values: 157.0 135.0 159.0 200.0 122.0 148.0 131.0 123.0 175.0 123.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:209.20 Median:198.50 stddev:26.63 (13.4%) stddev-sans-first:18.27 05:51:32 INFO - PROCESS | 1676 | Values: 267.0 251.0 197.0 195.0 197.0 201.0 195.0 198.0 192.0 199.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:93.60 Median:58.50 stddev:43.57 (74.5%) stddev-sans-first:45.97 05:51:32 INFO - PROCESS | 1676 | Values: 81.0 111.0 102.0 173.0 67.0 69.0 60.0 57.0 163.0 53.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:81.00 stddev:27.69 (34.2%) stddev-sans-first:28.70 05:51:32 INFO - PROCESS | 1676 | Values: 81.0 86.0 160.0 79.0 81.0 82.0 121.0 81.0 83.0 124.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:104.50 Median:85.50 stddev:21.81 (25.5%) stddev-sans-first:20.35 05:51:32 INFO - PROCESS | 1676 | Values: 134.0 98.0 88.0 85.0 89.0 86.0 116.0 84.0 135.0 130.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:97.10 Median:94.00 stddev:8.79 (9.3%) stddev-sans-first:9.31 05:51:32 INFO - PROCESS | 1676 | Values: 96.0 94.0 94.0 94.0 94.0 93.0 94.0 95.0 95.0 122.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:93.00 stddev:2.08 (2.2%) stddev-sans-first:2.17 05:51:32 INFO - PROCESS | 1676 | Values: 93.0 93.0 93.0 93.0 90.0 90.0 89.0 96.0 91.0 91.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:76.00 Median:61.50 stddev:35.35 (57.5%) stddev-sans-first:36.95 05:51:32 INFO - PROCESS | 1676 | Values: 59.0 61.0 61.0 62.0 59.0 69.0 94.0 172.0 64.0 59.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:68.00 stddev:4.37 (6.4%) stddev-sans-first:4.10 05:51:32 INFO - PROCESS | 1676 | Values: 63.0 65.0 67.0 72.0 78.0 73.0 68.0 67.0 67.0 68.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:88.00 stddev:6.40 (7.3%) stddev-sans-first:6.65 05:51:32 INFO - PROCESS | 1676 | Values: 87.0 89.0 90.0 91.0 101.0 103.0 85.0 84.0 89.0 87.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:154.60 Median:147.50 stddev:26.25 (17.8%) stddev-sans-first:27.57 05:51:32 INFO - PROCESS | 1676 | Values: 165.0 144.0 121.0 146.0 138.0 145.0 147.0 148.0 217.0 175.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:172.50 stddev:23.07 (13.4%) stddev-sans-first:24.00 05:51:32 INFO - PROCESS | 1676 | Values: 167.0 169.0 218.0 226.0 163.0 169.0 164.0 176.0 164.0 181.0 05:51:32 INFO - PROCESS | 1676 | 05:51:32 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.00 stddev:1.25 (1.9%) stddev-sans-first:1.24 05:51:32 INFO - PROCESS | 1676 | Values: 65.0 68.0 64.0 67.0 67.0 67.0 67.0 67.0 66.0 65.0 05:51:32 INFO - PROCESS | 1676 | -------- Summary: end -------- 05:51:32 INFO - PROCESS | 1676 | 05:51:33 INFO - TEST-INFO | 1676: exit 0 05:51:33 INFO - TEST-OK | kraken | took 61380ms 05:51:33 INFO - SUITE-END | took 1174s 05:51:33 INFO - Completed test suite (00:19:34) 05:51:33 INFO - javascript benchmark 05:51:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7196.383159373329, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3397.490376443533, 3443.156426675464, 3467.9479807802886, 3514.103903504606, 3540.3981323175058, 5766.913496297559, 5807.709614519268, 5876.324222714389, 5895.911561326584, 5900.586958912878, 5715.457031581993, 5789.131630255453, 5802.738876750545, 5814.4766970972605, 5823.5341172706085, 5160.458151894053, 5206.921896171561, 5285.048691235576, 5304.655197412156, 5336.0, 406.9258351034249, 5291.444398338177, 5300.787968481256, 5308.92036619451, 5353.223782551528, 5209.348831396075, 5234.371875374942, 5254.975279820436, 5279.2872962150195, 5301.549368303698, 5146.253793200002, 5307.920381194285, 5317.388500322474, 5330.946690533084, 5345.09133447311, 5173.611979101544, 5185.740712964337, 5196.246544251062, 5201.349831271091, 5213.348331458567, 5664.971675141639, 5675.602707810455, 5812.099124635688, 5837.854053648693, 5846.444587764156, 6154.1999540059505, 6182.381761823826, 6205.658688772116, 6272.15325930998, 6286.9056964145575, 6222.595531290452, 6244.188255526798, 6248.87502249957, 6250.843728906768, 6270.372962703739, 5889.0, 5900.0, 5904.940950590525, 5945.613535120204, 5958.582899197015, 6186.288576813634, 6302.558820882586, 6309.179806625201, 6325.019621958604, 6369.426751592379, 5151.5878729701535, 6269.749210031548, 6283.246010478681, 6286.402791734778, 6299.338569450142, 6225.408586184305, 6254.959995607756, 6301.117843501914, 6304.810855674337, 6312.873742525124, 5014.473480284518, 6128.105930682418, 6194.690265486708, 6212.751489940442, 6245.375462453719, 5414.6209765316835, 5992.520598352121, 6132.263729246456, 6209.875802483925, 6266.934621114421, 4854.417469903564, 4968.0, 5007.39911210657, 5021.774020169101, 5088.363954505687, 4403.207422663952, 4444.200043992112, 4456.821727130879, 4458.821647134143, 4514.34541991416, 5456.590755693339, 6003.909941350883, 6004.849878753066, 6031.0, 6057.212562366864, 6197.5351848611535, 6328.493720502349, 6343.651099189497, 6351.015592583157, 6352.872942541123, 5966.552508561788, 5980.0132978059, 6005.849853753691, 6018.067199583984, 6063.787767428119, 5504.2294078829, 5519.2824932758485, 5520.558355331564, 5535.446455354432, 5549.889002219933, 6306.716197771019, 6448.25845027809, 6520.021996700533, 6539.378759017885, 6542.901856472156, 5243.239730239017, 6200.0699895016105, 6265.8120256392385, 6280.246370435577, 6282.780102696383, 6447.4197322241225, 6504.349565043458, 6517.543771936109, 6520.706568204537, 6527.249366322933, 6154.846128846814, 6174.876502469836, 6184.474319682698, 6237.376262373727, 6280.0893870389455, 6257.731497811597, 6267.467265282502, 6278.6546739929745, 6292.11910332563, 6305.959516679696, 6012.278526576839, 6249.281332646804, 6271.184745982993, 6271.874562508816, 6275.560710750204, 6124.958757011408, 6128.686460748492, 6150.415710507495, 6188.257409110935, 6244.781432650017, 5901.173835663097, 5962.821115366546, 5980.252468441445, 6061.848453788691, 6137.018077107641, 6170.475509581735, 6178.752849886134, 6225.595336303126, 6245.812625621239, 6254.937450625617, 6022.36765139654, 6100.481459076028, 6115.388461153849, 6136.815895523141, 6150.477209437069], "value": 5731.104917276478, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.94158671752734, 545.2856757647482, 550.5925615044868, 550.6283258800308, 571.5456212311212, 35816.0, 42789.0, 42813.143737125276, 42877.78561107194, 42930.7853460733, 43524.0, 44193.55806441935, 44197.779011104976, 44210.0, 44328.556714432925, 9761.609535618585, 9928.503574821267, 9938.0, 9955.502224888764, 9957.302988790787, 8367.372447066464, 9714.417134971889, 9772.267079968995, 9808.019198080206, 9808.068233517804, 8486.726990951376, 9620.567074481665, 9765.804683906317, 9786.217102631808, 9798.461084640341, 8244.381671374671, 9698.70903872885, 9726.562304696306, 9736.026397360278, 9772.0, 9765.365251258645, 9867.50662466881, 9908.108270255712, 9933.55299011539, 9974.351667141613, 40380.394294085614, 40705.59294407048, 40816.0, 40885.18229635391, 40930.79534602338, 3280.1799550112473, 3294.5222942673417, 3360.0087974047715, 3429.4341433663417, 3565.4651802229614, 29863.552046719316, 29941.550876736866, 29949.850250748827, 30204.09387718372, 30538.38923221523, 2713.864306784653, 2731.139690997349, 2743.2867454461784, 2848.5584734366207, 2855.1822901127725, 527437.0, 534188.0, 536611.0, 536932.0, 537938.0, 59511.107333390035, 59651.806963861345, 60300.0, 60300.39699602948, 60443.39556604378, 29346.266343341587, 36053.0, 36552.0, 37908.81045594809, 37955.0, 17092.40176593813, 17269.913650431667, 17270.568235793955, 17288.827111728722, 17291.394801181897, 12618.0, 12927.741445171045, 12971.805422918664, 12986.026048046435, 13051.021173412031, 19945.301914432926, 20011.59976800456, 20101.99490025493, 20147.597048058957, 20173.394798156078, 8024.879626805603, 8145.144759800136, 8166.550839703873, 8180.304674102649, 8220.301274391744, 917.9403338782957, 1013.6097602422973, 1020.6784862768204, 1093.2019625673145, 1100.8403781451646, 25512.872435637702, 30112.84943575312, 30646.693533064383, 30673.38653226923, 31295.619984596386, 1080.3679847289266, 1088.4448931045065, 1098.983515247272, 1138.9088872890316, 1139.3676509537286, 13660.726785464236, 15523.922380388252, 15785.763213551805, 15930.442434514736, 15953.840461595235, 315187.6646784449, 322210.38894805376, 370548.0, 373252.0, 678496.0, 61492.69253653793, 61513.38486615166, 62268.06597901035, 62269.75460490765, 62330.0, 1286.5304163980268, 1409.8519655436237, 1422.5732280315865, 1468.4493315006873, 1468.8237411510688, 26063.609045864705, 29952.70047299499, 29963.55054674138, 30269.152990395767, 30550.0, 58382.70808645929, 59153.0, 59202.70398647979, 60060.399396007226, 60091.69954150376, 591516.0, 613537.0, 614960.0, 615163.0, 615638.0, 41965.58034419617, 42519.5748042528, 42524.72425827106, 42814.35778463388, 43216.35175472433, 35692.0, 43601.78199108984, 46103.53896460992, 46638.43488608159, 49374.012519750126, 38187.0, 38504.0, 38643.227135457695, 38952.41571376488, 39124.0, 38523.53288204075, 38792.61207388003, 38969.8051509752, 39072.609273908034, 39898.40152397774, 33047.50428743619, 37863.86408407752, 38375.42436863505, 38672.0, 38756.0, 27513.44973100567, 30221.09336719902, 33246.0, 33820.661793381754, 34236.48645270273, 22276.108955641594, 22512.774872251724, 24863.87568062148, 24934.50130997406, 25260.621090683286], "value": 19966.58814940509, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.1280637774573, 395.2352198495911, 397.93434083376246, 397.940308953657, 416.8686863637954, 14773.778393324083, 19978.90010549947, 20620.484487887774, 21340.57318853622, 21357.786422135792, 20661.0, 21095.15619375227, 21644.02601882915, 21676.783232167672, 21690.132394704193, 4955.826546070888, 5310.283111779918, 5315.521603055724, 5371.462853714617, 5383.219433182187, 4845.345878306436, 5085.771140298695, 5290.470952904717, 5440.374356948962, 5467.398586155519, 5358.571314294866, 5409.486098820606, 5423.240746295522, 5439.564834813205, 5464.153056276284, 5108.69347839129, 5413.675179489223, 5433.212184233264, 5447.1829225616075, 5455.672659640414, 5288.629795914288, 5289.576833853283, 5295.814646487354, 5301.284326615896, 5307.761150748187, 11804.527818887234, 13525.18848869071, 25170.74146292678, 26641.600375994374, 26831.73168268312, 2074.1495986645405, 2075.190675636503, 2238.1383167480635, 2284.0292875527834, 2465.5438743832447, 8307.501549907025, 9423.82202224722, 9973.401595904268, 10256.641017564349, 14834.554963351116, 1683.966320673592, 1796.4700413378084, 1812.420025591811, 1832.0290246169552, 1859.3120545398153, 116101.0, 116579.0, 117465.0, 120668.0, 126010.10984835327, 9606.759831004281, 11551.88448115508, 11898.10764192689, 11907.106966977512, 11914.404279785977, 12570.560030398892, 15173.848261517243, 15230.162341071125, 15299.388024478896, 15326.463573774865, 8780.956095219482, 8798.340124490689, 8807.383483156245, 8874.245689116497, 8892.555372231363, 6989.580625162506, 8620.13798620133, 8714.95642521796, 8743.868841967376, 8797.47215167092, 4657.301404789308, 4685.929711054337, 4764.0, 4768.451628062817, 4812.109759694397, 1725.6980028494963, 2021.86857854239, 2027.5640737241563, 2029.401326608654, 2035.5827055453597, 891.5453118909403, 940.3934462271797, 994.3685759542742, 1006.3307900246255, 1015.1624909449733, 19819.207231710858, 20356.18575257003, 20451.795482045287, 20860.58278834415, 20909.581808363746, 943.1700103908516, 972.7713987212999, 981.5337714585514, 990.5393991793851, 991.8859331176862, 10348.793024139475, 11208.943955280172, 11293.0, 11295.604653837076, 11505.884941150649, 598931.0, 611615.0, 613262.0, 623637.0, 625729.0, 139138.30430847782, 140289.0, 142107.289463552, 142994.2850285742, 143168.19875776398, 887.1837909123622, 1010.5604062232952, 1021.759886426704, 1033.4161198922584, 1034.2139973620103, 13453.730925381633, 18506.722399164293, 18555.0, 18692.43922682322, 18817.435476935712, 54751.89360798036, 55968.24269937101, 55982.0, 57266.42733572611, 58251.0, 80373.0, 83161.16838831776, 83685.48943531461, 84159.5792021036, 84812.0, 8156.673733050611, 8205.95897020511, 8347.83304333922, 8410.411271210956, 8418.747437576883, 8771.254443372129, 8835.425697329652, 8868.246199073152, 9314.487703176119, 9411.717648470556, 6244.781432649836, 7605.23947605235, 7954.403419743659, 8009.078955919911, 8309.00291964968, 6742.932570674427, 7733.110692270461, 8195.95902020486, 8289.336853051862, 8367.916320836714, 8122.7157049502985, 8199.836003280021, 8378.0, 8385.1195624461, 8508.659653613786, 12009.699757506132, 12385.690357740768, 12398.00815934705, 12481.375931203222, 12739.0, 9958.651447199314, 11191.66425007251, 11301.208915376174, 12089.0, 12121.757564848478], "value": 9289.97766152413, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.13082534816, 1650.4800987688873, 1728.7406888966661, 1912.5505506206039, 2008.889511076891, 8370.995480542328, 8812.691555795549, 8834.823303533925, 9315.720528384143, 9328.44029358239, 8924.33067519937, 8986.0, 9143.679971201009, 9157.725268241948, 9201.907980920188, 2450.0322372662786, 2469.790067844235, 2473.3940184654784, 2476.9752302477013, 2485.8135639826996, 2426.296374051523, 2483.801295896324, 2484.8633325167107, 2490.2404766546197, 2494.326531836403, 2370.288913326004, 2378.82158838087, 2476.05909754294, 2480.3675062858947, 2480.7519248075137, 2055.4142069510117, 2444.156765915747, 2444.1934161726676, 2453.7546245375497, 2459.213051823417, 2427.296084135616, 2429.344077099191, 2429.671994280767, 2431.222008957134, 2431.477232395026, 9120.498372589505, 9135.862962055575, 9169.220616247627, 9174.08259174084, 9215.815683686289, 4695.88260293492, 5445.50990410861, 5599.440055994409, 5607.551395888319, 5633.577481688849, 3565.0552603560077, 3615.8553657853918, 3642.05306620278, 3663.7435379523445, 3674.430463278196, 1036.740814796301, 1041.8383502394852, 1046.984295235572, 1048.6644273832376, 1051.5636011055403, 11952.64142075739, 12087.27476351413, 12119.515219391302, 12155.149139560146, 12177.634670959886, 4859.757012149378, 4869.537393947569, 4978.178600530944, 4978.850634480971, 5003.524665156804, 10201.74495637615, 10230.948845255725, 10266.743331416774, 10270.743231419125, 10271.126954208827, 5472.151816468374, 5472.863178420572, 5556.944430555724, 5560.193771903051, 5569.498745112959, 4520.977395113004, 4899.779509922062, 5074.594032477393, 5105.361829771278, 5107.693538387709, 4023.511300818716, 4113.979430102891, 4125.690573207022, 4158.979205103955, 4195.538490766014, 1157.3518829455531, 1351.3243378310844, 1373.230990645242, 1379.2276325257887, 1385.764420048594, 797.4178849439942, 915.9725208243764, 924.3390975452584, 938.2447130060367, 945.3996712087874, 5728.770849165987, 5917.5857689961285, 6065.636061836216, 6065.848353791102, 6102.058340323486, 393.51597535032073, 459.70349124814305, 463.1732357741415, 470.6540692590943, 472.26326929989324, 3382.6955573997966, 3964.147708242742, 4042.757434553936, 4064.861838685173, 4110.424540564323, 10595.788084238273, 10880.455977201109, 10892.292001019909, 11009.779804403866, 11039.944800275947, 9943.850842237372, 10833.67498975032, 11023.889761102446, 11050.557977680963, 11079.0, 8109.594520273963, 8400.453970491899, 8473.788155296166, 8481.27909127731, 8500.319974402033, 5724.942750572608, 5899.9675056865735, 5976.850578735566, 5988.431099045583, 6003.3996600339615, 10207.897921020694, 10274.589016439259, 10308.175345972155, 10315.48422578853, 10333.37999719989, 8673.696420625249, 9826.508674566387, 10353.84469232947, 10371.740706482398, 10585.576576936835, 7077.433805295667, 7507.211742766931, 7723.382129429519, 7751.147373788992, 7869.016372953381, 8620.439671421338, 8642.913570864463, 8708.69519566812, 8749.300055995378, 8762.474251544672, 7557.622118894144, 7701.422393320636, 7773.183815699496, 7777.805554861174, 7806.102298235548, 6110.633361998294, 7573.697052117854, 7581.166071732216, 7684.923150768421, 7709.113451952873, 7108.3602475777425, 7176.282371762782, 7182.066331376887, 7193.856122877409, 7206.89189662166, 4767.73777442244, 4928.531789479994, 5016.097102521435, 5040.143175660073, 5075.51782580669, 4998.575121114601, 5022.924656130234, 5061.645684802029, 5085.135526960499, 5263.947360526346], "value": 4614.468197827297, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.5310328300981, 318.19337978225195, 318.2378204200939, 324.26553855517244, 325.201629998354, 5509.4105770288, 5515.710979943318, 5543.528800052, 5564.248826408434, 5574.488072514316, 4239.025024244418, 5019.590596513675, 5028.2709007193935, 5467.289252397183, 5471.945280547194, 3524.7885126892406, 3985.0, 4088.2028004539125, 4097.221527909703, 4111.218868415007, 4034.1326614777818, 4079.97960010201, 4092.6930480213955, 4098.569650186732, 4129.483814523184, 3407.1822762537063, 3755.75587586806, 4099.692523060782, 4113.300738874384, 4116.526599441073, 3782.470454136396, 4040.323265844671, 4100.261952848486, 4104.897377565585, 4123.680422264876, 2939.970600293991, 3500.842462089212, 3512.771669841452, 3523.9956612365354, 3531.170175008871, 4147.668186545069, 4901.857930049103, 4967.627427942919, 5045.763787871958, 5155.639105262633, 4337.349397590355, 4646.9070618587775, 4705.764711764432, 4719.07977944301, 4730.787114579852, 5286.471352864722, 5715.828525144252, 5734.311882574076, 5734.369219385864, 5774.855628609276, 3406.67636574525, 3787.594877252937, 3897.4738410314526, 3899.8245078971513, 3916.373380259145, 5940.6435613863305, 6059.727312270914, 6088.421599948042, 6107.4197951194565, 6134.178277992129, 3318.3197444523817, 3800.733948623625, 3947.3092208863336, 3972.140059157402, 4005.559388467266, 3424.8116353600294, 4023.4509713759826, 4065.715399921977, 4066.532348779869, 4086.6730661547003, 4693.0, 4710.199266124767, 4710.929336059962, 4711.0, 4714.669973101919, 5089.236614507779, 5352.384475785257, 5504.036793561111, 5524.944750552523, 5781.306243250837, 1190.33936165429, 1375.1542801177204, 1377.5316392426623, 1378.1662094432925, 1379.7930310453312, 815.2784785464788, 817.6116344736203, 853.4537895746727, 856.9828603427896, 894.8344556257114, 4716.622670186377, 4901.289313049588, 5257.7896884124975, 5324.7870085196155, 5330.227117068003, 4963.5036496350795, 5510.173473978935, 5819.476247137778, 5830.679312637845, 5916.49709774674, 4331.480222373272, 4725.881852953704, 4836.911694868626, 5289.867753306121, 5521.309836270466, 2875.5686647003117, 3097.3650401667605, 3098.380323935222, 3099.1477343730653, 3136.619887249603, 5703.287089113861, 5800.709964501758, 5829.8251052468495, 6200.689965501707, 6244.812655620339, 5212.661177023481, 5458.563314934796, 5695.781883236041, 6070.209288259974, 6109.877802443926, 5946.256717910262, 6141.477974372139, 6156.815295541141, 6210.223722034836, 6225.688715564113, 5272.815451459181, 5364.758585863724, 5705.34388545306, 5903.970480147572, 5932.2584676915385, 5583.720813959367, 5806.02595117662, 5893.528517718658, 5894.38108998549, 5911.231539899957, 5838.678872662044, 5947.613405128653, 6193.721282542207, 6227.128202051807, 6237.906431403623, 3109.626844778641, 3337.8998630041137, 3363.1928337199383, 3978.1645854370904, 4001.7398869073418, 5246.160614301693, 5414.539764120094, 5528.668279903219, 5915.024021036481, 5990.520758339235, 4825.806967721252, 5401.648892821954, 5586.972065139811, 5719.342275638189, 5778.306603207642, 5077.619178561548, 5647.9949617886705, 5757.7121143941795, 5762.942370576408, 5787.813498232937, 4931.235658472942, 5032.521910418504, 5412.675239485643, 5591.021571224938, 5665.413684168439, 5637.548996080386, 5780.682062486436, 5834.854128646818, 5839.89042082003, 5857.414258574108, 4826.710397376169, 5713.571482138773, 5725.8568535786935, 5754.367019627922, 5760.884782304414], "value": 4155.202670330996, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.7615894039735, 667.6194569095616, 693.8681650486408, 732.6593134192599, 742.5099434373311, 12025.518979240831, 16280.104594247312, 16608.66782664346, 16877.746833797504, 16918.49244522663, 15228.314725837336, 15320.463783767571, 15414.84585154148, 15677.84322156778, 15696.450624228155, 748.3265061444716, 917.0920788419484, 931.8276118917988, 949.2121539122546, 954.0078318548701, 938.4979036215626, 938.9577469013876, 940.8870935487716, 941.4257303045136, 951.8191543606728, 998.181491177235, 1001.4992503748126, 1002.4085789384262, 1006.1548299428479, 1006.1598565198068, 966.429806414212, 978.8091322192175, 989.9950500247488, 994.5226291380105, 997.7106639074659, 733.0470388494989, 741.2587412587412, 742.6138408027796, 743.1379599664364, 744.4900243333279, 13163.210207387492, 13194.340282985811, 13262.933685331607, 13289.003324750585, 13445.731085378238, 10686.893131068668, 12527.38320628845, 12660.620181394574, 12696.238225706487, 12774.361281935866, 8694.347923905732, 9389.154976052188, 9957.75105622358, 10056.748581285454, 10058.89941100587, 3376.6116896556964, 3486.9476957845654, 3555.253396786678, 3564.625714299987, 3590.299891521154, 58969.0, 73180.1704957375, 82388.58805705933, 83287.0, 90099.64850527313, 9229.261659067259, 9230.630774769068, 9258.861117083248, 9282.953585232166, 9642.903570964341, 21096.47258818511, 21099.05054272531, 21121.0, 21124.577508449744, 21130.0, 6661.367170118928, 6966.268541803038, 7171.318724721144, 7206.171290301578, 7375.668094935741, 4000.179963107556, 4064.349704047338, 4064.7764372959773, 4070.3283958147167, 4086.3257562501853, 3513.2446523997464, 3618.0773902655183, 3619.746617736786, 3620.221652344706, 3648.4892115103908, 652.4780175859294, 679.4835924697167, 682.1507223506766, 694.944404447643, 697.8046147078826, 516.2823675091626, 578.5082679722202, 599.6581948289434, 607.2439812433548, 614.827848202504, 8005.719799807095, 8259.174082591813, 8322.042965059096, 8328.5002899825, 8474.3644226682, 574.4341823304043, 581.7963712700606, 602.1239097113689, 602.1904177945333, 619.4579742725116, 3853.6339047790416, 3870.845166193321, 3940.113474468279, 3963.048868271652, 4008.318585840414, 76981.61509192418, 96005.51997239969, 99228.5038574817, 99774.0, 101206.0, 64056.07831765054, 77894.610526947, 80762.59618701869, 87007.56496217605, 87233.12766872377, 69916.6504167476, 70473.0, 71363.64318178376, 71603.0, 72951.90572141422, 7513.699452021858, 7674.232576742281, 7856.685732570633, 7878.251566101211, 7928.246816552418, 40225.597744023355, 45678.54321456743, 46398.53601463942, 46802.29796552986, 46931.592052238484, 50657.0, 62937.68531157315, 64310.67844660747, 64389.678051609444, 64594.031089534634, 10475.319104258198, 10485.26603137773, 10798.028177464066, 10837.566497340333, 11074.612388566362, 11233.045191158517, 11339.0, 11450.912163344468, 11454.370009649549, 11589.362585057903, 10487.685369438928, 10573.682789516326, 10672.092872105957, 10795.352278863293, 10916.781664366827, 10473.214508912015, 10531.89468105309, 10691.358518488916, 10743.623973161215, 10960.725981850517, 10290.176785857264, 10389.06498415116, 10560.536888694245, 10620.309679870783, 10642.41466719338, 8805.383623146574, 8934.061923497939, 8959.955200223958, 9014.278857691237, 9438.716838494856, 8841.381103322705, 8949.31769033809, 9326.393784403992, 9453.149216570542, 9473.384230025027], "value": 6813.5016837289, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1604.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 151.0, 109.0, 114.0, 118.0, 115.0, 102.0, 145.0, 122.0, 154.0], "value": 127.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 135.0, 159.0, 200.0, 122.0, 148.0, 131.0, 123.0, 175.0, 123.0], "value": 147.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [267.0, 251.0, 197.0, 195.0, 197.0, 201.0, 195.0, 198.0, 192.0, 199.0], "value": 209.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 111.0, 102.0, 173.0, 67.0, 69.0, 60.0, 57.0, 163.0, 53.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 160.0, 79.0, 81.0, 82.0, 121.0, 81.0, 83.0, 124.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 98.0, 88.0, 85.0, 89.0, 86.0, 116.0, 84.0, 135.0, 130.0], "value": 104.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 95.0, 122.0], "value": 97.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 90.0, 90.0, 89.0, 96.0, 91.0, 91.0], "value": 91.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 61.0, 62.0, 59.0, 69.0, 94.0, 172.0, 64.0, 59.0], "value": 76.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 67.0, 72.0, 78.0, 73.0, 68.0, 67.0, 67.0, 68.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 90.0, 91.0, 101.0, 103.0, 85.0, 84.0, 89.0, 87.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 144.0, 121.0, 146.0, 138.0, 145.0, 147.0, 148.0, 217.0, 175.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 218.0, 226.0, 163.0, 169.0, 164.0, 176.0, 164.0, 181.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 64.0, 67.0, 67.0, 67.0, 67.0, 67.0, 66.0, 65.0], "value": 66.3, "unit": "score"}]}]} 05:51:33 INFO - Return code: 0 05:51:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:51:33 ERROR - # TBPL SUCCESS # 05:51:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:51:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:51:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:51:33 INFO - 2016-05-17 05:51:33.769 screenresolution[1681:10289] starting screenresolution argv=screenresolution get 05:51:33 INFO - 2016-05-17 05:51:33.772 screenresolution[1681:10289] Display 0: 1600x1200x32@60 05:51:33 INFO - 2016-05-17 05:51:33.777 screenresolution[1682:10292] starting screenresolution argv=screenresolution list 05:51:33 INFO - Available Modes on Display 0 05:51:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:51:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:51:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:51:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:51:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:51:34 INFO - Intel Iris: 05:51:34 INFO - Chipset Model: Intel Iris 05:51:34 INFO - Type: GPU 05:51:34 INFO - Bus: Built-In 05:51:34 INFO - VRAM (Dynamic, Max): 1536 MB 05:51:34 INFO - Vendor: Intel (0x8086) 05:51:34 INFO - Device ID: 0x0a2e 05:51:34 INFO - Revision ID: 0x0009 05:51:34 INFO - Displays: 05:51:34 INFO - Display: 05:51:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:51:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:51:34 INFO - Display Serial Number: 1600x1200 60 05:51:34 INFO - Main Display: Yes 05:51:34 INFO - Mirror: Off 05:51:34 INFO - Online: Yes 05:51:34 INFO - Rotation: Supported 05:51:34 INFO - Return code: 0 05:51:34 INFO - Running post-action listener: _resource_record_post_action 05:51:34 INFO - ##### 05:51:34 INFO - ##### Finished run-tests step (success) 05:51:34 INFO - ##### 05:51:34 INFO - Running post-run listener: _resource_record_post_run 05:51:34 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 37619200; Write bytes: 695574528; Read time: 391; Write time: 1983 05:51:34 INFO - TinderboxPrint: CPU usage
54.4% 05:51:34 INFO - TinderboxPrint: I/O read bytes / time
37,619,200 / 391 05:51:34 INFO - TinderboxPrint: I/O write bytes / time
695,574,528 / 1,983 05:51:34 INFO - TinderboxPrint: CPU idle
2,203.6 (45.6%) 05:51:34 INFO - TinderboxPrint: CPU system
969.5 (20.0%) 05:51:34 INFO - TinderboxPrint: CPU user
1,664.5 (34.4%) 05:51:34 INFO - TinderboxPrint: Swap in / out
432,594,944 / 0 05:51:34 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382620160; Write bytes: 376223232; Read time: 28479; Write time: 696 05:51:34 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35816960; Write bytes: 297843200; Read time: 370; Write time: 1238 05:51:34 INFO - Running post-run listener: _upload_blobber_files 05:51:34 INFO - Blob upload gear active. 05:51:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:51:34 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:51:34 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:51:34 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:51:34 INFO - (blobuploader) - INFO - Open directory for files ... 05:51:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:51:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:51:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:35 INFO - (blobuploader) - INFO - Done attempting. 05:51:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:51:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:51:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:36 INFO - (blobuploader) - INFO - Done attempting. 05:51:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:51:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:51:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:39 INFO - (blobuploader) - INFO - Done attempting. 05:51:39 INFO - (blobuploader) - INFO - Iteration through files over. 05:51:39 INFO - Return code: 0 05:51:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:51:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:51:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97e4a889ba9cc29819da4d46dcd7503cdd279df6b55d93ea979efa35411bef9f423b82797d6a790453ca2ad775a0eb4c0a87bb9cb8b1bf9543d1cb78085903b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5121944ad338991f731186344883c3d77df30ca06aa47c3370a4738c0bac31641175ea14671165938bf4c5cbccd80fa8f66f4fed26c5ebef3551d6c60930babc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc2f11d86f106b36f5e65d802b80207e537489016860889a1f1adfb305e82d19c5eb3d64edba578fb018a68875b51d66a51cf535d996396bc147b9550d463066"} 05:51:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:51:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:51:39 INFO - Contents: 05:51:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97e4a889ba9cc29819da4d46dcd7503cdd279df6b55d93ea979efa35411bef9f423b82797d6a790453ca2ad775a0eb4c0a87bb9cb8b1bf9543d1cb78085903b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5121944ad338991f731186344883c3d77df30ca06aa47c3370a4738c0bac31641175ea14671165938bf4c5cbccd80fa8f66f4fed26c5ebef3551d6c60930babc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc2f11d86f106b36f5e65d802b80207e537489016860889a1f1adfb305e82d19c5eb3d64edba578fb018a68875b51d66a51cf535d996396bc147b9550d463066"} 05:51:39 INFO - Running post-run listener: copy_logs_to_upload_dir 05:51:39 INFO - Copying logs to upload dir... 05:51:39 INFO - mkdir: /builds/slave/test/build/upload/logs 05:51:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.363058 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-17 05:51:39.966624) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 05:51:39.970620) ========= 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.EZfroAETp7/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.wJnDGx7gN5/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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/97e4a889ba9cc29819da4d46dcd7503cdd279df6b55d93ea979efa35411bef9f423b82797d6a790453ca2ad775a0eb4c0a87bb9cb8b1bf9543d1cb78085903b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5121944ad338991f731186344883c3d77df30ca06aa47c3370a4738c0bac31641175ea14671165938bf4c5cbccd80fa8f66f4fed26c5ebef3551d6c60930babc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc2f11d86f106b36f5e65d802b80207e537489016860889a1f1adfb305e82d19c5eb3d64edba578fb018a68875b51d66a51cf535d996396bc147b9550d463066"} build_url:https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010966 build_url: 'https://queue.taskcluster.net/v1/task/EJ01rgPETYuwxgF3Y4OnTQ/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/97e4a889ba9cc29819da4d46dcd7503cdd279df6b55d93ea979efa35411bef9f423b82797d6a790453ca2ad775a0eb4c0a87bb9cb8b1bf9543d1cb78085903b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5121944ad338991f731186344883c3d77df30ca06aa47c3370a4738c0bac31641175ea14671165938bf4c5cbccd80fa8f66f4fed26c5ebef3551d6c60930babc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc2f11d86f106b36f5e65d802b80207e537489016860889a1f1adfb305e82d19c5eb3d64edba578fb018a68875b51d66a51cf535d996396bc147b9550d463066"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 05:51:39.999405) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:51:39.999727) ========= 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.EZfroAETp7/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.wJnDGx7gN5/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.005028 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:51:40.048491) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 05:51:40.048802) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 05:51:40.049176) ========= ========= Total master_lag: 0.15 =========