builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0070 starttime: 1464102918.8 results: success (0) buildid: 20160524055509 builduid: 7b184da7e9404d6784228b66b8fb2b35 revision: 829d3be6ba648b838ee1953fdfa1a477dace752f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 08:15:18.795339) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 08:15:18.795738) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 10 secs) (at 2016-05-24 08:15:18.796013) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 10 secs) (at 2016-05-24 08:15:28.992129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-24 08:15:28.992463) ========= 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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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-24 08:15: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% 8.28M=0.001s 2016-05-24 08:15:29 (8.28 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.604984 ========= master_lag: 2.73 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-24 08:15:32.327799) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 08:15:32.328136) ========= 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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.020072 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 08:15:32.368389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 08:15:32.368713) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 829d3be6ba648b838ee1953fdfa1a477dace752f --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 829d3be6ba648b838ee1953fdfa1a477dace752f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kkTlUEa2rh/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.sebTEOv9Tm/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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-24 08:15:32,441 truncating revision to first 12 chars 2016-05-24 08:15:32,441 Setting DEBUG logging. 2016-05-24 08:15:32,441 attempt 1/10 2016-05-24 08:15:32,442 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/829d3be6ba64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 08:15:33,469 unpacking tar archive at: mozilla-central-829d3be6ba64/testing/mozharness/ program finished with exit code 0 elapsedTime=1.280331 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 08:15:33.668053) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 08:15:33.668343) ========= script_repo_revision: 829d3be6ba648b838ee1953fdfa1a477dace752f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 08:15:33.668723) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 08:15:33.668986) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 08:15:33.690568) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 37 secs) (at 2016-05-24 08:15:33.690834) ========= /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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 08:15:33 INFO - MultiFileLogger online at 20160524 08:15:33 in /builds/slave/test 08:15:33 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 08:15:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:15:33 INFO - {'append_to_log': False, 08:15:33 INFO - 'base_work_dir': '/builds/slave/test', 08:15:33 INFO - 'blob_upload_branch': 'Firefox', 08:15:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:15:33 INFO - 'branch': 'Firefox', 08:15:33 INFO - 'buildbot_json_path': 'buildprops.json', 08:15:33 INFO - 'config_files': ('talos/mac_config.py',), 08:15:33 INFO - 'default_actions': ('clobber', 08:15:33 INFO - 'read-buildbot-config', 08:15:33 INFO - 'download-and-extract', 08:15:33 INFO - 'populate-webroot', 08:15:33 INFO - 'create-virtualenv', 08:15:33 INFO - 'install', 08:15:33 INFO - 'run-tests'), 08:15:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:15:33 INFO - 'download_minidump_stackwalk': True, 08:15:33 INFO - 'download_symbols': 'ondemand', 08:15:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:15:33 INFO - 'tooltool.py': '/tools/tooltool.py', 08:15:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:15:33 INFO - '/tools/misc-python/virtualenv.py')}, 08:15:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:15:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:15:33 INFO - 'installer_path': 'installer.exe', 08:15:33 INFO - 'log_level': 'info', 08:15:33 INFO - 'log_name': 'talos', 08:15:33 INFO - 'log_to_console': True, 08:15:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:15:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:15:33 INFO - 'opt_config_files': (), 08:15:33 INFO - 'pip_index': False, 08:15:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:15:33 INFO - 'cmd': ('bash', 08:15:33 INFO - '-c', 08:15:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:15:33 INFO - 'enabled': True, 08:15:33 INFO - 'halt_on_failure': False, 08:15:33 INFO - 'name': 'check_screen_resolution'},), 08:15:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:15:33 INFO - 'cmd': ('bash', 08:15:33 INFO - '-c', 08:15:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:15:33 INFO - 'enabled': True, 08:15:33 INFO - 'halt_on_failure': False, 08:15:33 INFO - 'name': 'check_screen_resolution'},), 08:15:33 INFO - 'run_cmd_checks_enabled': True, 08:15:33 INFO - 'sps_profile': False, 08:15:33 INFO - 'sps_profile_interval': 0, 08:15:33 INFO - 'suite': 'dromaeojs-e10s', 08:15:33 INFO - 'system_bits': '32', 08:15:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:15:33 INFO - 'title': 't-yosemite-r7-0070', 08:15:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:15:33 INFO - 'use_talos_json': True, 08:15:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:15:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:15:33 INFO - 'work_dir': 'build'} 08:15:33 INFO - ##### 08:15:33 INFO - ##### Running clobber step. 08:15:33 INFO - ##### 08:15:33 INFO - Running pre-action listener: _resource_record_pre_action 08:15:33 INFO - Running main action method: clobber 08:15:33 INFO - rmtree: /builds/slave/test/build 08:15:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:15:34 INFO - Running post-action listener: _resource_record_post_action 08:15:34 INFO - ##### 08:15:34 INFO - ##### Finished clobber step (success) 08:15:34 INFO - ##### 08:15:34 INFO - ##### 08:15:34 INFO - ##### Running read-buildbot-config step. 08:15:34 INFO - ##### 08:15:34 INFO - Running pre-action listener: _resource_record_pre_action 08:15:34 INFO - Running main action method: read_buildbot_config 08:15:34 INFO - Using buildbot properties: 08:15:34 INFO - { 08:15:34 INFO - "project": "", 08:15:34 INFO - "product": "firefox", 08:15:34 INFO - "script_repo_revision": "production", 08:15:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:15:34 INFO - "repository": "", 08:15:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:15:34 INFO - "buildid": "20160524055509", 08:15:34 INFO - "pgo_build": "False", 08:15:34 INFO - "basedir": "/builds/slave/test", 08:15:34 INFO - "buildnumber": 7, 08:15:34 INFO - "slavename": "t-yosemite-r7-0070", 08:15:34 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:15:34 INFO - "platform": "yosemite_r7", 08:15:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:15:34 INFO - "branch": "mozilla-central", 08:15:34 INFO - "repo_path": "mozilla-central", 08:15:34 INFO - "revision": "829d3be6ba648b838ee1953fdfa1a477dace752f", 08:15:34 INFO - "stage_platform": "macosx64", 08:15:34 INFO - "builduid": "7b184da7e9404d6784228b66b8fb2b35", 08:15:34 INFO - "slavebuilddir": "test" 08:15:34 INFO - } 08:15:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:15:34 INFO - Running post-action listener: _resource_record_post_action 08:15:34 INFO - ##### 08:15:34 INFO - ##### Finished read-buildbot-config step (success) 08:15:34 INFO - ##### 08:15:34 INFO - ##### 08:15:34 INFO - ##### Running download-and-extract step. 08:15:34 INFO - ##### 08:15:34 INFO - Running pre-action listener: _resource_record_pre_action 08:15:34 INFO - Running main action method: download_and_extract 08:15:34 INFO - mkdir: /builds/slave/test/build/tests 08:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:15:34 INFO - https://queue.taskcluster.net/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:15:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:15:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:15:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/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 08:15:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/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 08:15:35 INFO - Downloaded 1199 bytes. 08:15:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:15:35 INFO - Using the following test package requirements: 08:15:35 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:15:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:15:35 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:15:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:15:35 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:15:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:15:35 INFO - u'jsshell-mac.zip'], 08:15:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:15:35 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:15:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:15:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:15:35 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:15:35 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:15:35 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:15:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:15:35 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:15:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:15:35 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:15:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:15:35 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')]} 08:15:35 INFO - https://queue.taskcluster.net/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:15:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:15:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:15:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/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 08:15:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/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 08:15:37 INFO - Downloaded 18632935 bytes. 08:15:37 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'] 08:15:37 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 08:15:40 INFO - Return code: 0 08:15:40 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 08:15:40 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')]} 08:15:40 INFO - https://queue.taskcluster.net/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:15:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:15:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:15:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/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 08:15:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/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 08:16:08 INFO - Downloaded 18632935 bytes. 08:16:08 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'] 08:16:08 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 08:16:12 INFO - Return code: 0 08:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:12 INFO - https://queue.taskcluster.net/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:16:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:16:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:16:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/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 08:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/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 08:16:14 INFO - Downloaded 10840359 bytes. 08:16:14 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'] 08:16:14 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 08:16:14 INFO - Return code: 0 08:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:14 INFO - https://queue.taskcluster.net/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:16:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:16:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:16:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:16:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WosYwMawQYS7iku-3kGVdg/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 08:16:20 INFO - Downloaded 110246141 bytes. 08:16:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:16:20 INFO - mkdir: /builds/slave/test/properties 08:16:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:16:20 INFO - Writing to file /builds/slave/test/properties/build_url 08:16:20 INFO - Contents: 08:16:20 INFO - build_url:https://queue.taskcluster.net/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:16:21 INFO - Running post-action listener: _resource_record_post_action 08:16:21 INFO - Running post-action listener: set_extra_try_arguments 08:16:21 INFO - ##### 08:16:21 INFO - ##### Finished download-and-extract step (success) 08:16:21 INFO - ##### 08:16:21 INFO - ##### 08:16:21 INFO - ##### Running populate-webroot step. 08:16:21 INFO - ##### 08:16:21 INFO - Running pre-action listener: _resource_record_pre_action 08:16:21 INFO - Running main action method: populate_webroot 08:16:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:16:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:16:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:16:21 INFO - '--tppagecycles', 08:16:21 INFO - '7'], 08:16:21 INFO - 'tests': ['tsvgm']}}, 08:16:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:16:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:16:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:16:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:16:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:16:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:16:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:21 INFO - 'talos_options': ['--disable-e10s'], 08:16:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:21 INFO - 'talos_options': ['--disable-e10s'], 08:16:21 INFO - 'tests': ['damp', 'tps']}, 08:16:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:21 INFO - 'tests': ['damp', 'tps']}, 08:16:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:16:21 INFO - 'tests': ['dromaeo_dom']}, 08:16:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:16:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:16:21 INFO - 'tests': ['a11yr', 08:16:21 INFO - 'ts_paint', 08:16:21 INFO - 'tpaint', 08:16:21 INFO - 'sessionrestore', 08:16:21 INFO - 'sessionrestore_no_auto_restore', 08:16:21 INFO - 'tabpaint']}, 08:16:21 INFO - 'other-e10s': {'tests': ['a11yr', 08:16:21 INFO - 'ts_paint', 08:16:21 INFO - 'tpaint', 08:16:21 INFO - 'sessionrestore', 08:16:21 INFO - 'sessionrestore_no_auto_restore', 08:16:21 INFO - 'tabpaint']}, 08:16:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:16:21 INFO - 'ts_paint', 08:16:21 INFO - 'tpaint', 08:16:21 INFO - 'sessionrestore', 08:16:21 INFO - 'sessionrestore_no_auto_restore', 08:16:21 INFO - 'tabpaint']}, 08:16:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:16:21 INFO - 'ts_paint', 08:16:21 INFO - 'tpaint', 08:16:21 INFO - 'sessionrestore', 08:16:21 INFO - 'sessionrestore_no_auto_restore', 08:16:21 INFO - 'tabpaint']}, 08:16:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:16:21 INFO - 'tests': ['a11yr', 08:16:21 INFO - 'ts_paint', 08:16:21 INFO - 'tpaint', 08:16:21 INFO - 'sessionrestore', 08:16:21 INFO - 'sessionrestore_no_auto_restore', 08:16:21 INFO - 'tabpaint']}, 08:16:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:16:21 INFO - 'tests': ['a11yr', 08:16:21 INFO - 'ts_paint', 08:16:21 INFO - 'tpaint', 08:16:21 INFO - 'sessionrestore', 08:16:21 INFO - 'sessionrestore_no_auto_restore', 08:16:21 INFO - 'tabpaint']}, 08:16:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:16:21 INFO - 'tests': ['tsvgx', 08:16:21 INFO - 'tsvgr_opacity', 08:16:21 INFO - 'tart', 08:16:21 INFO - 'tscrollx', 08:16:21 INFO - 'cart']}, 08:16:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:16:21 INFO - 'tsvgr_opacity', 08:16:21 INFO - 'tart', 08:16:21 INFO - 'tscrollx', 08:16:21 INFO - 'cart']}, 08:16:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:21 INFO - 'talos_options': ['--disable-e10s'], 08:16:21 INFO - 'tests': ['tp5o']}, 08:16:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:21 INFO - 'tests': ['tp5o']}, 08:16:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:21 INFO - 'talos_options': ['--disable-e10s', 08:16:21 INFO - '--xperf_path', 08:16:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:21 INFO - 'tests': ['tp5n']}, 08:16:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:21 INFO - 'talos_options': ['--xperf_path', 08:16:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:21 INFO - 'tests': ['tp5n']}}, 08:16:21 INFO - 'talos.zip': {'path': '', 08:16:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:16:21 INFO - Running post-action listener: _resource_record_post_action 08:16:21 INFO - ##### 08:16:21 INFO - ##### Finished populate-webroot step (success) 08:16:21 INFO - ##### 08:16:21 INFO - ##### 08:16:21 INFO - ##### Running create-virtualenv step. 08:16:21 INFO - ##### 08:16:21 INFO - Running pre-action listener: _resource_record_pre_action 08:16:21 INFO - Running main action method: create_virtualenv 08:16:21 INFO - Creating virtualenv /builds/slave/test/build/venv 08:16:21 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 08:16:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:16:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:16:21 INFO - Using real prefix '/tools/python27' 08:16:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:16:22 INFO - Installing distribute.............................................................................................................................................................................................done. 08:16:25 INFO - Installing pip.................done. 08:16:25 INFO - Return code: 0 08:16:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b085a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b08b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b38c80b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ad4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af20b20>, '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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 08:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kkTlUEa2rh/Render', 08:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:25 INFO - 'HOME': '/Users/cltbld', 08:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:25 INFO - 'LOGNAME': 'cltbld', 08:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:25 INFO - 'MOZ_NO_REMOTE': '1', 08:16:25 INFO - 'NO_EM_RESTART': '1', 08:16:25 INFO - 'PAGER': '/bin/cat', 08:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:25 INFO - 'PWD': '/builds/slave/test', 08:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:25 INFO - 'SHELL': '/bin/bash', 08:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sebTEOv9Tm/Listeners', 08:16:25 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 08:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:25 INFO - 'USER': 'cltbld', 08:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:25 INFO - 'XPC_FLAGS': '0x0', 08:16:25 INFO - 'XPC_SERVICE_NAME': '0', 08:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:25 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 08:16:25 INFO - Cleaning up... 08:16:25 INFO - Return code: 0 08:16:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b085a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b08b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b38c80b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ad4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af20b20>, '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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 08:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kkTlUEa2rh/Render', 08:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:25 INFO - 'HOME': '/Users/cltbld', 08:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:25 INFO - 'LOGNAME': 'cltbld', 08:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:25 INFO - 'MOZ_NO_REMOTE': '1', 08:16:25 INFO - 'NO_EM_RESTART': '1', 08:16:25 INFO - 'PAGER': '/bin/cat', 08:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:25 INFO - 'PWD': '/builds/slave/test', 08:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:25 INFO - 'SHELL': '/bin/bash', 08:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sebTEOv9Tm/Listeners', 08:16:25 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 08:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:25 INFO - 'USER': 'cltbld', 08:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:25 INFO - 'XPC_FLAGS': '0x0', 08:16:25 INFO - 'XPC_SERVICE_NAME': '0', 08:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:25 INFO - Downloading/unpacking psutil>=3.1.1 08:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:16:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:28 INFO - Installing collected packages: psutil 08:16:28 INFO - Running setup.py install for psutil 08:16:28 INFO - building 'psutil._psutil_osx' extension 08:16:28 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 08:16:29 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 08:16:29 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 08:16:29 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 08:16:29 INFO - building 'psutil._psutil_posix' extension 08:16:29 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 08:16:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:16:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:16:29 INFO - ^ 08:16:29 INFO - 1 warning generated. 08:16:29 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 08:16:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:29 INFO - Successfully installed psutil 08:16:29 INFO - Cleaning up... 08:16:29 INFO - Return code: 0 08:16:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b085a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b08b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b38c80b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ad4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af20b20>, '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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 08:16:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:16:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kkTlUEa2rh/Render', 08:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:29 INFO - 'HOME': '/Users/cltbld', 08:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:29 INFO - 'LOGNAME': 'cltbld', 08:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:29 INFO - 'MOZ_NO_REMOTE': '1', 08:16:29 INFO - 'NO_EM_RESTART': '1', 08:16:29 INFO - 'PAGER': '/bin/cat', 08:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:29 INFO - 'PWD': '/builds/slave/test', 08:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:29 INFO - 'SHELL': '/bin/bash', 08:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sebTEOv9Tm/Listeners', 08:16:29 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 08:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:29 INFO - 'USER': 'cltbld', 08:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:29 INFO - 'XPC_FLAGS': '0x0', 08:16:29 INFO - 'XPC_SERVICE_NAME': '0', 08:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:16:32 INFO - Installing collected packages: mozsystemmonitor 08:16:32 INFO - Running setup.py install for mozsystemmonitor 08:16:32 INFO - Successfully installed mozsystemmonitor 08:16:32 INFO - Cleaning up... 08:16:32 INFO - Return code: 0 08:16:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:16: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')]} 08:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16: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')]} 08:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:32 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 0x10b085a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b08b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b38c80b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ad4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af20b20>, '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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 08:16:32 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 08:16:32 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 08:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kkTlUEa2rh/Render', 08:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:32 INFO - 'HOME': '/Users/cltbld', 08:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:32 INFO - 'LOGNAME': 'cltbld', 08:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:32 INFO - 'MOZ_NO_REMOTE': '1', 08:16:32 INFO - 'NO_EM_RESTART': '1', 08:16:32 INFO - 'PAGER': '/bin/cat', 08:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:32 INFO - 'PWD': '/builds/slave/test', 08:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:32 INFO - 'SHELL': '/bin/bash', 08:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sebTEOv9Tm/Listeners', 08:16:32 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 08:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:32 INFO - 'USER': 'cltbld', 08:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:32 INFO - 'XPC_FLAGS': '0x0', 08:16:32 INFO - 'XPC_SERVICE_NAME': '0', 08:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:32 INFO - Downloading/unpacking blobuploader==1.2.4 08:16:32 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 08:16:32 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 08:16:32 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 08:16:32 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 08:16:32 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 08:16:32 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 08:16:35 INFO - Downloading blobuploader-1.2.4.tar.gz 08:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:16:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:16:35 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 08:16:35 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 08:16:35 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 08:16:35 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 08:16:35 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 08:16:35 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 08:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:16:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:36 INFO - Downloading docopt-0.6.1.tar.gz 08:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:16:36 INFO - Installing collected packages: blobuploader, requests, docopt 08:16:36 INFO - Running setup.py install for blobuploader 08:16:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:16:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:16:36 INFO - Running setup.py install for requests 08:16:37 INFO - Running setup.py install for docopt 08:16:37 INFO - Successfully installed blobuploader requests docopt 08:16:37 INFO - Cleaning up... 08:16:37 INFO - Return code: 0 08:16:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:37 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')]} 08:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:37 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')]} 08:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:37 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 0x10b085a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b08b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b38c80b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ad4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af20b20>, '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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 08:16:37 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 08:16:37 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 08:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kkTlUEa2rh/Render', 08:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:37 INFO - 'HOME': '/Users/cltbld', 08:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:37 INFO - 'LOGNAME': 'cltbld', 08:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:37 INFO - 'MOZ_NO_REMOTE': '1', 08:16:37 INFO - 'NO_EM_RESTART': '1', 08:16:37 INFO - 'PAGER': '/bin/cat', 08:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:37 INFO - 'PWD': '/builds/slave/test', 08:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:37 INFO - 'SHELL': '/bin/bash', 08:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sebTEOv9Tm/Listeners', 08:16:37 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 08:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:37 INFO - 'USER': 'cltbld', 08:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:37 INFO - 'XPC_FLAGS': '0x0', 08:16:37 INFO - 'XPC_SERVICE_NAME': '0', 08:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Exumnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-O4RlKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:37 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Ui83pX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-i6JlNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-27J1Vp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-g7atLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-HTZw1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-zEuhAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-WAZNa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-kXbPGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ngsGtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:38 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-_llfRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-J6diQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Yv0NzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-uT9o6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-lPyOzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:39 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-9esYzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:16:39 INFO - Running setup.py install for manifestparser 08:16:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:16:39 INFO - Running setup.py install for mozcrash 08:16:39 INFO - Running setup.py install for mozdebug 08:16:39 INFO - Running setup.py install for mozdevice 08:16:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:16:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:16:40 INFO - Running setup.py install for mozfile 08:16:40 INFO - Running setup.py install for mozhttpd 08:16:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:16:40 INFO - Running setup.py install for mozinfo 08:16:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:16:40 INFO - Running setup.py install for mozInstall 08:16:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:16:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:16:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:16:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:16:40 INFO - Running setup.py install for mozleak 08:16:40 INFO - Running setup.py install for mozlog 08:16:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:16:41 INFO - Running setup.py install for moznetwork 08:16:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:16:41 INFO - Running setup.py install for mozprocess 08:16:41 INFO - Running setup.py install for mozprofile 08:16:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:16:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:16:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:16:41 INFO - Running setup.py install for mozrunner 08:16:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:16:41 INFO - Running setup.py install for mozscreenshot 08:16:41 INFO - Running setup.py install for moztest 08:16:41 INFO - Running setup.py install for mozversion 08:16:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:16:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:16:42 INFO - Cleaning up... 08:16:42 INFO - Return code: 0 08:16:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:42 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 0x10b085a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b08b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b38c80b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ad4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af20b20>, '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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 08:16:42 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 08:16:42 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 08:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kkTlUEa2rh/Render', 08:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:42 INFO - 'HOME': '/Users/cltbld', 08:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:42 INFO - 'LOGNAME': 'cltbld', 08:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:42 INFO - 'MOZ_NO_REMOTE': '1', 08:16:42 INFO - 'NO_EM_RESTART': '1', 08:16:42 INFO - 'PAGER': '/bin/cat', 08:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:42 INFO - 'PWD': '/builds/slave/test', 08:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:42 INFO - 'SHELL': '/bin/bash', 08:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sebTEOv9Tm/Listeners', 08:16:42 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 08:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:42 INFO - 'USER': 'cltbld', 08:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:42 INFO - 'XPC_FLAGS': '0x0', 08:16:42 INFO - 'XPC_SERVICE_NAME': '0', 08:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-5dG4jE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16:42 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)) 08:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-BBf8T6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16:42 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)) 08:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-nZ5l4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16:42 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)) 08:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-R6GDG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16:42 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)) 08:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-esXC4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16:42 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)) 08:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-GA7CGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16:42 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)) 08:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ArlAqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:43 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)) 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-05P6Ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16:43 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)) 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-KqcAFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16:43 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)) 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-J6Q1AN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16:43 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)) 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-j8BO64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16:43 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)) 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-zRTDpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16:43 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)) 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-aZjtst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16:43 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)) 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-udT4Bl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16:43 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)) 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-gpZ1Dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:43 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)) 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-qd7Vul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16:44 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)) 08:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:44 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-PowHvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16:44 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)) 08:16:44 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)) 08:16:44 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)) 08:16:44 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)) 08:16:44 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)) 08:16:44 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)) 08:16:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:44 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 08:16:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:46 INFO - Downloading blessings-1.6.tar.gz 08:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:16:46 INFO - Installing collected packages: blessings 08:16:46 INFO - Running setup.py install for blessings 08:16:47 INFO - Successfully installed blessings 08:16:47 INFO - Cleaning up... 08:16:47 INFO - Return code: 0 08:16:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:16:47 INFO - Reading from file tmpfile_stdout 08:16:47 INFO - Current package versions: 08:16:47 INFO - blessings == 1.6 08:16:47 INFO - blobuploader == 1.2.4 08:16:47 INFO - docopt == 0.6.1 08:16:47 INFO - manifestparser == 1.1 08:16:47 INFO - mozInstall == 1.12 08:16:47 INFO - mozcrash == 0.17 08:16:47 INFO - mozdebug == 0.1 08:16:47 INFO - mozdevice == 0.48 08:16:47 INFO - mozfile == 1.2 08:16:47 INFO - mozhttpd == 0.7 08:16:47 INFO - mozinfo == 0.9 08:16:47 INFO - mozleak == 0.1 08:16:47 INFO - mozlog == 3.2 08:16:47 INFO - moznetwork == 0.27 08:16:47 INFO - mozprocess == 0.22 08:16:47 INFO - mozprofile == 0.28 08:16:47 INFO - mozrunner == 6.11 08:16:47 INFO - mozscreenshot == 0.1 08:16:47 INFO - mozsystemmonitor == 0.3 08:16:47 INFO - moztest == 0.7 08:16:47 INFO - mozversion == 1.4 08:16:47 INFO - psutil == 3.1.1 08:16:47 INFO - requests == 1.2.3 08:16:47 INFO - wsgiref == 0.1.2 08:16:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:47 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 0x10b085a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b08b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b38c80b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ad4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af20b20>, '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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 08:16:47 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 08:16:47 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 08:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kkTlUEa2rh/Render', 08:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:47 INFO - 'HOME': '/Users/cltbld', 08:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:47 INFO - 'LOGNAME': 'cltbld', 08:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:47 INFO - 'MOZ_NO_REMOTE': '1', 08:16:47 INFO - 'NO_EM_RESTART': '1', 08:16:47 INFO - 'PAGER': '/bin/cat', 08:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:47 INFO - 'PWD': '/builds/slave/test', 08:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:47 INFO - 'SHELL': '/bin/bash', 08:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sebTEOv9Tm/Listeners', 08:16:47 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 08:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:47 INFO - 'USER': 'cltbld', 08:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:47 INFO - 'XPC_FLAGS': '0x0', 08:16:47 INFO - 'XPC_SERVICE_NAME': '0', 08:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:47 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)) 08:16:47 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)) 08:16:47 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)) 08:16:47 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)) 08:16:47 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)) 08:16:47 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)) 08:16:47 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)) 08:16:47 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)) 08:16:47 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)) 08:16:47 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)) 08:16:47 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)) 08:16:47 INFO - Cleaning up... 08:16:47 INFO - Return code: 0 08:16:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:47 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 0x10b085a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b08b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b38c80b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ad4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af20b20>, '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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 08:16:47 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 08:16:47 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 08:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kkTlUEa2rh/Render', 08:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:47 INFO - 'HOME': '/Users/cltbld', 08:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:47 INFO - 'LOGNAME': 'cltbld', 08:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:47 INFO - 'MOZ_NO_REMOTE': '1', 08:16:47 INFO - 'NO_EM_RESTART': '1', 08:16:47 INFO - 'PAGER': '/bin/cat', 08:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:47 INFO - 'PWD': '/builds/slave/test', 08:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:47 INFO - 'SHELL': '/bin/bash', 08:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sebTEOv9Tm/Listeners', 08:16:47 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 08:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:47 INFO - 'USER': 'cltbld', 08:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:47 INFO - 'XPC_FLAGS': '0x0', 08:16:47 INFO - 'XPC_SERVICE_NAME': '0', 08:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:48 INFO - Downloading/unpacking jsonschema 08:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:16:50 INFO - Downloading/unpacking functools32 (from jsonschema) 08:16:50 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 08:16:50 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 08:16:50 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 08:16:50 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 08:16:50 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 08:16:50 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 08:16:50 INFO - Downloading functools32-3.2.3-2.tar.gz 08:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:16:51 INFO - warning: no files found matching '*.txt' 08:16:51 INFO - no previously-included directories found matching 'build' 08:16:51 INFO - no previously-included directories found matching 'dist' 08:16:51 INFO - no previously-included directories found matching '.git*' 08:16:51 INFO - Installing collected packages: jsonschema, functools32 08:16:51 INFO - Running setup.py install for functools32 08:16:51 INFO - warning: no files found matching '*.txt' 08:16:51 INFO - no previously-included directories found matching 'build' 08:16:51 INFO - no previously-included directories found matching 'dist' 08:16:51 INFO - no previously-included directories found matching '.git*' 08:16:51 INFO - Successfully installed jsonschema functools32 08:16:51 INFO - Cleaning up... 08:16:51 INFO - Return code: 0 08:16:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:16:51 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')]} 08:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:51 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')]} 08:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:51 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 0x10b085a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b08b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b38c80b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ad4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af20b20>, '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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 08:16:51 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 08:16:51 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 08:16:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kkTlUEa2rh/Render', 08:16:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:51 INFO - 'HOME': '/Users/cltbld', 08:16:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:51 INFO - 'LOGNAME': 'cltbld', 08:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:51 INFO - 'MOZ_NO_REMOTE': '1', 08:16:51 INFO - 'NO_EM_RESTART': '1', 08:16:51 INFO - 'PAGER': '/bin/cat', 08:16:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:51 INFO - 'PWD': '/builds/slave/test', 08:16:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:51 INFO - 'SHELL': '/bin/bash', 08:16:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sebTEOv9Tm/Listeners', 08:16:51 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 08:16:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:51 INFO - 'USER': 'cltbld', 08:16:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:51 INFO - 'XPC_FLAGS': '0x0', 08:16:51 INFO - 'XPC_SERVICE_NAME': '0', 08:16:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:51 INFO - Downloading/unpacking flake8 08:16:51 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 08:16:51 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 08:16:51 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 08:16:51 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 08:16:51 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 08:16:51 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 08:16:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:16:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:16:54 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 08:16:54 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 08:16:54 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 08:16:54 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 08:16:54 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 08:16:54 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 08:16:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:16:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:16:54 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 08:16:54 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 08:16:54 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 08:16:54 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 08:16:54 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 08:16:54 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 08:16:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:16:54 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 08:16:54 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 08:16:54 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 08:16:54 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 08:16:54 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 08:16:54 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 08:16:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:16:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:16:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:16:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:16:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:16:55 INFO - no previously-included directories found matching 'docs/_build' 08:16:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:16:55 INFO - Running setup.py install for pep8 08:16:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:16:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:16:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:16:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:16:55 INFO - no previously-included directories found matching 'docs/_build' 08:16:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:16:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:16:55 INFO - Cleaning up... 08:16:55 INFO - Return code: 0 08:16:55 INFO - Running post-action listener: _resource_record_post_action 08:16:55 INFO - Running post-action listener: _start_resource_monitoring 08:16:55 INFO - Starting resource monitoring. 08:16:55 INFO - ##### 08:16:55 INFO - ##### Finished create-virtualenv step (success) 08:16:55 INFO - ##### 08:16:55 INFO - ##### 08:16:55 INFO - ##### Running install step. 08:16:55 INFO - ##### 08:16:55 INFO - Running pre-action listener: _resource_record_pre_action 08:16:55 INFO - Running main action method: install 08:16:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:16:56 INFO - Reading from file tmpfile_stdout 08:16:56 INFO - Detecting whether we're running mozinstall >=1.0... 08:16:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:16:56 INFO - Reading from file tmpfile_stdout 08:16:56 INFO - Output received: 08:16:56 INFO - Usage: mozinstall [options] installer 08:16:56 INFO - Options: 08:16:56 INFO - -h, --help show this help message and exit 08:16:56 INFO - -d DEST, --destination=DEST 08:16:56 INFO - Directory to install application into. [default: 08:16:56 INFO - "/builds/slave/test"] 08:16:56 INFO - --app=APP Application being installed. [default: firefox] 08:16:56 INFO - mkdir: /builds/slave/test/build/application 08:16:56 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'] 08:16:56 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 08:17:29 INFO - Reading from file tmpfile_stdout 08:17:29 INFO - Output received: 08:17:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:17:29 INFO - Running post-action listener: _resource_record_post_action 08:17:29 INFO - ##### 08:17:29 INFO - ##### Finished install step (success) 08:17:29 INFO - ##### 08:17:29 INFO - ##### 08:17:29 INFO - ##### Running run-tests step. 08:17:29 INFO - ##### 08:17:29 INFO - Running pre-action listener: _resource_record_pre_action 08:17:29 INFO - Running main action method: run_tests 08:17:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:17:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:17:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:17:29 INFO - 2016-05-24 08:17:29.881 screenresolution[1944:11930] starting screenresolution argv=screenresolution get 08:17:29 INFO - 2016-05-24 08:17:29.884 screenresolution[1944:11930] Display 0: 1600x1200x32@60 08:17:29 INFO - 2016-05-24 08:17:29.890 screenresolution[1945:11932] starting screenresolution argv=screenresolution list 08:17:29 INFO - Available Modes on Display 0 08:17:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:17:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:17:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:17:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:17:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:17:30 INFO - Intel Iris: 08:17:30 INFO - Chipset Model: Intel Iris 08:17:30 INFO - Type: GPU 08:17:30 INFO - Bus: Built-In 08:17:30 INFO - VRAM (Dynamic, Max): 1536 MB 08:17:30 INFO - Vendor: Intel (0x8086) 08:17:30 INFO - Device ID: 0x0a2e 08:17:30 INFO - Revision ID: 0x0009 08:17:30 INFO - Displays: 08:17:30 INFO - Display: 08:17:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:17:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:17:30 INFO - Display Serial Number: 1600x1200 60 08:17:30 INFO - Main Display: Yes 08:17:30 INFO - Mirror: Off 08:17:30 INFO - Online: Yes 08:17:30 INFO - Rotation: Supported 08:17:30 INFO - Return code: 0 08:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:17:30 INFO - Python 2.7.3 08:17:30 INFO - Return code: 0 08:17:30 INFO - grabbing minidump binary from tooltool 08:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:17:30 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 0x10af29d30>, '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 0x10ad4ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af20b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:17:30 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 08:17:30 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 08:17:30 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 08:17:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:17:30 INFO - Return code: 0 08:17:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:17:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:17:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:17:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:17:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:17:31 INFO - Return code: 0 08:17:31 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/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 08:17:31 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/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0070 --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 08:17:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kkTlUEa2rh/Render', 08:17:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:31 INFO - 'HOME': '/Users/cltbld', 08:17:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:31 INFO - 'LOGNAME': 'cltbld', 08:17:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:17:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:31 INFO - 'MOZ_NO_REMOTE': '1', 08:17:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:17:31 INFO - 'NO_EM_RESTART': '1', 08:17:31 INFO - 'PAGER': '/bin/cat', 08:17:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:31 INFO - 'PWD': '/builds/slave/test', 08:17:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:17:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:31 INFO - 'SHELL': '/bin/bash', 08:17:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sebTEOv9Tm/Listeners', 08:17:31 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 08:17:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:31 INFO - 'USER': 'cltbld', 08:17:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:31 INFO - 'XPC_FLAGS': '0x0', 08:17:31 INFO - 'XPC_SERVICE_NAME': '0', 08:17:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:31 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/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 08:17:31 INFO - mozversion application_buildid: 20160524055509 08:17:31 INFO - mozversion application_changeset: 829d3be6ba648b838ee1953fdfa1a477dace752f 08:17:31 INFO - mozversion application_display_name: Nightly 08:17:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:17:31 INFO - mozversion application_name: Firefox 08:17:31 INFO - mozversion application_remotingname: firefox 08:17:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:17:31 INFO - mozversion application_vendor: Mozilla 08:17:31 INFO - mozversion application_version: 49.0a1 08:17:31 INFO - mozversion platform_buildid: 20160524055509 08:17:31 INFO - mozversion platform_changeset: 829d3be6ba648b838ee1953fdfa1a477dace752f 08:17:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:17:31 INFO - mozversion platform_version: 49.0a1 08:17:31 INFO - using testdate: 1464103051 08:17:31 INFO - actual date: 1464103051 08:17:31 INFO - starting webserver on 'localhost:49671' 08:17:31 INFO - SUITE-START | Running 2 tests 08:17:31 INFO - TEST-START | dromaeo_css 08:17:31 INFO - operating with platform_type : mac_ 08:17:31 INFO - Initialising browser for dromaeo_css test... 08:17:31 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmp7Lpmfg/profile http://localhost:49671/getInfo.html) 08:17:34 INFO - PROCESS | 1963 | __metrics Screen width/height:1600/1200 08:17:34 INFO - PROCESS | 1963 | colorDepth:24 08:17:34 INFO - PROCESS | 1963 | Browser inner width/height: 1024/689 08:17:34 INFO - PROCESS | 1963 | __metrics 08:17:34 ERROR - PROCESS | 1963 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 08:17:35 INFO - TEST-INFO | 1963: exit 0 08:17:35 INFO - Browser initialized. 08:17:35 INFO - Running cycle 1/1 for dromaeo_css test... 08:17:35 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmp7Lpmfg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:17:47 INFO - PROCESS | 1966 | RSS: Main: 250163200 08:17:47 INFO - PROCESS | 1966 | 08:18:36 INFO - PROCESS | 1966 | 1464103116777 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] 08:20:48 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49671/tests/dromaeo/cssquery-ext.html) 08:20:49 INFO - PROCESS | 1966 | RSS: Main: 261206016 08:20:49 INFO - PROCESS | 1966 | 08:23:51 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-ext.html (next: http://localhost:49671/tests/dromaeo/cssquery-jquery.html) 08:23:52 INFO - PROCESS | 1966 | RSS: Main: 273952768 08:23:52 INFO - PROCESS | 1966 | 08:26:54 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49671/tests/dromaeo/cssquery-mootools.html) 08:26:55 INFO - PROCESS | 1966 | RSS: Main: 276070400 08:26:55 INFO - PROCESS | 1966 | 08:29:56 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49671/tests/dromaeo/cssquery-prototype.html) 08:29:57 INFO - PROCESS | 1966 | RSS: Main: 277737472 08:29:57 INFO - PROCESS | 1966 | 08:33:00 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49671/tests/dromaeo/cssquery-yui.html) 08:33:00 INFO - PROCESS | 1966 | RSS: Main: 280592384 08:33:00 INFO - PROCESS | 1966 | 08:36:03 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-yui.html (next: http://localhost:49671/tests/dromaeo/cssquery-dojo.html) 08:36:03 INFO - PROCESS | 1966 | RSS: Main: 281661440 08:36:03 INFO - PROCESS | 1966 | 08:36:03 INFO - PROCESS | 1966 | __start_tp_report 08:36:03 INFO - PROCESS | 1966 | _x_x_mozilla_page_load 08:36:03 INFO - PROCESS | 1966 | _x_x_mozilla_page_load_details 08:36:03 INFO - PROCESS | 1966 | |i|pagename|runs| 08:36:03 INFO - PROCESS | 1966 | |0;dojo.html;3225.1614580209143;3453.1367158210446;3482.8084455354947;3486.7036301914345;3514.630963748808;4288.592583704551;5549.02891993901;5686.147077938306;5793.652380857152;5816.1275808628725;4755.8097676093;5697;5835.795747148851;5878.265216847894;5932.940670593303;5244.056069907415;5273.973630131844;5319.228711836781;5334.279872217259;5437.6465529740635;5458.908218356325;5463.836084917439;5497.615166938316;5510.752016159262;5512.586556008296;5233.712145831978;5286.3127793387;5304.336957880265;5305.575553955694;5375.16684913839;5296.947030529684;5310.0441920454305;5313.814016509404;5320.760565774511;5329.920051199235;5249.501297376782;5328.067588172094;5337.946620533784;5398.2982212312545;5416.647917885325;5662.37713851476;5689.459501347365;5751.1085781703905;5763.1355296705415;5764.481196692318;5904.498117660004;6299.559030867841;6304.464120549759;6334.778282760081;6375.71309291083;6220.440160385542;6283.057541368786;6306.842328941813;6317.273513545955;6320.336364681688;5936.821895343147;5985.7306421210715;6003.639781613074;6022.39776022403;6055.818325450244;6380.138681277968;6404.103425520431;6421.74313027483;6434.227892652818;6518.185226846645;6121.757838957688;6171.722272497748;6283.277423096311;6286.842828929313;6297.433231009231;6212.627242365382;6244.625322480666;6335.714892829834;6345.936540634627;6349.714262858182;6184.81445556634;6243.344448832807;6291.496680265568;6310.905336419865;6330.082138090043;6181.536384771161;6241.687915604201;6251.499880009588;6285.057241413824;6319.6208227506495;5037.471065538139;5071.923921141186;5086.516780905808;5096.464871188546;5140.254663073833;4478.373027776114;4514.774261286922;4541.772911354419;4544.295634176708;4588.49526552079;5771.538276937835;5884.882302353929;5932.3474417814;5937.168796368512;6031.8793624127275;6275.435210831047;6307.117003619497;6310.116583678288;6320.146780184617;6341.651209183539;5960.791372302036;5979.611325263844;5980.581359304894;5987.251593550806;6009.789657361971;5380.677159370451;5443.782248710087;5465.371482279589;5498.862528436741;5567.805126820542;6447.982391052083;6454.290028097;6456.644884531302;6480.2547707013355;6494.740210391531;5237.895242095061;6298.590591611531;6298.622082675054;6311.368863113652;6340.4293613575;6217;6400.23197216337;6433.292337842927;6484.157059582224;6534.215894092739;5358.0355536002335;6236.095766113979;6292.685365731787;6312.368763123651;6319.115323854756;6013.098035294741;6303.590266632654;6313.05304204373;6323.747050117944;6332.176817013758;5197.766100525561;6121.47967422774;6127.342242870382;6152.815415537541;6168.938310617016;5094.7962081516325;6093.664848433202;6143.911070236141;6217.595856269328;6249.562530622813;5871.970640146772;6078.483328917092;6083.756649733961;6094.20775299226;6147.969260153671;6083.6045657032155;6130.428711073822;6160.199174107337;6199.690015499297;6263.404976527223;6160;6163.445289923929;6170.228721409824;6186.752529898934;6193.690315484298 08:36:03 INFO - PROCESS | 1966 | |1;ext.html;491.19444111656884;502.15387072782363;512.3621091740783;512.4670342843443;530.5569849175938;32918.34163316735;36505.81747091264;39377;41964;42095.158096838124;37034.25931481375;42130.157396852046;43382.566174338244;43407;43454.565454345444;9507.857382139257;9665.323427360088;9677.37097088687;9754.46350450725;9830;9617.27870409722;9621.518924053804;9622.855657165148;9623.278254130933;9739.366941148837;9649.517524123801;9687.854682179774;9696.418214907128;9748.756281092994;9776.217902567812;9628.422294662309;9639.325247232697;9680.419174849496;9696.46669433181;9741.65904193357;9474.763130921714;9540.95229523848;9550.092741189575;9641.855372169422;9650.662226822027;35523.11192220215;37815.05462363463;39482.407763883566;39536;39657.40513892294;3143.387039527293;3149.826759528225;3264.4287249731437;3312.205070782995;3472.9131771705656;23656.935437905337;28962.710372896214;29482.557761633594;29900.401991960258;30258.394832103455;2746.6703995520666;2754.352727109148;2813.0998080614213;2833.7732981361646;2881.236472334833;511939;517300;517735;517998;518401.81598183536;34890.30219395598;35660.64339356625;64322.67838660777;64848.67575662091;64964;30351.696483035328;36035.459468108;36065;36238;37251.88244352619;12941.611751647466;14950.62623434401;14989.325480353411;15142.318595663222;15144.621384465256;12699.809502857464;12795.04037197214;12829.358532073546;12834.743305133845;12862.807057894139;7787.5716835574585;7852.332551733052;7962.920370796333;7972.202779721982;8023.9598802006785;3307.751918606114;3317.817520036373;3319.6680331967095;3339.248669049493;3345.5985281766343;912.32943786317;934.2992755433424;968.5835090910836;1023.4166525080633;1033.1992705653179;28690.282742931595;29005;29160.56259156114;29521.557176642367;29531.261718457212;1049.653614307292;1065.9520321585546;1090.536521978156;1100.0099910080992;1159.96520104397;15017.324220410108;15125.168115753519;15131.394744210109;15218.16300103505;15320.974871570193;421067.89466052473;513510;548140;550530;569221;47408.525914740414;57740.711296443245;58966.41033589695;59013;59476.702616487506;1382.4470211915113;1412.4632639597094;1425.6934759026858;1427.414759948437;1428.8785453236383;24465.266042018768;28691.856540717163;29028.274293141996;29118.708812912446;29417.705822941498;48932.75533622309;51859.96280074453;51927.2210916829;52009.21986170135;52084.479155209476;395497;406899;407358;407657;407951;25890.741092588833;25954;26071.73928260693;26477.205683829518;27029.45941081128;45058;45281.32078018766;45526.08947821091;46299;46698.59904202879;37498.8125059373;37626;37996.050098746644;38283.61716382801;38616.2276754469;37014;37017;37152.07119821918;37525.87422377333;37857.432138518496;32254.83872580622;34771.478427824106;37351.43972840355;37763.81118094392;38475.230495389376;33148.668513315526;33470.832645836614;34016.65983340135;34230.65769342275;34290.82854585811;23389.181378651665;24433.267001989258;25002.874985624956;25140.874295628404;25293.114740983976 08:36:03 INFO - PROCESS | 1966 | |2;jquery.html;321.86964279466815;382.82390100553727;384.3773087598091;399.5285563035617;404.03838863504853;19047.7618954768;20442.795572044313;20704.068316925735;21126.260580879672;21140.471488212785;20393.796062039375;20736.274230401938;21004.26485073023;21005.684914726255;21464.141434342648;5773.797917072914;5806.564507661921;6057.818265452043;6626.801195964129;6627.304133066031;5942.792002279921;6584.374484423996;6647.966760166217;6691.096701945224;6715.194176698803;5605.27131472908;6538.5749926254875;6555.672216389185;6565.868682626368;6582.078509008743;6229.626222426669;6504.642244676541;6537.346265373472;6556.704948277315;6557.967210163943;5646.717664116819;5655.491005809456;5658.151277308396;5665.886682266373;5692.886142277172;25625.487490250092;26298.21105366842;26743.598846017132;26746.732532674618;26803.463930721275;2076.9480762981048;2147.4631342164457;2294.908203671851;2382.707440980174;2450.9509809803703;14375.712485750226;15077.849221507755;15159.621009474851;15230.771538426932;15272.236388180658;1671.1727694791157;1705.8038325592593;1710.503953853381;1725.870559708027;1756.6311074674206;117048.41475792651;123295;124595.75404246023;125573.11640325311;125649.74350256471;10655.200859935536;10821.513031913582;11038.006579407891;11122.888771112186;11276.492557834918;13368.59894203156;13394.598162055154;13791.51729689476;13809.654758631115;13846.861531384557;8669.739907802776;8823.647054117891;8849.026607073216;8964.372493925363;8968.775780605538;7731.806704832424;7835.764927052198;8455.323574114056;8911.37620366568;8935.240504557185;6622.106015687917;6629.3370662934285;6665.866682666319;6718.563293386012;6733.114727876105;1822;1858.0988220712943;1858.8884666920028;1861.3485280151838;1864.2449807827852;925.3938670171049;965.9806803864024;970.2189737261572;1008.1330056151704;1029.4080903480528;20209.696854547194;20852;20968.685469717966;21214.78785212128;21368.622984233545;883.7392969074139;969.4280374579032;981.6122631560636;1029.2280789407944;1060.1148041385375;8632.870506942401;11368.658940231806;11378.975892169745;11384.943075284571;11415.942920285512;516506.4174679103;596932;606051;611843;613149;94883.52558237164;118057;121038;121923.34229973116;122196;989.7179303898355;992.9078014184483;994.3039872089566;996.0437979539723;1031.1338475680463;18357.908210458863;18480.81519184791;18497.81502184961;18604.7209291858;18800.811991880455;46111.84720382017;47230.58308250758;48515;49657.75171124121;51533.48466515287;52661.47338526566;65724.67137664281;70602.64698676679;71346.64326678333;72923.90614140686;7384.261573842573;7415.406767458699;7502.474826762293;7609.391248700202;7817.6872925084635;9111.498867562348;11585.6524304271;11933.4033298333;11982.921537061706;11995.820062698893;8419.83160336802;9991.050850169222;10200;10238.078572928473;10255.179585633281;9921.107100361001;10036.64871729486;10277.794444111225;10283.280170388001;10361.792764144524;9743.366681165702;10344.431056291674;10372.481375931022;10581.841272380767;10660.520276587727;7452.925470745223;7488.588127653032;7491.737789177353;7553.169151393452;7771.33943614799;10004.449755263531;10180.236482263712;10332.896671033193;10366.118879895294;10414.791704166026 08:36:03 INFO - PROCESS | 1966 | |3;mootools.html;1380.4133243371568;1544.7065057639052;1751.5621094726318;1900.0594705620729;1938.234397413021;8039.397045221617;8586.570671466432;8698.304135669146;9059.728208153758;9072.41029333093;8801.251893589037;8850.601722922467;8939.150780675842;8941.463512189273;8953.865692014626;2369.170790223421;2407.9157229496973;2412.0713525292713;2412.348665860217;2412.6139817629182;2374.157174203159;2388.8566685998894;2406.639004149383;2415.7946574541184;2419.250032489925;2368.171140100968;2388.9283321500384;2394.820388470863;2399.5081008393327;2400.7719266669637;1968.6358023765506;2037.5415531505441;2308.35366097493;2362.492064206195;2364.574376612209;2259.5254996450767;2259.53679495703;2261.050358849287;2265.1392470861133;2273.943151421211;8748.518831464267;8838.204561589488;8877.51173685447;8900.154485323885;8907.242884354837;5224.712640804754;5343.679379237219;5395.946040539623;5467.07059799831;5513.972430137864;3441.0537102296817;3538.0800991742076;3606.9098272543133;3607.9098022549383;3629.3285742137523;999.8300288950823;1035.663409391943;1037.723599972031;1049.1921220660124;1059.5814653211937;11649;11712.121590880633;11815.763684726344;11819.704507387383;11865.822012669816;4828.348172996671;4829.396325459317;4932.4081110267;4944.55499005084;4944.678595891256;9948.950255248677;10022.599096036078;10088.646897358558;10093.747656308651;10132.69601911929;5340.946590534045;5353.946460535422;5371.973140134352;5448.482394172547;5467.781288748485;4393.747781882148;4441.888952776142;4763.142634325785;4814.831480898151;4898.608111351084;3994.7403418777685;4060.7766572838186;4067.349224124126;4117.279476091732;4161.687873409506;1122.5173175534599;1303.0748168800258;1303.7653222419865;1307.1046333261745;1319.9538016169388;854.7093988043971;917.5733284022962;930.8464103422859;959.7072892767633;961.9086186812382;5921.259842519685;6029.914615369275;6038.396160383926;6053.273607167168;6232.189815323979;981.79873126009;1000.214831357381;1003.1523362758603;1052.2686732720838;1067.6103222323952;3231.660675629072;3434.8797792077658;4054.5945405459806;4057.5130984282073;4121.361189015707;10490.370577765358;10589.417582033062;10688;10816.188785841094;10859.239853210358;10634.840477392845;10714.142868570496;10724.981126792942;10729.78540429187;10731.08785753204;8160.7143749969655;8173.468724533005;8196.83606327882;8329.416940814084;8346.040205376337;4892.168331383602;5883.264591926009;5885.499732522783;5898.4101589840675;5958.1658567801405;9670.936197018464;9875.506224688881;9902.653407130716;10052.849207262043;10089.142422894136;10146.695599132037;10183.236257280882;10292.485375731334;10304.381737095799;10319;7044.964775176296;7058.71765129389;7331.486795924234;7611.543307401574;7672.424568157299;8360.372972027;8406.453580517247;8454.36592255571;8507.574621268788;8513.787155321166;7251.166115896739;7300.96349518249;7454.44091693136;7530.510516816389;7651.3496352810635;7296.4162866969455;7387.482876198829;7417.998570193171;7433.888491672521;7536;7042.859142817012;7077.504574679723;7078.610676412847;7112.928870711434;7181.640917954186;4574.085182963354;4852.272159176152;4880.5607495324175;5037.319961805109;5205.245239440184;4913.680610760289;4962.851114466572;5000.974995125001;5070.670406423571;5275.788968441219 08:36:03 INFO - PROCESS | 1966 | |4;prototype.html;278.14470503202654;300.44417827020004;316.92235402326435;319.4824384497115;321.53217069164367;4591.4260717410325;4684.789184486698;5430.647007944491;5613.2141500189955;5638.718064096789;4723.409573803275;5503.532199763015;5514.393416724157;5523.171524271362;5569.526590239824;3730.701543876497;3904.2777086238993;4221.82575973374;4223.873283801483;4228.429162063128;3620.8051343056727;3894.4742459767995;3899.961000389988;4217.493900731916;4287.992321804373;3272.7381809455187;3767.907306881001;4114.444549985744;4145.3781932710035;4159.750414975111;3360.815155166465;3832.597577254404;4129.669626429878;4138.91722165555;4173.624373806341;3064.3952640255825;3084.6144231971;3440.5205761522534;3587.7847329160336;3626.311000909845;4998.900021999576;5254;5266.052110620087;5271.446498117719;5409.188621706736;4575;4670.789814458358;4720.952790472085;4773.4510531289;4775.824076988798;4951.678140920829;5354.651947623431;5566.610337276392;5763.711814409263;5781.797637082682;3226.757993150523;3921.784301863396;3940.5862384449792;3944.250592387466;3957.5448823385104;4925.088858561142;5387.326584176939;5619.7471113799975;5883.793822266468;6054.8183554493435;3650.580183278904;3747.063234191452;3988.441618173458;4018.799060046986;4039.4448137466698;3943.5662077171487;4035.7174997750462;4047.2310261050893;4080.5511393746665;4118.299889018814;4048.757074575476;4703.741294228782;4733.366988389001;4774.641901857374;4790.7844147012775;4960.677555958851;5183.792648294026;5382.3541175059245;5679.375268720438;5951.809638072403;1149.8447709559102;1312.967175820593;1362.8773410393112;1372.739179555901;1398.496616137656;810.5744484145753;827.2389401750404;838.9874151887726;845.9045536030732;850.1339559666472;3706.7405281630026;4366.759828209415;4511.932321015187;4642.257238841837;4666.899895047242;4956.058348913695;5115.38615366151;5149.433562308143;5163.432022477525;5209.739513024334;4380.03639199382;4384.4081049058605;4435.600795928317;4468.419105516262;4626.028534007896;2839.367363765843;2900.5069138246126;3079.4286770546846;3211.951820722691;3238.8542515586855;5522.30971128609;6329.563118199434;6444.2589102252905;6477.5465717400275;6480.870382592416;5539.944600554023;5955.962459145867;6250.249970003629;6255.12428260044;6312.589681670676;6343.904841427382;6353.396427339396;6378.553501254868;6386.808395748135;6406.135171751754;5618.747156378055;5701.658987997594;5714.571407144397;6016.278046634432;6032.90950635732;5387.949349876736;5763.4812866842185;5984.58107932458;5997.100434934795;6024.939750602438;6328.272248691459;6332.873342533216;6377.840553986002;6377.840553986188;6447.83880402975;3444.0283799763897;3485;3546.5212196353164;3687.967369136646;3874.9806250968563;5273.630845840754;5753.90675771602;5966.284045914518;5968.791092311748;5987.64074155552;5566.526845218202;5896.380880007538;5899.616524925866;5902.852428689317;5931.51712071982;5326.8014696692935;5467.7266136692215;5820.243368362086;5883.411658834082;6106.969465152823;4813.085513752376;5090.974545127251;5501.19439086061;5689.487946084872;5691.430856914276;4982.900341993068;5245.983559453253;5635.549156067586;5836.241288632451;5861.882762344814;5253.947460525499;5365.919511207257;5570.6657600544095;5694.202811606296;5801.883962320814 08:36:03 INFO - PROCESS | 1966 | |5;yui.html;659;720.3552820225898;741.2661465149504;742.35786045071;744.6462930108199;13399.7320053599;14946.626334341647;14977;15167.544973650809;15273.159976201305;13704.972127090457;14036.929815350935;14043.22762248076;14076.085054471452;14101.788473172885;714.6605362452846;874.3049275825703;888.080836334394;895.3687650206622;910.0308171797014;880.2869675562782;894.1862904756673;894.4141587260323;895.4716717136888;896.2337201692557;951.9666811661593;951.9857202141939;952.9190018848407;953.142172045158;956.3305686019814;942.3497786527274;944.4852555357312;948.0756262643868;951.5289931483891;951.5337484632549;673.5217995223397;699.4544255480733;700.4501466348943;700.502643123383;703.0157779109237;11252.38111903845;11500.482478288497;11501.30992140466;13639.181649100987;13641.590752277447;12204.267743935392;12362.629121126382;12500.437480313407;12542.561010364685;12548.1216314858;9575;9698.660546880896;9950.154236889874;10005.59977600895;10431.113355364803;3980.24375368679;3990.960090399117;3994.960050399488;4018.3168861293652;4135.834566617332;75214.62392688057;76407.61796190984;76706;77957.61021194914;80569.19430805676;9045.819083618291;9187.127222913812;9196.908030919605;9310.487923164155;9485.288603354777;20270.18919243214;20327.695084573745;20333.89833050825;20357;20821.791782082288;7456.47804653669;7530.811729706692;7612.695492180251;7671.6164191790185;7720.3051725344985;3267.8702364202018;3822.8853134406013;3842.50047493824;3874.1476875087437;3910.7066969977363;3462.2556150427276;3549.8580056797955;3552.3960926642535;3554.0581745837376;3563.982180089083;535.8178219405421;652.4519403700914;672.1799404726227;675.152683382352;698.4796326736512;526.2711145064037;563.8111232736996;566.9171881705922;596.6599038548072;600.3516202501288;8264.876026859602;8275.710350137837;8367.707130250412;8402.537860417613;8516.914830851736;565.8726786473093;582.1762206477871;591.2373038780012;595.1905408644327;601.0488501457821;3931.233409485123;3942.605739426092;4152.231837110084;4274.615284624399;4340.565943405697;87179.12820871836;94662;96752;100925.49537252267;100981.49509252407;65868.01197982034;84577;86065;87427;88181;61869.381306187264;63535.36464635387;67084.6645766768;78119.21880781233;80371.59814200892;8245.175482451707;8268.875966860622;8302.70940517079;8318.625661845352;8344.41589088758;35094.12264693403;42369;44195.337069943336;44274.55725442704;44414.667559973255;60331.69834150801;62783.6860815693;63044.739105218556;63238;64483.710325794156;9678.854817177607;10646.73383165427;10864.782704346027;11008.119350451785;11142.27575207609;11633.243839150766;11806.586769463027;11969.820452693391;12138.453769580214;12301.938490307492;10758.363347933146;11084.556617735201;11201.327920324806;11224.831627525757;11411;9301.953490232505;11315.490802913724;11328.773424531299;11639.76720465603;11693.854937366717;10196.796064078826;10212.591496340063;10288.742781430225;10293.794124117625;10755.784884302426;8953.59708813118;9142.77143071402;9196.908030919605;9668.661596844076;9838.163756080814;7871.134175240604;8513.680379541182;9321.254299656146;9435.103665151799;9456.33805633599 08:36:03 INFO - PROCESS | 1966 | __end_tp_report 08:36:03 INFO - PROCESS | 1966 | __start_cc_report 08:36:03 INFO - PROCESS | 1966 | _x_x_mozilla_cycle_collect,442 08:36:03 INFO - PROCESS | 1966 | __end_cc_report 08:36:03 INFO - PROCESS | 1966 | __startTimestamp1464104163396__endTimestamp 08:36:03 INFO - PROCESS | 1966 | 08:36:03 INFO - PROCESS | 1966 | ------- Summary: start ------- 08:36:03 INFO - PROCESS | 1966 | Number of tests: 6 08:36:03 INFO - PROCESS | 1966 | 08:36:03 INFO - PROCESS | 1966 | [#0] dojo.html Cycles:165 Average:5816.62 Median:6078.48 stddev:652.60 (10.7%) stddev-sans-first:622.13 08:36:03 INFO - PROCESS | 1966 | Values: 3225.2 3453.1 3482.8 3486.7 3514.6 4288.6 5549.0 5686.1 5793.7 5816.1 4755.8 5697.0 5835.8 5878.3 5932.9 5244.1 5274.0 5319.2 5334.3 5437.6 5458.9 5463.8 5497.6 5510.8 5512.6 5233.7 5286.3 5304.3 5305.6 5375.2 5296.9 5310.0 5313.8 5320.8 5329.9 5249.5 5328.1 5337.9 5398.3 5416.6 5662.4 5689.5 5751.1 5763.1 5764.5 5904.5 6299.6 6304.5 6334.8 6375.7 6220.4 6283.1 6306.8 6317.3 6320.3 5936.8 5985.7 6003.6 6022.4 6055.8 6380.1 6404.1 6421.7 6434.2 6518.2 6121.8 6171.7 6283.3 6286.8 6297.4 6212.6 6244.6 6335.7 6345.9 6349.7 6184.8 6243.3 6291.5 6310.9 6330.1 6181.5 6241.7 6251.5 6285.1 6319.6 5037.5 5071.9 5086.5 5096.5 5140.3 4478.4 4514.8 4541.8 4544.3 4588.5 5771.5 5884.9 5932.3 5937.2 6031.9 6275.4 6307.1 6310.1 6320.1 6341.7 5960.8 5979.6 5980.6 5987.3 6009.8 5380.7 5443.8 5465.4 5498.9 5567.8 6448.0 6454.3 6456.6 6480.3 6494.7 5237.9 6298.6 6298.6 6311.4 6340.4 6217.0 6400.2 6433.3 6484.2 6534.2 5358.0 6236.1 6292.7 6312.4 6319.1 6013.1 6303.6 6313.1 6323.7 6332.2 5197.8 6121.5 6127.3 6152.8 6168.9 5094.8 6093.7 6143.9 6217.6 6249.6 5872.0 6078.5 6083.8 6094.2 6148.0 6083.6 6130.4 6160.2 6199.7 6263.4 6160.0 6163.4 6170.2 6186.8 6193.7 08:36:03 INFO - PROCESS | 1966 | 08:36:03 INFO - PROCESS | 1966 | [#1] ext.html Cycles:180 Average:61029.80 Median:37575.94 stddev:129228.04 (343.9%) stddev-sans-first:129510.62 08:36:03 INFO - PROCESS | 1966 | Values: 491.2 502.2 512.4 512.5 530.6 32918.3 36505.8 39377.0 41964.0 42095.2 37034.3 42130.2 43382.6 43407.0 43454.6 9507.9 9665.3 9677.4 9754.5 9830.0 9617.3 9621.5 9622.9 9623.3 9739.4 9649.5 9687.9 9696.4 9748.8 9776.2 9628.4 9639.3 9680.4 9696.5 9741.7 9474.8 9541.0 9550.1 9641.9 9650.7 35523.1 37815.1 39482.4 39536.0 39657.4 3143.4 3149.8 3264.4 3312.2 3472.9 23656.9 28962.7 29482.6 29900.4 30258.4 2746.7 2754.4 2813.1 2833.8 2881.2 511939.0 517300.0 517735.0 517998.0 518401.8 34890.3 35660.6 64322.7 64848.7 64964.0 30351.7 36035.5 36065.0 36238.0 37251.9 12941.6 14950.6 14989.3 15142.3 15144.6 12699.8 12795.0 12829.4 12834.7 12862.8 7787.6 7852.3 7962.9 7972.2 8024.0 3307.8 3317.8 3319.7 3339.2 3345.6 912.3 934.3 968.6 1023.4 1033.2 28690.3 29005.0 29160.6 29521.6 29531.3 1049.7 1066.0 1090.5 1100.0 1160.0 15017.3 15125.2 15131.4 15218.2 15321.0 421067.9 513510.0 548140.0 550530.0 569221.0 47408.5 57740.7 58966.4 59013.0 59476.7 1382.4 1412.5 1425.7 1427.4 1428.9 24465.3 28691.9 29028.3 29118.7 29417.7 48932.8 51860.0 51927.2 52009.2 52084.5 395497.0 406899.0 407358.0 407657.0 407951.0 25890.7 25954.0 26071.7 26477.2 27029.5 45058.0 45281.3 45526.1 46299.0 46698.6 37498.8 37626.0 37996.1 38283.6 38616.2 37014.0 37017.0 37152.1 37525.9 37857.4 32254.8 34771.5 37351.4 37763.8 38475.2 33148.7 33470.8 34016.7 34230.7 34290.8 23389.2 24433.3 25002.9 25140.9 25293.1 08:36:03 INFO - PROCESS | 1966 | 08:36:03 INFO - PROCESS | 1966 | [#2] jquery.html Cycles:180 Average:34225.79 Median:26745.17 stddev:98247.16 (367.3%) stddev-sans-first:98489.79 08:36:03 INFO - PROCESS | 1966 | Values: 321.9 382.8 384.4 399.5 404.0 19047.8 20442.8 20704.1 21126.3 21140.5 20393.8 20736.3 21004.3 21005.7 21464.1 5773.8 5806.6 6057.8 6626.8 6627.3 5942.8 6584.4 6648.0 6691.1 6715.2 5605.3 6538.6 6555.7 6565.9 6582.1 6229.6 6504.6 6537.3 6556.7 6558.0 5646.7 5655.5 5658.2 5665.9 5692.9 25625.5 26298.2 26743.6 26746.7 26803.5 2076.9 2147.5 2294.9 2382.7 2451.0 14375.7 15077.8 15159.6 15230.8 15272.2 1671.2 1705.8 1710.5 1725.9 1756.6 117048.4 123295.0 124595.8 125573.1 125649.7 10655.2 10821.5 11038.0 11122.9 11276.5 13368.6 13394.6 13791.5 13809.7 13846.9 8669.7 8823.6 8849.0 8964.4 8968.8 7731.8 7835.8 8455.3 8911.4 8935.2 6622.1 6629.3 6665.9 6718.6 6733.1 1822.0 1858.1 1858.9 1861.3 1864.2 925.4 966.0 970.2 1008.1 1029.4 20209.7 20852.0 20968.7 21214.8 21368.6 883.7 969.4 981.6 1029.2 1060.1 8632.9 11368.7 11379.0 11384.9 11415.9 516506.4 596932.0 606051.0 611843.0 613149.0 94883.5 118057.0 121038.0 121923.3 122196.0 989.7 992.9 994.3 996.0 1031.1 18357.9 18480.8 18497.8 18604.7 18800.8 46111.8 47230.6 48515.0 49657.8 51533.5 52661.5 65724.7 70602.6 71346.6 72923.9 7384.3 7415.4 7502.5 7609.4 7817.7 9111.5 11585.7 11933.4 11982.9 11995.8 8419.8 9991.1 10200.0 10238.1 10255.2 9921.1 10036.6 10277.8 10283.3 10361.8 9743.4 10344.4 10372.5 10581.8 10660.5 7452.9 7488.6 7491.7 7553.2 7771.3 10004.4 10180.2 10332.9 10366.1 10414.8 08:36:03 INFO - PROCESS | 1966 | 08:36:03 INFO - PROCESS | 1966 | [#3] mootools.html Cycles:180 Average:5747.93 Median:4840.83 stddev:3221.41 (66.5%) stddev-sans-first:3213.72 08:36:03 INFO - PROCESS | 1966 | Values: 1380.4 1544.7 1751.6 1900.1 1938.2 8039.4 8586.6 8698.3 9059.7 9072.4 8801.3 8850.6 8939.2 8941.5 8953.9 2369.2 2407.9 2412.1 2412.3 2412.6 2374.2 2388.9 2406.6 2415.8 2419.3 2368.2 2388.9 2394.8 2399.5 2400.8 1968.6 2037.5 2308.4 2362.5 2364.6 2259.5 2259.5 2261.1 2265.1 2273.9 8748.5 8838.2 8877.5 8900.2 8907.2 5224.7 5343.7 5395.9 5467.1 5514.0 3441.1 3538.1 3606.9 3607.9 3629.3 999.8 1035.7 1037.7 1049.2 1059.6 11649.0 11712.1 11815.8 11819.7 11865.8 4828.3 4829.4 4932.4 4944.6 4944.7 9949.0 10022.6 10088.6 10093.7 10132.7 5340.9 5353.9 5372.0 5448.5 5467.8 4393.7 4441.9 4763.1 4814.8 4898.6 3994.7 4060.8 4067.3 4117.3 4161.7 1122.5 1303.1 1303.8 1307.1 1320.0 854.7 917.6 930.8 959.7 961.9 5921.3 6029.9 6038.4 6053.3 6232.2 981.8 1000.2 1003.2 1052.3 1067.6 3231.7 3434.9 4054.6 4057.5 4121.4 10490.4 10589.4 10688.0 10816.2 10859.2 10634.8 10714.1 10725.0 10729.8 10731.1 8160.7 8173.5 8196.8 8329.4 8346.0 4892.2 5883.3 5885.5 5898.4 5958.2 9670.9 9875.5 9902.7 10052.8 10089.1 10146.7 10183.2 10292.5 10304.4 10319.0 7045.0 7058.7 7331.5 7611.5 7672.4 8360.4 8406.5 8454.4 8507.6 8513.8 7251.2 7301.0 7454.4 7530.5 7651.3 7296.4 7387.5 7418.0 7433.9 7536.0 7042.9 7077.5 7078.6 7112.9 7181.6 4574.1 4852.3 4880.6 5037.3 5205.2 4913.7 4962.9 5001.0 5070.7 5275.8 08:36:03 INFO - PROCESS | 1966 | 08:36:03 INFO - PROCESS | 1966 | [#4] prototype.html Cycles:180 Average:4614.04 Median:5103.18 stddev:1479.42 (29.0%) stddev-sans-first:1447.33 08:36:03 INFO - PROCESS | 1966 | Values: 278.1 300.4 316.9 319.5 321.5 4591.4 4684.8 5430.6 5613.2 5638.7 4723.4 5503.5 5514.4 5523.2 5569.5 3730.7 3904.3 4221.8 4223.9 4228.4 3620.8 3894.5 3900.0 4217.5 4288.0 3272.7 3767.9 4114.4 4145.4 4159.8 3360.8 3832.6 4129.7 4138.9 4173.6 3064.4 3084.6 3440.5 3587.8 3626.3 4998.9 5254.0 5266.1 5271.4 5409.2 4575.0 4670.8 4721.0 4773.5 4775.8 4951.7 5354.7 5566.6 5763.7 5781.8 3226.8 3921.8 3940.6 3944.3 3957.5 4925.1 5387.3 5619.7 5883.8 6054.8 3650.6 3747.1 3988.4 4018.8 4039.4 3943.6 4035.7 4047.2 4080.6 4118.3 4048.8 4703.7 4733.4 4774.6 4790.8 4960.7 5183.8 5382.4 5679.4 5951.8 1149.8 1313.0 1362.9 1372.7 1398.5 810.6 827.2 839.0 845.9 850.1 3706.7 4366.8 4511.9 4642.3 4666.9 4956.1 5115.4 5149.4 5163.4 5209.7 4380.0 4384.4 4435.6 4468.4 4626.0 2839.4 2900.5 3079.4 3212.0 3238.9 5522.3 6329.6 6444.3 6477.5 6480.9 5539.9 5956.0 6250.2 6255.1 6312.6 6343.9 6353.4 6378.6 6386.8 6406.1 5618.7 5701.7 5714.6 6016.3 6032.9 5387.9 5763.5 5984.6 5997.1 6024.9 6328.3 6332.9 6377.8 6377.8 6447.8 3444.0 3485.0 3546.5 3688.0 3875.0 5273.6 5753.9 5966.3 5968.8 5987.6 5566.5 5896.4 5899.6 5902.9 5931.5 5326.8 5467.7 5820.2 5883.4 6107.0 4813.1 5091.0 5501.2 5689.5 5691.4 4982.9 5246.0 5635.5 5836.2 5861.9 5253.9 5365.9 5570.7 5694.2 5801.9 08:36:03 INFO - PROCESS | 1966 | 08:36:03 INFO - PROCESS | 1966 | [#5] yui.html Cycles:180 Average:17890.07 Median:62914.21 stddev:25711.51 (40.9%) stddev-sans-first:25751.08 08:36:03 INFO - PROCESS | 1966 | Values: 659.0 720.4 741.3 742.4 744.6 13399.7 14946.6 14977.0 15167.5 15273.2 13705.0 14036.9 14043.2 14076.1 14101.8 714.7 874.3 888.1 895.4 910.0 880.3 894.2 894.4 895.5 896.2 952.0 952.0 952.9 953.1 956.3 942.3 944.5 948.1 951.5 951.5 673.5 699.5 700.5 700.5 703.0 11252.4 11500.5 11501.3 13639.2 13641.6 12204.3 12362.6 12500.4 12542.6 12548.1 9575.0 9698.7 9950.2 10005.6 10431.1 3980.2 3991.0 3995.0 4018.3 4135.8 75214.6 76407.6 76706.0 77957.6 80569.2 9045.8 9187.1 9196.9 9310.5 9485.3 20270.2 20327.7 20333.9 20357.0 20821.8 7456.5 7530.8 7612.7 7671.6 7720.3 3267.9 3822.9 3842.5 3874.1 3910.7 3462.3 3549.9 3552.4 3554.1 3564.0 535.8 652.5 672.2 675.2 698.5 526.3 563.8 566.9 596.7 600.4 8264.9 8275.7 8367.7 8402.5 8516.9 565.9 582.2 591.2 595.2 601.0 3931.2 3942.6 4152.2 4274.6 4340.6 87179.1 94662.0 96752.0 100925.5 100981.5 65868.0 84577.0 86065.0 87427.0 88181.0 61869.4 63535.4 67084.7 78119.2 80371.6 8245.2 8268.9 8302.7 8318.6 8344.4 35094.1 42369.0 44195.3 44274.6 44414.7 60331.7 62783.7 63044.7 63238.0 64483.7 9678.9 10646.7 10864.8 11008.1 11142.3 11633.2 11806.6 11969.8 12138.5 12301.9 10758.4 11084.6 11201.3 11224.8 11411.0 9302.0 11315.5 11328.8 11639.8 11693.9 10196.8 10212.6 10288.7 10293.8 10755.8 8953.6 9142.8 9196.9 9668.7 9838.2 7871.1 8513.7 9321.3 9435.1 9456.3 08:36:03 INFO - PROCESS | 1966 | -------- Summary: end -------- 08:36:03 INFO - PROCESS | 1966 | 08:36:03 INFO - TEST-INFO | 1966: exit 0 08:36:03 INFO - TEST-OK | dromaeo_css | took 1112351ms 08:36:03 INFO - TEST-START | kraken 08:36:03 INFO - operating with platform_type : mac_ 08:36:03 INFO - Initialising browser for kraken test... 08:36:03 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpeQhrH1/profile http://localhost:49671/getInfo.html) 08:36:07 INFO - PROCESS | 1971 | __metrics Screen width/height:1600/1200 08:36:07 INFO - PROCESS | 1971 | colorDepth:24 08:36:07 INFO - PROCESS | 1971 | Browser inner width/height: 1024/689 08:36:07 INFO - PROCESS | 1971 | __metrics 08:36:07 ERROR - PROCESS | 1971 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 08:36:07 INFO - TEST-INFO | 1971: exit 0 08:36:07 INFO - Browser initialized. 08:36:07 INFO - Running cycle 1/1 for kraken test... 08:36:07 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpeQhrH1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:36:19 INFO - PROCESS | 1974 | RSS: Main: 250216448 08:36:19 INFO - PROCESS | 1974 | 08:36:22 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49671/tests/kraken/driver.html?testName=audio-beat-detection) 08:36:23 INFO - PROCESS | 1974 | RSS: Main: 262033408 08:36:23 INFO - PROCESS | 1974 | 08:36:26 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49671/tests/kraken/driver.html?testName=audio-dft) 08:36:27 INFO - PROCESS | 1974 | RSS: Main: 266870784 08:36:27 INFO - PROCESS | 1974 | 08:36:30 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49671/tests/kraken/driver.html?testName=audio-fft) 08:36:31 INFO - PROCESS | 1974 | RSS: Main: 267739136 08:36:31 INFO - PROCESS | 1974 | 08:36:33 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49671/tests/kraken/driver.html?testName=audio-oscillator) 08:36:33 INFO - PROCESS | 1974 | RSS: Main: 267804672 08:36:33 INFO - PROCESS | 1974 | 08:36:35 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49671/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:36:36 INFO - PROCESS | 1974 | RSS: Main: 268378112 08:36:36 INFO - PROCESS | 1974 | 08:36:39 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49671/tests/kraken/driver.html?testName=imaging-darkroom) 08:36:40 INFO - PROCESS | 1974 | RSS: Main: 268861440 08:36:40 INFO - PROCESS | 1974 | 08:36:43 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49671/tests/kraken/driver.html?testName=imaging-desaturate) 08:36:43 INFO - PROCESS | 1974 | RSS: Main: 269053952 08:36:43 INFO - PROCESS | 1974 | 08:36:46 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49671/tests/kraken/driver.html?testName=json-parse-financial) 08:36:47 INFO - PROCESS | 1974 | RSS: Main: 268926976 08:36:47 INFO - PROCESS | 1974 | 08:36:48 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49671/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:36:49 INFO - PROCESS | 1974 | RSS: Main: 268775424 08:36:49 INFO - PROCESS | 1974 | 08:36:51 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:36:52 INFO - PROCESS | 1974 | RSS: Main: 269004800 08:36:52 INFO - PROCESS | 1974 | 08:36:54 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:36:55 INFO - PROCESS | 1974 | RSS: Main: 269701120 08:36:55 INFO - PROCESS | 1974 | 08:36:57 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:36:58 INFO - PROCESS | 1974 | RSS: Main: 269017088 08:36:58 INFO - PROCESS | 1974 | 08:37:01 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:37:02 INFO - PROCESS | 1974 | RSS: Main: 268988416 08:37:02 INFO - PROCESS | 1974 | 08:37:03 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49671/tests/kraken/driver.html?testName=ai-astar) 08:37:04 INFO - PROCESS | 1974 | RSS: Main: 270651392 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | __start_tp_report 08:37:04 INFO - PROCESS | 1974 | _x_x_mozilla_page_load 08:37:04 INFO - PROCESS | 1974 | _x_x_mozilla_page_load_details 08:37:04 INFO - PROCESS | 1974 | |i|pagename|runs| 08:37:04 INFO - PROCESS | 1974 | |0;ai-astar;119;111;117;115;164;94;170;168;167;115 08:37:04 INFO - PROCESS | 1974 | |1;audio-beat-detection;153;124;147;165;143;154;171;152;177;127 08:37:04 INFO - PROCESS | 1974 | |2;audio-dft;202;212;272;269;227;209;243;204;209;271 08:37:04 INFO - PROCESS | 1974 | |3;audio-fft;68;82;75;140;58;59;57;61;130;58 08:37:04 INFO - PROCESS | 1974 | |4;audio-oscillator;72;88;121;70;73;77;111;71;75;112 08:37:04 INFO - PROCESS | 1974 | |5;imaging-gaussian-blur;118;73;124;74;96;131;117;116;90;73 08:37:04 INFO - PROCESS | 1974 | |6;imaging-darkroom;92;132;152;104;150;122;95;95;104;94 08:37:04 INFO - PROCESS | 1974 | |7;imaging-desaturate;115;105;90;92;90;91;93;91;85;145 08:37:04 INFO - PROCESS | 1974 | |8;json-parse-financial;58;62;60;57;59;59;61;76;62;60 08:37:04 INFO - PROCESS | 1974 | |9;json-stringify-tinderbox;69;67;68;76;77;76;73;69;69;71 08:37:04 INFO - PROCESS | 1974 | |10;stanford-crypto-aes;105;108;96;86;87;86;88;88;89;90 08:37:04 INFO - PROCESS | 1974 | |11;stanford-crypto-ccm;143;126;149;158;157;142;154;154;199;180 08:37:04 INFO - PROCESS | 1974 | |12;stanford-crypto-pbkdf2;146;150;200;174;204;235;169;167;168;178 08:37:04 INFO - PROCESS | 1974 | |13;stanford-crypto-sha256-iterative;68;65;70;64;75;62;62;90;88;85 08:37:04 INFO - PROCESS | 1974 | __end_tp_report 08:37:04 INFO - PROCESS | 1974 | __start_cc_report 08:37:04 INFO - PROCESS | 1974 | _x_x_mozilla_cycle_collect,1098 08:37:04 INFO - PROCESS | 1974 | __end_cc_report 08:37:04 INFO - PROCESS | 1974 | __startTimestamp1464104224226__endTimestamp 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | ------- Summary: start ------- 08:37:04 INFO - PROCESS | 1974 | Number of tests: 14 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#0] ai-astar Cycles:10 Average:134.00 Median:165.50 stddev:29.45 (17.8%) stddev-sans-first:30.73 08:37:04 INFO - PROCESS | 1974 | Values: 119.0 111.0 117.0 115.0 164.0 94.0 170.0 168.0 167.0 115.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#1] audio-beat-detection Cycles:10 Average:151.30 Median:153.50 stddev:17.22 (11.2%) stddev-sans-first:18.26 08:37:04 INFO - PROCESS | 1974 | Values: 153.0 124.0 147.0 165.0 143.0 154.0 171.0 152.0 177.0 127.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#2] audio-dft Cycles:10 Average:231.80 Median:235.00 stddev:29.40 (12.5%) stddev-sans-first:29.14 08:37:04 INFO - PROCESS | 1974 | Values: 202.0 212.0 272.0 269.0 227.0 209.0 243.0 204.0 209.0 271.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#3] audio-fft Cycles:10 Average:78.80 Median:60.00 stddev:30.84 (51.4%) stddev-sans-first:32.46 08:37:04 INFO - PROCESS | 1974 | Values: 68.0 82.0 75.0 140.0 58.0 59.0 57.0 61.0 130.0 58.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#4] audio-oscillator Cycles:10 Average:87.00 Median:72.50 stddev:19.91 (27.5%) stddev-sans-first:20.37 08:37:04 INFO - PROCESS | 1974 | Values: 72.0 88.0 121.0 70.0 73.0 77.0 111.0 71.0 75.0 112.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#5] imaging-gaussian-blur Cycles:10 Average:101.20 Median:73.00 stddev:22.71 (31.1%) stddev-sans-first:23.26 08:37:04 INFO - PROCESS | 1974 | Values: 118.0 73.0 124.0 74.0 96.0 131.0 117.0 116.0 90.0 73.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#6] imaging-darkroom Cycles:10 Average:114.00 Median:122.00 stddev:23.41 (19.2%) stddev-sans-first:23.44 08:37:04 INFO - PROCESS | 1974 | Values: 92.0 132.0 152.0 104.0 150.0 122.0 95.0 95.0 104.0 94.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#7] imaging-desaturate Cycles:10 Average:99.70 Median:90.50 stddev:18.18 (20.1%) stddev-sans-first:18.42 08:37:04 INFO - PROCESS | 1974 | Values: 115.0 105.0 90.0 92.0 90.0 91.0 93.0 91.0 85.0 145.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:60.50 stddev:5.38 (8.9%) stddev-sans-first:5.56 08:37:04 INFO - PROCESS | 1974 | Values: 58.0 62.0 60.0 57.0 59.0 59.0 61.0 76.0 62.0 60.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.00 stddev:3.72 (5.2%) stddev-sans-first:3.83 08:37:04 INFO - PROCESS | 1974 | Values: 69.0 67.0 68.0 76.0 77.0 76.0 73.0 69.0 69.0 71.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#10] stanford-crypto-aes Cycles:10 Average:92.30 Median:88.00 stddev:8.04 (9.1%) stddev-sans-first:7.10 08:37:04 INFO - PROCESS | 1974 | Values: 105.0 108.0 96.0 86.0 87.0 86.0 88.0 88.0 89.0 90.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#11] stanford-crypto-ccm Cycles:10 Average:156.20 Median:155.50 stddev:20.42 (13.1%) stddev-sans-first:21.09 08:37:04 INFO - PROCESS | 1974 | Values: 143.0 126.0 149.0 158.0 157.0 142.0 154.0 154.0 199.0 180.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:176.00 stddev:26.92 (15.3%) stddev-sans-first:25.75 08:37:04 INFO - PROCESS | 1974 | Values: 146.0 150.0 200.0 174.0 204.0 235.0 169.0 167.0 168.0 178.0 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - PROCESS | 1974 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:72.50 stddev:10.97 (15.1%) stddev-sans-first:11.49 08:37:04 INFO - PROCESS | 1974 | Values: 68.0 65.0 70.0 64.0 75.0 62.0 62.0 90.0 88.0 85.0 08:37:04 INFO - PROCESS | 1974 | -------- Summary: end -------- 08:37:04 INFO - PROCESS | 1974 | 08:37:04 INFO - TEST-INFO | 1974: exit 0 08:37:05 INFO - TEST-OK | kraken | took 61072ms 08:37:05 INFO - SUITE-END | took 1173s 08:37:05 INFO - Completed test suite (00:19:33) 08:37:05 INFO - javascript benchmark 08:37:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7121.465442602788, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3225.1614580209143, 3453.1367158210446, 3482.8084455354947, 3486.7036301914345, 3514.630963748808, 4288.592583704551, 5549.02891993901, 5686.147077938306, 5793.652380857152, 5816.1275808628725, 4755.8097676093, 5697.0, 5835.795747148851, 5878.265216847894, 5932.940670593303, 5244.056069907415, 5273.973630131844, 5319.228711836781, 5334.279872217259, 5437.6465529740635, 5458.908218356325, 5463.836084917439, 5497.615166938316, 5510.752016159262, 5512.586556008296, 5233.712145831978, 5286.3127793387, 5304.336957880265, 5305.575553955694, 5375.16684913839, 5296.947030529684, 5310.0441920454305, 5313.814016509404, 5320.760565774511, 5329.920051199235, 5249.501297376782, 5328.067588172094, 5337.946620533784, 5398.2982212312545, 5416.647917885325, 5662.37713851476, 5689.459501347365, 5751.1085781703905, 5763.1355296705415, 5764.481196692318, 5904.498117660004, 6299.559030867841, 6304.464120549759, 6334.778282760081, 6375.71309291083, 6220.440160385542, 6283.057541368786, 6306.842328941813, 6317.273513545955, 6320.336364681688, 5936.821895343147, 5985.7306421210715, 6003.639781613074, 6022.39776022403, 6055.818325450244, 6380.138681277968, 6404.103425520431, 6421.74313027483, 6434.227892652818, 6518.185226846645, 6121.757838957688, 6171.722272497748, 6283.277423096311, 6286.842828929313, 6297.433231009231, 6212.627242365382, 6244.625322480666, 6335.714892829834, 6345.936540634627, 6349.714262858182, 6184.81445556634, 6243.344448832807, 6291.496680265568, 6310.905336419865, 6330.082138090043, 6181.536384771161, 6241.687915604201, 6251.499880009588, 6285.057241413824, 6319.6208227506495, 5037.471065538139, 5071.923921141186, 5086.516780905808, 5096.464871188546, 5140.254663073833, 4478.373027776114, 4514.774261286922, 4541.772911354419, 4544.295634176708, 4588.49526552079, 5771.538276937835, 5884.882302353929, 5932.3474417814, 5937.168796368512, 6031.8793624127275, 6275.435210831047, 6307.117003619497, 6310.116583678288, 6320.146780184617, 6341.651209183539, 5960.791372302036, 5979.611325263844, 5980.581359304894, 5987.251593550806, 6009.789657361971, 5380.677159370451, 5443.782248710087, 5465.371482279589, 5498.862528436741, 5567.805126820542, 6447.982391052083, 6454.290028097, 6456.644884531302, 6480.2547707013355, 6494.740210391531, 5237.895242095061, 6298.590591611531, 6298.622082675054, 6311.368863113652, 6340.4293613575, 6217.0, 6400.23197216337, 6433.292337842927, 6484.157059582224, 6534.215894092739, 5358.0355536002335, 6236.095766113979, 6292.685365731787, 6312.368763123651, 6319.115323854756, 6013.098035294741, 6303.590266632654, 6313.05304204373, 6323.747050117944, 6332.176817013758, 5197.766100525561, 6121.47967422774, 6127.342242870382, 6152.815415537541, 6168.938310617016, 5094.7962081516325, 6093.664848433202, 6143.911070236141, 6217.595856269328, 6249.562530622813, 5871.970640146772, 6078.483328917092, 6083.756649733961, 6094.20775299226, 6147.969260153671, 6083.6045657032155, 6130.428711073822, 6160.199174107337, 6199.690015499297, 6263.404976527223, 6160.0, 6163.445289923929, 6170.228721409824, 6186.752529898934, 6193.690315484298], "value": 5777.243322683259, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.19444111656884, 502.15387072782363, 512.3621091740783, 512.4670342843443, 530.5569849175938, 32918.34163316735, 36505.81747091264, 39377.0, 41964.0, 42095.158096838124, 37034.25931481375, 42130.157396852046, 43382.566174338244, 43407.0, 43454.565454345444, 9507.857382139257, 9665.323427360088, 9677.37097088687, 9754.46350450725, 9830.0, 9617.27870409722, 9621.518924053804, 9622.855657165148, 9623.278254130933, 9739.366941148837, 9649.517524123801, 9687.854682179774, 9696.418214907128, 9748.756281092994, 9776.217902567812, 9628.422294662309, 9639.325247232697, 9680.419174849496, 9696.46669433181, 9741.65904193357, 9474.763130921714, 9540.95229523848, 9550.092741189575, 9641.855372169422, 9650.662226822027, 35523.11192220215, 37815.05462363463, 39482.407763883566, 39536.0, 39657.40513892294, 3143.387039527293, 3149.826759528225, 3264.4287249731437, 3312.205070782995, 3472.9131771705656, 23656.935437905337, 28962.710372896214, 29482.557761633594, 29900.401991960258, 30258.394832103455, 2746.6703995520666, 2754.352727109148, 2813.0998080614213, 2833.7732981361646, 2881.236472334833, 511939.0, 517300.0, 517735.0, 517998.0, 518401.81598183536, 34890.30219395598, 35660.64339356625, 64322.67838660777, 64848.67575662091, 64964.0, 30351.696483035328, 36035.459468108, 36065.0, 36238.0, 37251.88244352619, 12941.611751647466, 14950.62623434401, 14989.325480353411, 15142.318595663222, 15144.621384465256, 12699.809502857464, 12795.04037197214, 12829.358532073546, 12834.743305133845, 12862.807057894139, 7787.5716835574585, 7852.332551733052, 7962.920370796333, 7972.202779721982, 8023.9598802006785, 3307.751918606114, 3317.817520036373, 3319.6680331967095, 3339.248669049493, 3345.5985281766343, 912.32943786317, 934.2992755433424, 968.5835090910836, 1023.4166525080633, 1033.1992705653179, 28690.282742931595, 29005.0, 29160.56259156114, 29521.557176642367, 29531.261718457212, 1049.653614307292, 1065.9520321585546, 1090.536521978156, 1100.0099910080992, 1159.96520104397, 15017.324220410108, 15125.168115753519, 15131.394744210109, 15218.16300103505, 15320.974871570193, 421067.89466052473, 513510.0, 548140.0, 550530.0, 569221.0, 47408.525914740414, 57740.711296443245, 58966.41033589695, 59013.0, 59476.702616487506, 1382.4470211915113, 1412.4632639597094, 1425.6934759026858, 1427.414759948437, 1428.8785453236383, 24465.266042018768, 28691.856540717163, 29028.274293141996, 29118.708812912446, 29417.705822941498, 48932.75533622309, 51859.96280074453, 51927.2210916829, 52009.21986170135, 52084.479155209476, 395497.0, 406899.0, 407358.0, 407657.0, 407951.0, 25890.741092588833, 25954.0, 26071.73928260693, 26477.205683829518, 27029.45941081128, 45058.0, 45281.32078018766, 45526.08947821091, 46299.0, 46698.59904202879, 37498.8125059373, 37626.0, 37996.050098746644, 38283.61716382801, 38616.2276754469, 37014.0, 37017.0, 37152.07119821918, 37525.87422377333, 37857.432138518496, 32254.83872580622, 34771.478427824106, 37351.43972840355, 37763.81118094392, 38475.230495389376, 33148.668513315526, 33470.832645836614, 34016.65983340135, 34230.65769342275, 34290.82854585811, 23389.181378651665, 24433.267001989258, 25002.874985624956, 25140.874295628404, 25293.114740983976], "value": 18117.205145594515, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.86964279466815, 382.82390100553727, 384.3773087598091, 399.5285563035617, 404.03838863504853, 19047.7618954768, 20442.795572044313, 20704.068316925735, 21126.260580879672, 21140.471488212785, 20393.796062039375, 20736.274230401938, 21004.26485073023, 21005.684914726255, 21464.141434342648, 5773.797917072914, 5806.564507661921, 6057.818265452043, 6626.801195964129, 6627.304133066031, 5942.792002279921, 6584.374484423996, 6647.966760166217, 6691.096701945224, 6715.194176698803, 5605.27131472908, 6538.5749926254875, 6555.672216389185, 6565.868682626368, 6582.078509008743, 6229.626222426669, 6504.642244676541, 6537.346265373472, 6556.704948277315, 6557.967210163943, 5646.717664116819, 5655.491005809456, 5658.151277308396, 5665.886682266373, 5692.886142277172, 25625.487490250092, 26298.21105366842, 26743.598846017132, 26746.732532674618, 26803.463930721275, 2076.9480762981048, 2147.4631342164457, 2294.908203671851, 2382.707440980174, 2450.9509809803703, 14375.712485750226, 15077.849221507755, 15159.621009474851, 15230.771538426932, 15272.236388180658, 1671.1727694791157, 1705.8038325592593, 1710.503953853381, 1725.870559708027, 1756.6311074674206, 117048.41475792651, 123295.0, 124595.75404246023, 125573.11640325311, 125649.74350256471, 10655.200859935536, 10821.513031913582, 11038.006579407891, 11122.888771112186, 11276.492557834918, 13368.59894203156, 13394.598162055154, 13791.51729689476, 13809.654758631115, 13846.861531384557, 8669.739907802776, 8823.647054117891, 8849.026607073216, 8964.372493925363, 8968.775780605538, 7731.806704832424, 7835.764927052198, 8455.323574114056, 8911.37620366568, 8935.240504557185, 6622.106015687917, 6629.3370662934285, 6665.866682666319, 6718.563293386012, 6733.114727876105, 1822.0, 1858.0988220712943, 1858.8884666920028, 1861.3485280151838, 1864.2449807827852, 925.3938670171049, 965.9806803864024, 970.2189737261572, 1008.1330056151704, 1029.4080903480528, 20209.696854547194, 20852.0, 20968.685469717966, 21214.78785212128, 21368.622984233545, 883.7392969074139, 969.4280374579032, 981.6122631560636, 1029.2280789407944, 1060.1148041385375, 8632.870506942401, 11368.658940231806, 11378.975892169745, 11384.943075284571, 11415.942920285512, 516506.4174679103, 596932.0, 606051.0, 611843.0, 613149.0, 94883.52558237164, 118057.0, 121038.0, 121923.34229973116, 122196.0, 989.7179303898355, 992.9078014184483, 994.3039872089566, 996.0437979539723, 1031.1338475680463, 18357.908210458863, 18480.81519184791, 18497.81502184961, 18604.7209291858, 18800.811991880455, 46111.84720382017, 47230.58308250758, 48515.0, 49657.75171124121, 51533.48466515287, 52661.47338526566, 65724.67137664281, 70602.64698676679, 71346.64326678333, 72923.90614140686, 7384.261573842573, 7415.406767458699, 7502.474826762293, 7609.391248700202, 7817.6872925084635, 9111.498867562348, 11585.6524304271, 11933.4033298333, 11982.921537061706, 11995.820062698893, 8419.83160336802, 9991.050850169222, 10200.0, 10238.078572928473, 10255.179585633281, 9921.107100361001, 10036.64871729486, 10277.794444111225, 10283.280170388001, 10361.792764144524, 9743.366681165702, 10344.431056291674, 10372.481375931022, 10581.841272380767, 10660.520276587727, 7452.925470745223, 7488.588127653032, 7491.737789177353, 7553.169151393452, 7771.33943614799, 10004.449755263531, 10180.236482263712, 10332.896671033193, 10366.118879895294, 10414.791704166026], "value": 9618.35503171228, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.4133243371568, 1544.7065057639052, 1751.5621094726318, 1900.0594705620729, 1938.234397413021, 8039.397045221617, 8586.570671466432, 8698.304135669146, 9059.728208153758, 9072.41029333093, 8801.251893589037, 8850.601722922467, 8939.150780675842, 8941.463512189273, 8953.865692014626, 2369.170790223421, 2407.9157229496973, 2412.0713525292713, 2412.348665860217, 2412.6139817629182, 2374.157174203159, 2388.8566685998894, 2406.639004149383, 2415.7946574541184, 2419.250032489925, 2368.171140100968, 2388.9283321500384, 2394.820388470863, 2399.5081008393327, 2400.7719266669637, 1968.6358023765506, 2037.5415531505441, 2308.35366097493, 2362.492064206195, 2364.574376612209, 2259.5254996450767, 2259.53679495703, 2261.050358849287, 2265.1392470861133, 2273.943151421211, 8748.518831464267, 8838.204561589488, 8877.51173685447, 8900.154485323885, 8907.242884354837, 5224.712640804754, 5343.679379237219, 5395.946040539623, 5467.07059799831, 5513.972430137864, 3441.0537102296817, 3538.0800991742076, 3606.9098272543133, 3607.9098022549383, 3629.3285742137523, 999.8300288950823, 1035.663409391943, 1037.723599972031, 1049.1921220660124, 1059.5814653211937, 11649.0, 11712.121590880633, 11815.763684726344, 11819.704507387383, 11865.822012669816, 4828.348172996671, 4829.396325459317, 4932.4081110267, 4944.55499005084, 4944.678595891256, 9948.950255248677, 10022.599096036078, 10088.646897358558, 10093.747656308651, 10132.69601911929, 5340.946590534045, 5353.946460535422, 5371.973140134352, 5448.482394172547, 5467.781288748485, 4393.747781882148, 4441.888952776142, 4763.142634325785, 4814.831480898151, 4898.608111351084, 3994.7403418777685, 4060.7766572838186, 4067.349224124126, 4117.279476091732, 4161.687873409506, 1122.5173175534599, 1303.0748168800258, 1303.7653222419865, 1307.1046333261745, 1319.9538016169388, 854.7093988043971, 917.5733284022962, 930.8464103422859, 959.7072892767633, 961.9086186812382, 5921.259842519685, 6029.914615369275, 6038.396160383926, 6053.273607167168, 6232.189815323979, 981.79873126009, 1000.214831357381, 1003.1523362758603, 1052.2686732720838, 1067.6103222323952, 3231.660675629072, 3434.8797792077658, 4054.5945405459806, 4057.5130984282073, 4121.361189015707, 10490.370577765358, 10589.417582033062, 10688.0, 10816.188785841094, 10859.239853210358, 10634.840477392845, 10714.142868570496, 10724.981126792942, 10729.78540429187, 10731.08785753204, 8160.7143749969655, 8173.468724533005, 8196.83606327882, 8329.416940814084, 8346.040205376337, 4892.168331383602, 5883.264591926009, 5885.499732522783, 5898.4101589840675, 5958.1658567801405, 9670.936197018464, 9875.506224688881, 9902.653407130716, 10052.849207262043, 10089.142422894136, 10146.695599132037, 10183.236257280882, 10292.485375731334, 10304.381737095799, 10319.0, 7044.964775176296, 7058.71765129389, 7331.486795924234, 7611.543307401574, 7672.424568157299, 8360.372972027, 8406.453580517247, 8454.36592255571, 8507.574621268788, 8513.787155321166, 7251.166115896739, 7300.96349518249, 7454.44091693136, 7530.510516816389, 7651.3496352810635, 7296.4162866969455, 7387.482876198829, 7417.998570193171, 7433.888491672521, 7536.0, 7042.859142817012, 7077.504574679723, 7078.610676412847, 7112.928870711434, 7181.640917954186, 4574.085182963354, 4852.272159176152, 4880.5607495324175, 5037.319961805109, 5205.245239440184, 4913.680610760289, 4962.851114466572, 5000.974995125001, 5070.670406423571, 5275.788968441219], "value": 4622.69561131772, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.14470503202654, 300.44417827020004, 316.92235402326435, 319.4824384497115, 321.53217069164367, 4591.4260717410325, 4684.789184486698, 5430.647007944491, 5613.2141500189955, 5638.718064096789, 4723.409573803275, 5503.532199763015, 5514.393416724157, 5523.171524271362, 5569.526590239824, 3730.701543876497, 3904.2777086238993, 4221.82575973374, 4223.873283801483, 4228.429162063128, 3620.8051343056727, 3894.4742459767995, 3899.961000389988, 4217.493900731916, 4287.992321804373, 3272.7381809455187, 3767.907306881001, 4114.444549985744, 4145.3781932710035, 4159.750414975111, 3360.815155166465, 3832.597577254404, 4129.669626429878, 4138.91722165555, 4173.624373806341, 3064.3952640255825, 3084.6144231971, 3440.5205761522534, 3587.7847329160336, 3626.311000909845, 4998.900021999576, 5254.0, 5266.052110620087, 5271.446498117719, 5409.188621706736, 4575.0, 4670.789814458358, 4720.952790472085, 4773.4510531289, 4775.824076988798, 4951.678140920829, 5354.651947623431, 5566.610337276392, 5763.711814409263, 5781.797637082682, 3226.757993150523, 3921.784301863396, 3940.5862384449792, 3944.250592387466, 3957.5448823385104, 4925.088858561142, 5387.326584176939, 5619.7471113799975, 5883.793822266468, 6054.8183554493435, 3650.580183278904, 3747.063234191452, 3988.441618173458, 4018.799060046986, 4039.4448137466698, 3943.5662077171487, 4035.7174997750462, 4047.2310261050893, 4080.5511393746665, 4118.299889018814, 4048.757074575476, 4703.741294228782, 4733.366988389001, 4774.641901857374, 4790.7844147012775, 4960.677555958851, 5183.792648294026, 5382.3541175059245, 5679.375268720438, 5951.809638072403, 1149.8447709559102, 1312.967175820593, 1362.8773410393112, 1372.739179555901, 1398.496616137656, 810.5744484145753, 827.2389401750404, 838.9874151887726, 845.9045536030732, 850.1339559666472, 3706.7405281630026, 4366.759828209415, 4511.932321015187, 4642.257238841837, 4666.899895047242, 4956.058348913695, 5115.38615366151, 5149.433562308143, 5163.432022477525, 5209.739513024334, 4380.03639199382, 4384.4081049058605, 4435.600795928317, 4468.419105516262, 4626.028534007896, 2839.367363765843, 2900.5069138246126, 3079.4286770546846, 3211.951820722691, 3238.8542515586855, 5522.30971128609, 6329.563118199434, 6444.2589102252905, 6477.5465717400275, 6480.870382592416, 5539.944600554023, 5955.962459145867, 6250.249970003629, 6255.12428260044, 6312.589681670676, 6343.904841427382, 6353.396427339396, 6378.553501254868, 6386.808395748135, 6406.135171751754, 5618.747156378055, 5701.658987997594, 5714.571407144397, 6016.278046634432, 6032.90950635732, 5387.949349876736, 5763.4812866842185, 5984.58107932458, 5997.100434934795, 6024.939750602438, 6328.272248691459, 6332.873342533216, 6377.840553986002, 6377.840553986188, 6447.83880402975, 3444.0283799763897, 3485.0, 3546.5212196353164, 3687.967369136646, 3874.9806250968563, 5273.630845840754, 5753.90675771602, 5966.284045914518, 5968.791092311748, 5987.64074155552, 5566.526845218202, 5896.380880007538, 5899.616524925866, 5902.852428689317, 5931.51712071982, 5326.8014696692935, 5467.7266136692215, 5820.243368362086, 5883.411658834082, 6106.969465152823, 4813.085513752376, 5090.974545127251, 5501.19439086061, 5689.487946084872, 5691.430856914276, 4982.900341993068, 5245.983559453253, 5635.549156067586, 5836.241288632451, 5861.882762344814, 5253.947460525499, 5365.919511207257, 5570.6657600544095, 5694.202811606296, 5801.883962320814], "value": 4138.78315780428, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.0, 720.3552820225898, 741.2661465149504, 742.35786045071, 744.6462930108199, 13399.7320053599, 14946.626334341647, 14977.0, 15167.544973650809, 15273.159976201305, 13704.972127090457, 14036.929815350935, 14043.22762248076, 14076.085054471452, 14101.788473172885, 714.6605362452846, 874.3049275825703, 888.080836334394, 895.3687650206622, 910.0308171797014, 880.2869675562782, 894.1862904756673, 894.4141587260323, 895.4716717136888, 896.2337201692557, 951.9666811661593, 951.9857202141939, 952.9190018848407, 953.142172045158, 956.3305686019814, 942.3497786527274, 944.4852555357312, 948.0756262643868, 951.5289931483891, 951.5337484632549, 673.5217995223397, 699.4544255480733, 700.4501466348943, 700.502643123383, 703.0157779109237, 11252.38111903845, 11500.482478288497, 11501.30992140466, 13639.181649100987, 13641.590752277447, 12204.267743935392, 12362.629121126382, 12500.437480313407, 12542.561010364685, 12548.1216314858, 9575.0, 9698.660546880896, 9950.154236889874, 10005.59977600895, 10431.113355364803, 3980.24375368679, 3990.960090399117, 3994.960050399488, 4018.3168861293652, 4135.834566617332, 75214.62392688057, 76407.61796190984, 76706.0, 77957.61021194914, 80569.19430805676, 9045.819083618291, 9187.127222913812, 9196.908030919605, 9310.487923164155, 9485.288603354777, 20270.18919243214, 20327.695084573745, 20333.89833050825, 20357.0, 20821.791782082288, 7456.47804653669, 7530.811729706692, 7612.695492180251, 7671.6164191790185, 7720.3051725344985, 3267.8702364202018, 3822.8853134406013, 3842.50047493824, 3874.1476875087437, 3910.7066969977363, 3462.2556150427276, 3549.8580056797955, 3552.3960926642535, 3554.0581745837376, 3563.982180089083, 535.8178219405421, 652.4519403700914, 672.1799404726227, 675.152683382352, 698.4796326736512, 526.2711145064037, 563.8111232736996, 566.9171881705922, 596.6599038548072, 600.3516202501288, 8264.876026859602, 8275.710350137837, 8367.707130250412, 8402.537860417613, 8516.914830851736, 565.8726786473093, 582.1762206477871, 591.2373038780012, 595.1905408644327, 601.0488501457821, 3931.233409485123, 3942.605739426092, 4152.231837110084, 4274.615284624399, 4340.565943405697, 87179.12820871836, 94662.0, 96752.0, 100925.49537252267, 100981.49509252407, 65868.01197982034, 84577.0, 86065.0, 87427.0, 88181.0, 61869.381306187264, 63535.36464635387, 67084.6645766768, 78119.21880781233, 80371.59814200892, 8245.175482451707, 8268.875966860622, 8302.70940517079, 8318.625661845352, 8344.41589088758, 35094.12264693403, 42369.0, 44195.337069943336, 44274.55725442704, 44414.667559973255, 60331.69834150801, 62783.6860815693, 63044.739105218556, 63238.0, 64483.710325794156, 9678.854817177607, 10646.73383165427, 10864.782704346027, 11008.119350451785, 11142.27575207609, 11633.243839150766, 11806.586769463027, 11969.820452693391, 12138.453769580214, 12301.938490307492, 10758.363347933146, 11084.556617735201, 11201.327920324806, 11224.831627525757, 11411.0, 9301.953490232505, 11315.490802913724, 11328.773424531299, 11639.76720465603, 11693.854937366717, 10196.796064078826, 10212.591496340063, 10288.742781430225, 10293.794124117625, 10755.784884302426, 8953.59708813118, 9142.77143071402, 9196.908030919605, 9668.661596844076, 9838.163756080814, 7871.134175240604, 8513.680379541182, 9321.254299656146, 9435.103665151799, 9456.33805633599], "value": 6771.645169176706, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1631.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 111.0, 117.0, 115.0, 164.0, 94.0, 170.0, 168.0, 167.0, 115.0], "value": 134.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 124.0, 147.0, 165.0, 143.0, 154.0, 171.0, 152.0, 177.0, 127.0], "value": 151.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 212.0, 272.0, 269.0, 227.0, 209.0, 243.0, 204.0, 209.0, 271.0], "value": 231.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 82.0, 75.0, 140.0, 58.0, 59.0, 57.0, 61.0, 130.0, 58.0], "value": 78.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 88.0, 121.0, 70.0, 73.0, 77.0, 111.0, 71.0, 75.0, 112.0], "value": 87.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 73.0, 124.0, 74.0, 96.0, 131.0, 117.0, 116.0, 90.0, 73.0], "value": 101.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 132.0, 152.0, 104.0, 150.0, 122.0, 95.0, 95.0, 104.0, 94.0], "value": 114.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 105.0, 90.0, 92.0, 90.0, 91.0, 93.0, 91.0, 85.0, 145.0], "value": 99.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 60.0, 57.0, 59.0, 59.0, 61.0, 76.0, 62.0, 60.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 76.0, 77.0, 76.0, 73.0, 69.0, 69.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 108.0, 96.0, 86.0, 87.0, 86.0, 88.0, 88.0, 89.0, 90.0], "value": 92.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 126.0, 149.0, 158.0, 157.0, 142.0, 154.0, 154.0, 199.0, 180.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 150.0, 200.0, 174.0, 204.0, 235.0, 169.0, 167.0, 168.0, 178.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 70.0, 64.0, 75.0, 62.0, 62.0, 90.0, 88.0, 85.0], "value": 72.9, "unit": "score"}]}]} 08:37:05 INFO - Return code: 0 08:37:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:37:05 ERROR - # TBPL SUCCESS # 08:37:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:37:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:37:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:37:05 INFO - 2016-05-24 08:37:05.540 screenresolution[1979:14277] starting screenresolution argv=screenresolution get 08:37:05 INFO - 2016-05-24 08:37:05.543 screenresolution[1979:14277] Display 0: 1600x1200x32@60 08:37:05 INFO - 2016-05-24 08:37:05.549 screenresolution[1980:14280] starting screenresolution argv=screenresolution list 08:37:05 INFO - Available Modes on Display 0 08:37:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:37:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:37:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:37:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:37:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:37:05 INFO - Intel Iris: 08:37:05 INFO - Chipset Model: Intel Iris 08:37:05 INFO - Type: GPU 08:37:05 INFO - Bus: Built-In 08:37:05 INFO - VRAM (Dynamic, Max): 1536 MB 08:37:05 INFO - Vendor: Intel (0x8086) 08:37:05 INFO - Device ID: 0x0a2e 08:37:05 INFO - Revision ID: 0x0009 08:37:05 INFO - Displays: 08:37:05 INFO - Display: 08:37:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:37:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:37:05 INFO - Display Serial Number: 1600x1200 60 08:37:05 INFO - Main Display: Yes 08:37:05 INFO - Mirror: Off 08:37:05 INFO - Online: Yes 08:37:05 INFO - Rotation: Supported 08:37:05 INFO - Return code: 0 08:37:05 INFO - Running post-action listener: _resource_record_post_action 08:37:05 INFO - ##### 08:37:05 INFO - ##### Finished run-tests step (success) 08:37:05 INFO - ##### 08:37:05 INFO - Running post-run listener: _resource_record_post_run 08:37:05 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 151552; Write bytes: 696243712; Read time: 4; Write time: 2105 08:37:05 INFO - TinderboxPrint: CPU usage
54.4% 08:37:05 INFO - TinderboxPrint: I/O read bytes / time
151,552 / 4 08:37:05 INFO - TinderboxPrint: I/O write bytes / time
696,243,712 / 2,105 08:37:05 INFO - TinderboxPrint: CPU idle
2,207.3 (45.6%) 08:37:05 INFO - TinderboxPrint: CPU system
979.2 (20.2%) 08:37:05 INFO - TinderboxPrint: CPU user
1,651.1 (34.1%) 08:37:05 INFO - TinderboxPrint: Swap in / out
396,009,472 / 0 08:37:05 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 397273600; Write bytes: 398657024; Read time: 29922; Write time: 725 08:37:05 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 139264; Write bytes: 296738816; Read time: 3; Write time: 1378 08:37:06 INFO - Running post-run listener: _upload_blobber_files 08:37:06 INFO - Blob upload gear active. 08:37:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:37:06 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 08:37:06 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'] 08:37:06 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 08:37:06 INFO - (blobuploader) - INFO - Open directory for files ... 08:37:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:37:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:37:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:07 INFO - (blobuploader) - INFO - Done attempting. 08:37:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:37:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:37:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:08 INFO - (blobuploader) - INFO - Done attempting. 08:37:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:37:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:37:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:10 INFO - (blobuploader) - INFO - Done attempting. 08:37:10 INFO - (blobuploader) - INFO - Iteration through files over. 08:37:10 INFO - Return code: 0 08:37:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:37:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:37:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d805e919b5d27ac6d884da83cc4d8c8b9b9ea0ec8e9d692453d0ef66adf559ccc62ddef95d2c9e6fa356f8b82a5337d62ff66287c29c0aa667aab8cac4c0dd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21ae29bdcb908eb7d5eb49c0ccf9c88e61a4a7b624e8cda4cb6c0f6b5d75d6525be6390cdcd86b1da33c035ea87543092569178a504f8a4172ecbb4f41036f21", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d155afe03e40d79e298e6585899e7a12be10c464128c5f9c23522b4496c9df190e62e880ac07064caa551d936edfc7c43e61e8b09630790a9fd89e8684332657"} 08:37:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:37:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:37:10 INFO - Contents: 08:37:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d805e919b5d27ac6d884da83cc4d8c8b9b9ea0ec8e9d692453d0ef66adf559ccc62ddef95d2c9e6fa356f8b82a5337d62ff66287c29c0aa667aab8cac4c0dd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21ae29bdcb908eb7d5eb49c0ccf9c88e61a4a7b624e8cda4cb6c0f6b5d75d6525be6390cdcd86b1da33c035ea87543092569178a504f8a4172ecbb4f41036f21", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d155afe03e40d79e298e6585899e7a12be10c464128c5f9c23522b4496c9df190e62e880ac07064caa551d936edfc7c43e61e8b09630790a9fd89e8684332657"} 08:37:10 INFO - Running post-run listener: copy_logs_to_upload_dir 08:37:10 INFO - Copying logs to upload dir... 08:37:10 INFO - mkdir: /builds/slave/test/build/upload/logs 08:37:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1297.227342 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 37 secs) (at 2016-05-24 08:37:10.954046) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 08:37:10.959877) ========= 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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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/6d805e919b5d27ac6d884da83cc4d8c8b9b9ea0ec8e9d692453d0ef66adf559ccc62ddef95d2c9e6fa356f8b82a5337d62ff66287c29c0aa667aab8cac4c0dd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21ae29bdcb908eb7d5eb49c0ccf9c88e61a4a7b624e8cda4cb6c0f6b5d75d6525be6390cdcd86b1da33c035ea87543092569178a504f8a4172ecbb4f41036f21", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d155afe03e40d79e298e6585899e7a12be10c464128c5f9c23522b4496c9df190e62e880ac07064caa551d936edfc7c43e61e8b09630790a9fd89e8684332657"} build_url:https://queue.taskcluster.net/v1/task/WosYwMawQYS7iku-3kGVdg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011236 build_url: 'https://queue.taskcluster.net/v1/task/WosYwMawQYS7iku-3kGVdg/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/6d805e919b5d27ac6d884da83cc4d8c8b9b9ea0ec8e9d692453d0ef66adf559ccc62ddef95d2c9e6fa356f8b82a5337d62ff66287c29c0aa667aab8cac4c0dd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21ae29bdcb908eb7d5eb49c0ccf9c88e61a4a7b624e8cda4cb6c0f6b5d75d6525be6390cdcd86b1da33c035ea87543092569178a504f8a4172ecbb4f41036f21", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d155afe03e40d79e298e6585899e7a12be10c464128c5f9c23522b4496c9df190e62e880ac07064caa551d936edfc7c43e61e8b09630790a9fd89e8684332657"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 08:37:10.991457) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 08:37:10.991779) ========= 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.kkTlUEa2rh/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.sebTEOv9Tm/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.005241 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 08:37:11.040087) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 08:37:11.040378) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 08:37:11.040691) ========= ========= Total master_lag: 2.87 =========