builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0169 starttime: 1464729431.02 results: success (0) buildid: 20160531122540 builduid: 110a2d9dc13a4e7e9e2cd5af6d2ae613 revision: 23a96762bc11fdbd26f032d3fcbcbf7417797acb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:11.024279) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:11.024645) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:11.024910) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:11.147180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:11.147722) ========= 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.fHASSF1ENJ/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.cS18ClHoSw/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-31 14:17:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.14M=0.001s 2016-05-31 14:17:11 (8.14 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124872 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:11.300798) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:11.301074) ========= 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.fHASSF1ENJ/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.cS18ClHoSw/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.019647 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:11.351678) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:11.352018) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 23a96762bc11fdbd26f032d3fcbcbf7417797acb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 23a96762bc11fdbd26f032d3fcbcbf7417797acb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fHASSF1ENJ/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.cS18ClHoSw/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-31 14:17:11,427 truncating revision to first 12 chars 2016-05-31 14:17:11,427 Setting DEBUG logging. 2016-05-31 14:17:11,427 attempt 1/10 2016-05-31 14:17:11,427 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/23a96762bc11?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 14:17:12,102 unpacking tar archive at: larch-23a96762bc11/testing/mozharness/ program finished with exit code 0 elapsedTime=0.930493 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:12.304632) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:12.304937) ========= script_repo_revision: 23a96762bc11fdbd26f032d3fcbcbf7417797acb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:12.305692) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:12.306032) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 14:17:12.323148) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-05-31 14:17:12.323462) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fHASSF1ENJ/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.cS18ClHoSw/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:17:12 INFO - MultiFileLogger online at 20160531 14:17:12 in /builds/slave/test 14:17:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 14:17:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:17:12 INFO - {'append_to_log': False, 14:17:12 INFO - 'base_work_dir': '/builds/slave/test', 14:17:12 INFO - 'blob_upload_branch': 'Larch', 14:17:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:17:12 INFO - 'branch': 'Larch', 14:17:12 INFO - 'buildbot_json_path': 'buildprops.json', 14:17:12 INFO - 'config_files': ('talos/mac_config.py',), 14:17:12 INFO - 'default_actions': ('clobber', 14:17:12 INFO - 'read-buildbot-config', 14:17:12 INFO - 'download-and-extract', 14:17:12 INFO - 'populate-webroot', 14:17:12 INFO - 'create-virtualenv', 14:17:12 INFO - 'install', 14:17:12 INFO - 'run-tests'), 14:17:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:17:12 INFO - 'download_minidump_stackwalk': True, 14:17:12 INFO - 'download_symbols': 'ondemand', 14:17:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:17:12 INFO - 'tooltool.py': '/tools/tooltool.py', 14:17:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:17:12 INFO - '/tools/misc-python/virtualenv.py')}, 14:17:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:17:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:17:12 INFO - 'installer_path': 'installer.exe', 14:17:12 INFO - 'log_level': 'info', 14:17:12 INFO - 'log_name': 'talos', 14:17:12 INFO - 'log_to_console': True, 14:17:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:17:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:17:12 INFO - 'opt_config_files': (), 14:17:12 INFO - 'pip_index': False, 14:17:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:17:12 INFO - 'cmd': ('bash', 14:17:12 INFO - '-c', 14:17:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:17:12 INFO - 'enabled': True, 14:17:12 INFO - 'halt_on_failure': False, 14:17:12 INFO - 'name': 'check_screen_resolution'},), 14:17:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:17:12 INFO - 'cmd': ('bash', 14:17:12 INFO - '-c', 14:17:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:17:12 INFO - 'enabled': True, 14:17:12 INFO - 'halt_on_failure': False, 14:17:12 INFO - 'name': 'check_screen_resolution'},), 14:17:12 INFO - 'run_cmd_checks_enabled': True, 14:17:12 INFO - 'sps_profile': False, 14:17:12 INFO - 'sps_profile_interval': 0, 14:17:12 INFO - 'suite': 'dromaeojs', 14:17:12 INFO - 'system_bits': '32', 14:17:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:17:12 INFO - 'title': 't-yosemite-r7-0169', 14:17:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:17:12 INFO - 'use_talos_json': True, 14:17:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:17:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:17:12 INFO - 'work_dir': 'build'} 14:17:12 INFO - ##### 14:17:12 INFO - ##### Running clobber step. 14:17:12 INFO - ##### 14:17:12 INFO - Running pre-action listener: _resource_record_pre_action 14:17:12 INFO - Running main action method: clobber 14:17:12 INFO - rmtree: /builds/slave/test/build 14:17:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:17:13 INFO - Running post-action listener: _resource_record_post_action 14:17:13 INFO - ##### 14:17:13 INFO - ##### Finished clobber step (success) 14:17:13 INFO - ##### 14:17:13 INFO - ##### 14:17:13 INFO - ##### Running read-buildbot-config step. 14:17:13 INFO - ##### 14:17:13 INFO - Running pre-action listener: _resource_record_pre_action 14:17:13 INFO - Running main action method: read_buildbot_config 14:17:13 INFO - Using buildbot properties: 14:17:13 INFO - { 14:17:13 INFO - "project": "", 14:17:13 INFO - "product": "firefox", 14:17:13 INFO - "script_repo_revision": "production", 14:17:13 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 14:17:13 INFO - "repository": "", 14:17:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 14:17:13 INFO - "buildid": "20160531122540", 14:17:13 INFO - "pgo_build": "False", 14:17:13 INFO - "basedir": "/builds/slave/test", 14:17:13 INFO - "buildnumber": 0, 14:17:13 INFO - "slavename": "t-yosemite-r7-0169", 14:17:13 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 14:17:13 INFO - "platform": "yosemite_r7", 14:17:13 INFO - "scheduler": "tests-larch-macosx64-talos", 14:17:13 INFO - "branch": "larch", 14:17:13 INFO - "repo_path": "projects/larch", 14:17:13 INFO - "revision": "23a96762bc11fdbd26f032d3fcbcbf7417797acb", 14:17:13 INFO - "stage_platform": "macosx64", 14:17:13 INFO - "builduid": "110a2d9dc13a4e7e9e2cd5af6d2ae613", 14:17:13 INFO - "slavebuilddir": "test" 14:17:13 INFO - } 14:17:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:17:13 INFO - Running post-action listener: _resource_record_post_action 14:17:13 INFO - ##### 14:17:13 INFO - ##### Finished read-buildbot-config step (success) 14:17:13 INFO - ##### 14:17:13 INFO - ##### 14:17:13 INFO - ##### Running download-and-extract step. 14:17:13 INFO - ##### 14:17:13 INFO - Running pre-action listener: _resource_record_pre_action 14:17:13 INFO - Running main action method: download_and_extract 14:17:13 INFO - mkdir: /builds/slave/test/build/tests 14:17:13 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')]} 14:17:13 INFO - https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:17:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:17:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:17:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/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 14:17:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/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 14:17:19 INFO - Downloaded 1199 bytes. 14:17:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:17:19 INFO - Using the following test package requirements: 14:17:19 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:17:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:17:19 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:17:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:17:19 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:17:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:17:19 INFO - u'jsshell-mac.zip'], 14:17:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:17:19 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:17:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:17:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:17:19 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:17:19 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:17:19 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:17:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:17:19 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:17:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:17:19 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:17:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:19 INFO - https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:17:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:17:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:17:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/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 14:17:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/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 14:17:24 INFO - Downloaded 18687096 bytes. 14:17:24 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'] 14:17:24 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 14:17:27 INFO - Return code: 0 14:17:27 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 14:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:27 INFO - https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:17:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:17:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:17:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/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 14:17:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/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 14:17:40 INFO - Downloaded 18687096 bytes. 14:17:40 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'] 14:17:40 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 14:17:43 INFO - Return code: 0 14:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:43 INFO - https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:17:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:17:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:17:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/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 14:17:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/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 14:17:45 INFO - Downloaded 10834063 bytes. 14:17:45 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'] 14:17:45 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 14:17:45 INFO - Return code: 0 14:17:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:45 INFO - https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:17:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:17:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:17:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:17:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/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 14:17:54 INFO - Downloaded 111129254 bytes. 14:17:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:17:54 INFO - mkdir: /builds/slave/test/properties 14:17:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:17:54 INFO - Writing to file /builds/slave/test/properties/build_url 14:17:54 INFO - Contents: 14:17:54 INFO - build_url:https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:17:56 INFO - Running post-action listener: _resource_record_post_action 14:17:56 INFO - Running post-action listener: set_extra_try_arguments 14:17:56 INFO - ##### 14:17:56 INFO - ##### Finished download-and-extract step (success) 14:17:56 INFO - ##### 14:17:56 INFO - ##### 14:17:56 INFO - ##### Running populate-webroot step. 14:17:56 INFO - ##### 14:17:56 INFO - Running pre-action listener: _resource_record_pre_action 14:17:56 INFO - Running main action method: populate_webroot 14:17:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:17:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:17:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:17:56 INFO - '--tppagecycles', 14:17:56 INFO - '7'], 14:17:56 INFO - 'tests': ['tsvgm']}}, 14:17:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:17:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:17:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:17:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:17:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:17:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:17:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:56 INFO - 'talos_options': ['--disable-e10s'], 14:17:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:17:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:17:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:56 INFO - 'talos_options': ['--disable-e10s'], 14:17:56 INFO - 'tests': ['damp', 'tps']}, 14:17:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:56 INFO - 'tests': ['damp', 'tps']}, 14:17:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:17:56 INFO - 'tests': ['dromaeo_dom']}, 14:17:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:17:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:17:56 INFO - 'tests': ['a11yr', 14:17:56 INFO - 'ts_paint', 14:17:56 INFO - 'tpaint', 14:17:56 INFO - 'sessionrestore', 14:17:56 INFO - 'sessionrestore_no_auto_restore', 14:17:56 INFO - 'tabpaint']}, 14:17:56 INFO - 'other-e10s': {'tests': ['a11yr', 14:17:56 INFO - 'ts_paint', 14:17:56 INFO - 'tpaint', 14:17:56 INFO - 'sessionrestore', 14:17:56 INFO - 'sessionrestore_no_auto_restore', 14:17:56 INFO - 'tabpaint']}, 14:17:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:17:56 INFO - 'ts_paint', 14:17:56 INFO - 'tpaint', 14:17:56 INFO - 'sessionrestore', 14:17:56 INFO - 'sessionrestore_no_auto_restore', 14:17:56 INFO - 'tabpaint']}, 14:17:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:17:56 INFO - 'ts_paint', 14:17:56 INFO - 'tpaint', 14:17:56 INFO - 'sessionrestore', 14:17:56 INFO - 'sessionrestore_no_auto_restore', 14:17:56 INFO - 'tabpaint']}, 14:17:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:17:56 INFO - 'tests': ['a11yr', 14:17:56 INFO - 'ts_paint', 14:17:56 INFO - 'tpaint', 14:17:56 INFO - 'sessionrestore', 14:17:56 INFO - 'sessionrestore_no_auto_restore', 14:17:56 INFO - 'tabpaint']}, 14:17:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:17:56 INFO - 'tests': ['a11yr', 14:17:56 INFO - 'ts_paint', 14:17:56 INFO - 'tpaint', 14:17:56 INFO - 'sessionrestore', 14:17:56 INFO - 'sessionrestore_no_auto_restore', 14:17:56 INFO - 'tabpaint']}, 14:17:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:17:56 INFO - 'tests': ['tsvgx', 14:17:56 INFO - 'tsvgr_opacity', 14:17:56 INFO - 'tart', 14:17:56 INFO - 'tscrollx', 14:17:56 INFO - 'cart']}, 14:17:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:17:56 INFO - 'tsvgr_opacity', 14:17:56 INFO - 'tart', 14:17:56 INFO - 'tscrollx', 14:17:56 INFO - 'cart']}, 14:17:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:56 INFO - 'talos_options': ['--disable-e10s'], 14:17:56 INFO - 'tests': ['tp5o']}, 14:17:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:56 INFO - 'tests': ['tp5o']}, 14:17:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:56 INFO - 'talos_options': ['--disable-e10s', 14:17:56 INFO - '--xperf_path', 14:17:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:17:56 INFO - 'tests': ['tp5n']}, 14:17:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:56 INFO - 'talos_options': ['--xperf_path', 14:17:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:17:56 INFO - 'tests': ['tp5n']}}, 14:17:56 INFO - 'talos.zip': {'path': '', 14:17:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:17:56 INFO - Running post-action listener: _resource_record_post_action 14:17:56 INFO - ##### 14:17:56 INFO - ##### Finished populate-webroot step (success) 14:17:56 INFO - ##### 14:17:56 INFO - ##### 14:17:56 INFO - ##### Running create-virtualenv step. 14:17:56 INFO - ##### 14:17:56 INFO - Running pre-action listener: _resource_record_pre_action 14:17:56 INFO - Running main action method: create_virtualenv 14:17:56 INFO - Creating virtualenv /builds/slave/test/build/venv 14:17:56 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 14:17:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:17:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:17:56 INFO - Using real prefix '/tools/python27' 14:17:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:17:57 INFO - Installing distribute.............................................................................................................................................................................................done. 14:17:59 INFO - Installing pip.................done. 14:17:59 INFO - Return code: 0 14:17:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:17:59 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')]} 14:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:59 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')]} 14:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:59 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 0x1085dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78cb5000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108481d30>, '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 0x1082a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108478b20>, '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.fHASSF1ENJ/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.cS18ClHoSw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:17:59 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 14:17:59 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 14:17:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fHASSF1ENJ/Render', 14:17:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:59 INFO - 'HOME': '/Users/cltbld', 14:17:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:59 INFO - 'LOGNAME': 'cltbld', 14:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:59 INFO - 'MOZ_NO_REMOTE': '1', 14:17:59 INFO - 'NO_EM_RESTART': '1', 14:17:59 INFO - 'PAGER': '/bin/cat', 14:17:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:59 INFO - 'PWD': '/builds/slave/test', 14:17:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:59 INFO - 'SHELL': '/bin/bash', 14:17:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cS18ClHoSw/Listeners', 14:17:59 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:17:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:59 INFO - 'USER': 'cltbld', 14:17:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:59 INFO - 'XPC_FLAGS': '0x0', 14:17:59 INFO - 'XPC_SERVICE_NAME': '0', 14:17:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:00 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 14:18:00 INFO - Cleaning up... 14:18:00 INFO - Return code: 0 14:18:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:18:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1085dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78cb5000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108481d30>, '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 0x1082a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108478b20>, '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.fHASSF1ENJ/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.cS18ClHoSw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:18:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:18:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:18:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fHASSF1ENJ/Render', 14:18:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:00 INFO - 'HOME': '/Users/cltbld', 14:18:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:00 INFO - 'LOGNAME': 'cltbld', 14:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:00 INFO - 'MOZ_NO_REMOTE': '1', 14:18:00 INFO - 'NO_EM_RESTART': '1', 14:18:00 INFO - 'PAGER': '/bin/cat', 14:18:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:00 INFO - 'PWD': '/builds/slave/test', 14:18:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:00 INFO - 'SHELL': '/bin/bash', 14:18:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cS18ClHoSw/Listeners', 14:18:00 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:18:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:00 INFO - 'USER': 'cltbld', 14:18:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:00 INFO - 'XPC_FLAGS': '0x0', 14:18:00 INFO - 'XPC_SERVICE_NAME': '0', 14:18:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:00 INFO - Downloading/unpacking psutil>=3.1.1 14:18:00 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 14:18:00 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 14:18:00 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 14:18:00 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 14:18:00 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 14:18:00 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 14:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:18:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:03 INFO - Installing collected packages: psutil 14:18:03 INFO - Running setup.py install for psutil 14:18:03 INFO - building 'psutil._psutil_osx' extension 14:18:03 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 14:18:04 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 14:18:04 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 14:18:04 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 14:18:04 INFO - building 'psutil._psutil_posix' extension 14:18:04 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 14:18:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:18:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:18:04 INFO - ^ 14:18:04 INFO - 1 warning generated. 14:18:04 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 14:18:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:04 INFO - Successfully installed psutil 14:18:04 INFO - Cleaning up... 14:18:04 INFO - Return code: 0 14:18:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1085dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78cb5000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108481d30>, '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 0x1082a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108478b20>, '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.fHASSF1ENJ/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.cS18ClHoSw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:18:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:18:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fHASSF1ENJ/Render', 14:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:04 INFO - 'HOME': '/Users/cltbld', 14:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:04 INFO - 'LOGNAME': 'cltbld', 14:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:04 INFO - 'MOZ_NO_REMOTE': '1', 14:18:04 INFO - 'NO_EM_RESTART': '1', 14:18:04 INFO - 'PAGER': '/bin/cat', 14:18:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:04 INFO - 'PWD': '/builds/slave/test', 14:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:04 INFO - 'SHELL': '/bin/bash', 14:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cS18ClHoSw/Listeners', 14:18:04 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:04 INFO - 'USER': 'cltbld', 14:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:04 INFO - 'XPC_FLAGS': '0x0', 14:18:04 INFO - 'XPC_SERVICE_NAME': '0', 14:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:18:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:18:07 INFO - Installing collected packages: mozsystemmonitor 14:18:07 INFO - Running setup.py install for mozsystemmonitor 14:18:07 INFO - Successfully installed mozsystemmonitor 14:18:07 INFO - Cleaning up... 14:18:07 INFO - Return code: 0 14:18:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1085dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78cb5000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108481d30>, '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 0x1082a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108478b20>, '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.fHASSF1ENJ/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.cS18ClHoSw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:18:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fHASSF1ENJ/Render', 14:18:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:07 INFO - 'HOME': '/Users/cltbld', 14:18:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:07 INFO - 'LOGNAME': 'cltbld', 14:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:07 INFO - 'MOZ_NO_REMOTE': '1', 14:18:07 INFO - 'NO_EM_RESTART': '1', 14:18:07 INFO - 'PAGER': '/bin/cat', 14:18:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:07 INFO - 'PWD': '/builds/slave/test', 14:18:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:07 INFO - 'SHELL': '/bin/bash', 14:18:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cS18ClHoSw/Listeners', 14:18:07 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:18:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:07 INFO - 'USER': 'cltbld', 14:18:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:07 INFO - 'XPC_FLAGS': '0x0', 14:18:07 INFO - 'XPC_SERVICE_NAME': '0', 14:18:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:07 INFO - Downloading/unpacking blobuploader==1.2.4 14:18:07 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 14:18:07 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 14:18:07 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 14:18:07 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 14:18:07 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 14:18:07 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 14:18:10 INFO - Downloading blobuploader-1.2.4.tar.gz 14:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:18:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:18:10 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 14:18:10 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 14:18:10 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 14:18:10 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 14:18:10 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 14:18:10 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 14:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:18:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:11 INFO - Downloading docopt-0.6.1.tar.gz 14:18:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:18:11 INFO - Installing collected packages: blobuploader, requests, docopt 14:18:11 INFO - Running setup.py install for blobuploader 14:18:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:18:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:18:11 INFO - Running setup.py install for requests 14:18:12 INFO - Running setup.py install for docopt 14:18:12 INFO - Successfully installed blobuploader requests docopt 14:18:12 INFO - Cleaning up... 14:18:12 INFO - Return code: 0 14:18:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18: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')]} 14:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18: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')]} 14:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:12 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 0x1085dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78cb5000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108481d30>, '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 0x1082a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108478b20>, '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.fHASSF1ENJ/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.cS18ClHoSw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:18:12 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 14:18:12 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 14:18:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fHASSF1ENJ/Render', 14:18:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:12 INFO - 'HOME': '/Users/cltbld', 14:18:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:12 INFO - 'LOGNAME': 'cltbld', 14:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:12 INFO - 'MOZ_NO_REMOTE': '1', 14:18:12 INFO - 'NO_EM_RESTART': '1', 14:18:12 INFO - 'PAGER': '/bin/cat', 14:18:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:12 INFO - 'PWD': '/builds/slave/test', 14:18:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:12 INFO - 'SHELL': '/bin/bash', 14:18:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cS18ClHoSw/Listeners', 14:18:12 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:18:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:12 INFO - 'USER': 'cltbld', 14:18:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:12 INFO - 'XPC_FLAGS': '0x0', 14:18:12 INFO - 'XPC_SERVICE_NAME': '0', 14:18:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:18:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-js7D3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:18:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-QNEmZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:18:12 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-qZNULv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:18:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-T4gDoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:18:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-x68lTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:18:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-LcuHDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:18:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-5yRRvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:18:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-ZW7qG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:18:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-f6ssj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:18:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-93yVTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:18:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-25Sqku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:18:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-eGFVzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:18:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-9MQgCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:18:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-bvy42K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-LCeOZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:18:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-SK4BQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:18:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-zZRBhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:18:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:18:14 INFO - Running setup.py install for manifestparser 14:18:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:18:14 INFO - Running setup.py install for mozcrash 14:18:14 INFO - Running setup.py install for mozdebug 14:18:14 INFO - Running setup.py install for mozdevice 14:18:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:18:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:18:15 INFO - Running setup.py install for mozfile 14:18:15 INFO - Running setup.py install for mozhttpd 14:18:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:18:15 INFO - Running setup.py install for mozinfo 14:18:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:18:15 INFO - Running setup.py install for mozInstall 14:18:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:18:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:18:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:18:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:18:15 INFO - Running setup.py install for mozleak 14:18:15 INFO - Running setup.py install for mozlog 14:18:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:18:15 INFO - Running setup.py install for moznetwork 14:18:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:18:16 INFO - Running setup.py install for mozprocess 14:18:16 INFO - Running setup.py install for mozprofile 14:18:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:18:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:18:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:18:16 INFO - Running setup.py install for mozrunner 14:18:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:18:16 INFO - Running setup.py install for mozscreenshot 14:18:16 INFO - Running setup.py install for moztest 14:18:16 INFO - Running setup.py install for mozversion 14:18:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:18:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:18:17 INFO - Cleaning up... 14:18:17 INFO - Return code: 0 14:18:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:17 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 0x1085dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78cb5000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108481d30>, '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 0x1082a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108478b20>, '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.fHASSF1ENJ/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.cS18ClHoSw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:18:17 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 14:18:17 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 14:18:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fHASSF1ENJ/Render', 14:18:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:17 INFO - 'HOME': '/Users/cltbld', 14:18:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:17 INFO - 'LOGNAME': 'cltbld', 14:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:17 INFO - 'MOZ_NO_REMOTE': '1', 14:18:17 INFO - 'NO_EM_RESTART': '1', 14:18:17 INFO - 'PAGER': '/bin/cat', 14:18:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:17 INFO - 'PWD': '/builds/slave/test', 14:18:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:17 INFO - 'SHELL': '/bin/bash', 14:18:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cS18ClHoSw/Listeners', 14:18:17 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:18:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:17 INFO - 'USER': 'cltbld', 14:18:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:17 INFO - 'XPC_FLAGS': '0x0', 14:18:17 INFO - 'XPC_SERVICE_NAME': '0', 14:18:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:18:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-al6jWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:18:17 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)) 14:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:18:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-9ZApNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:18:17 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)) 14:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:18:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-hbNf8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:18:17 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)) 14:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:18:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-3fWqmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:18:17 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)) 14:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:18:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-kdaYnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:18:17 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)) 14:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:18:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-_UpRUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:18:17 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)) 14:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:18:17 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-okf1Ts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:18:18 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)) 14:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:18:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-kkA2GC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:18:18 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)) 14:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:18:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-UBY8wj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:18:18 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)) 14:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:18:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-6jdxJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:18:18 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)) 14:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:18:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-0mFSHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:18:18 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)) 14:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:18:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-91AVzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:18:18 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)) 14:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:18:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-_Ti0DF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:18:18 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)) 14:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:18:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-33z6c8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:18:18 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)) 14:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-wwhGMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:18 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)) 14:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:18:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-3QrZEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:18:19 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)) 14:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:18:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-q4iQvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:18:19 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)) 14:18:19 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)) 14:18:19 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)) 14:18:19 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)) 14:18:19 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)) 14:18:19 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)) 14:18:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:21 INFO - Downloading blessings-1.6.tar.gz 14:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:18:21 INFO - Installing collected packages: blessings 14:18:21 INFO - Running setup.py install for blessings 14:18:21 INFO - Successfully installed blessings 14:18:21 INFO - Cleaning up... 14:18:21 INFO - Return code: 0 14:18:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:22 INFO - Reading from file tmpfile_stdout 14:18:22 INFO - Current package versions: 14:18:22 INFO - blessings == 1.6 14:18:22 INFO - blobuploader == 1.2.4 14:18:22 INFO - docopt == 0.6.1 14:18:22 INFO - manifestparser == 1.1 14:18:22 INFO - mozInstall == 1.12 14:18:22 INFO - mozcrash == 0.17 14:18:22 INFO - mozdebug == 0.1 14:18:22 INFO - mozdevice == 0.48 14:18:22 INFO - mozfile == 1.2 14:18:22 INFO - mozhttpd == 0.7 14:18:22 INFO - mozinfo == 0.9 14:18:22 INFO - mozleak == 0.1 14:18:22 INFO - mozlog == 3.2 14:18:22 INFO - moznetwork == 0.27 14:18:22 INFO - mozprocess == 0.22 14:18:22 INFO - mozprofile == 0.28 14:18:22 INFO - mozrunner == 6.11 14:18:22 INFO - mozscreenshot == 0.1 14:18:22 INFO - mozsystemmonitor == 0.3 14:18:22 INFO - moztest == 0.7 14:18:22 INFO - mozversion == 1.4 14:18:22 INFO - psutil == 3.1.1 14:18:22 INFO - requests == 1.2.3 14:18:22 INFO - wsgiref == 0.1.2 14:18:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:22 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 0x1085dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78cb5000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108481d30>, '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 0x1082a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108478b20>, '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.fHASSF1ENJ/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.cS18ClHoSw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:18:22 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 14:18:22 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 14:18:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fHASSF1ENJ/Render', 14:18:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:22 INFO - 'HOME': '/Users/cltbld', 14:18:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:22 INFO - 'LOGNAME': 'cltbld', 14:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:22 INFO - 'MOZ_NO_REMOTE': '1', 14:18:22 INFO - 'NO_EM_RESTART': '1', 14:18:22 INFO - 'PAGER': '/bin/cat', 14:18:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:22 INFO - 'PWD': '/builds/slave/test', 14:18:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:22 INFO - 'SHELL': '/bin/bash', 14:18:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cS18ClHoSw/Listeners', 14:18:22 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:18:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:22 INFO - 'USER': 'cltbld', 14:18:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:22 INFO - 'XPC_FLAGS': '0x0', 14:18:22 INFO - 'XPC_SERVICE_NAME': '0', 14:18:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:22 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)) 14:18:22 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)) 14:18:22 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)) 14:18:22 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)) 14:18:22 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)) 14:18:22 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)) 14:18:22 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)) 14:18:22 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)) 14:18:22 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)) 14:18:22 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)) 14:18:22 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)) 14:18:22 INFO - Cleaning up... 14:18:22 INFO - Return code: 0 14:18:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1085dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78cb5000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108481d30>, '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 0x1082a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108478b20>, '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.fHASSF1ENJ/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.cS18ClHoSw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:18:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:18:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:18:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fHASSF1ENJ/Render', 14:18:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:22 INFO - 'HOME': '/Users/cltbld', 14:18:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:22 INFO - 'LOGNAME': 'cltbld', 14:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:22 INFO - 'MOZ_NO_REMOTE': '1', 14:18:22 INFO - 'NO_EM_RESTART': '1', 14:18:22 INFO - 'PAGER': '/bin/cat', 14:18:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:22 INFO - 'PWD': '/builds/slave/test', 14:18:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:22 INFO - 'SHELL': '/bin/bash', 14:18:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cS18ClHoSw/Listeners', 14:18:22 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:18:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:22 INFO - 'USER': 'cltbld', 14:18:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:22 INFO - 'XPC_FLAGS': '0x0', 14:18:22 INFO - 'XPC_SERVICE_NAME': '0', 14:18:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:22 INFO - Downloading/unpacking jsonschema 14:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:18:25 INFO - Downloading/unpacking functools32 (from jsonschema) 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18:25 INFO - Downloading functools32-3.2.3-2.tar.gz 14:18:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:18:25 INFO - warning: no files found matching '*.txt' 14:18:25 INFO - no previously-included directories found matching 'build' 14:18:25 INFO - no previously-included directories found matching 'dist' 14:18:25 INFO - no previously-included directories found matching '.git*' 14:18:25 INFO - Installing collected packages: jsonschema, functools32 14:18:25 INFO - Running setup.py install for functools32 14:18:25 INFO - warning: no files found matching '*.txt' 14:18:25 INFO - no previously-included directories found matching 'build' 14:18:25 INFO - no previously-included directories found matching 'dist' 14:18:25 INFO - no previously-included directories found matching '.git*' 14:18:25 INFO - Successfully installed jsonschema functools32 14:18:25 INFO - Cleaning up... 14:18:26 INFO - Return code: 0 14:18:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:26 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 0x1085dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78cb5000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108481d30>, '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 0x1082a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108478b20>, '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.fHASSF1ENJ/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.cS18ClHoSw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:18:26 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 14:18:26 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 14:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fHASSF1ENJ/Render', 14:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:26 INFO - 'HOME': '/Users/cltbld', 14:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:26 INFO - 'LOGNAME': 'cltbld', 14:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:26 INFO - 'MOZ_NO_REMOTE': '1', 14:18:26 INFO - 'NO_EM_RESTART': '1', 14:18:26 INFO - 'PAGER': '/bin/cat', 14:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:26 INFO - 'PWD': '/builds/slave/test', 14:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:26 INFO - 'SHELL': '/bin/bash', 14:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cS18ClHoSw/Listeners', 14:18:26 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:26 INFO - 'USER': 'cltbld', 14:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:26 INFO - 'XPC_FLAGS': '0x0', 14:18:26 INFO - 'XPC_SERVICE_NAME': '0', 14:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:26 INFO - Downloading/unpacking flake8 14:18:26 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 14:18:26 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 14:18:26 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 14:18:26 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 14:18:26 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 14:18:26 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 14:18:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:18:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:18:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:18:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:18:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:18:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:18:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:18:29 INFO - no previously-included directories found matching 'docs/_build' 14:18:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:18:30 INFO - Running setup.py install for pep8 14:18:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:18:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:18:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:18:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:18:30 INFO - no previously-included directories found matching 'docs/_build' 14:18:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:18:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:18:30 INFO - Cleaning up... 14:18:30 INFO - Return code: 0 14:18:30 INFO - Running post-action listener: _resource_record_post_action 14:18:30 INFO - Running post-action listener: _start_resource_monitoring 14:18:30 INFO - Starting resource monitoring. 14:18:30 INFO - ##### 14:18:30 INFO - ##### Finished create-virtualenv step (success) 14:18:30 INFO - ##### 14:18:30 INFO - ##### 14:18:30 INFO - ##### Running install step. 14:18:30 INFO - ##### 14:18:30 INFO - Running pre-action listener: _resource_record_pre_action 14:18:30 INFO - Running main action method: install 14:18:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:30 INFO - Reading from file tmpfile_stdout 14:18:30 INFO - Detecting whether we're running mozinstall >=1.0... 14:18:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:18:30 INFO - Reading from file tmpfile_stdout 14:18:30 INFO - Output received: 14:18:30 INFO - Usage: mozinstall [options] installer 14:18:30 INFO - Options: 14:18:30 INFO - -h, --help show this help message and exit 14:18:30 INFO - -d DEST, --destination=DEST 14:18:30 INFO - Directory to install application into. [default: 14:18:30 INFO - "/builds/slave/test"] 14:18:30 INFO - --app=APP Application being installed. [default: firefox] 14:18:30 INFO - mkdir: /builds/slave/test/build/application 14:18:30 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'] 14:18:30 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 14:19:04 INFO - Reading from file tmpfile_stdout 14:19:04 INFO - Output received: 14:19:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:19:04 INFO - Running post-action listener: _resource_record_post_action 14:19:04 INFO - ##### 14:19:04 INFO - ##### Finished install step (success) 14:19:04 INFO - ##### 14:19:04 INFO - ##### 14:19:04 INFO - ##### Running run-tests step. 14:19:04 INFO - ##### 14:19:04 INFO - Running pre-action listener: _resource_record_pre_action 14:19:04 INFO - Running main action method: run_tests 14:19:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:19:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:19:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:19:04 INFO - 2016-05-31 14:19:04.520 screenresolution[1922:13220] starting screenresolution argv=screenresolution get 14:19:04 INFO - 2016-05-31 14:19:04.523 screenresolution[1922:13220] Display 0: 1600x1200x32@60 14:19:04 INFO - 2016-05-31 14:19:04.528 screenresolution[1923:13222] starting screenresolution argv=screenresolution list 14:19:04 INFO - Available Modes on Display 0 14:19:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:19:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:19:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:19:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:19:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:19:04 INFO - Intel Iris: 14:19:04 INFO - Chipset Model: Intel Iris 14:19:04 INFO - Type: GPU 14:19:04 INFO - Bus: Built-In 14:19:04 INFO - VRAM (Dynamic, Max): 1536 MB 14:19:04 INFO - Vendor: Intel (0x8086) 14:19:04 INFO - Device ID: 0x0a2e 14:19:04 INFO - Revision ID: 0x0009 14:19:04 INFO - Displays: 14:19:04 INFO - Display: 14:19:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:19:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:19:04 INFO - Display Serial Number: 1600x1200 60 14:19:04 INFO - Main Display: Yes 14:19:04 INFO - Mirror: Off 14:19:04 INFO - Online: Yes 14:19:04 INFO - Rotation: Supported 14:19:04 INFO - Return code: 0 14:19:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:19:04 INFO - Python 2.7.3 14:19:04 INFO - Return code: 0 14:19:04 INFO - grabbing minidump binary from tooltool 14:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:04 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108481d30>, '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 0x1082a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108478b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:19:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:19:04 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:19:04 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:19:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:19:04 INFO - Return code: 0 14:19:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:19:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:19:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:19:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:19:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:19:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:19:06 INFO - Return code: 0 14:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0169 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fHASSF1ENJ/Render', 14:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:06 INFO - 'HOME': '/Users/cltbld', 14:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:06 INFO - 'LOGNAME': 'cltbld', 14:19:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:19:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:06 INFO - 'MOZ_NO_REMOTE': '1', 14:19:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:19:06 INFO - 'NO_EM_RESTART': '1', 14:19:06 INFO - 'PAGER': '/bin/cat', 14:19:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:06 INFO - 'PWD': '/builds/slave/test', 14:19:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:06 INFO - 'SHELL': '/bin/bash', 14:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cS18ClHoSw/Listeners', 14:19:06 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 14:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:06 INFO - 'USER': 'cltbld', 14:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:06 INFO - 'XPC_FLAGS': '0x0', 14:19:06 INFO - 'XPC_SERVICE_NAME': '0', 14:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:19:06 INFO - mozversion application_buildid: 20160531122540 14:19:06 INFO - mozversion application_changeset: 23a96762bc11fdbd26f032d3fcbcbf7417797acb 14:19:06 INFO - mozversion application_display_name: Nightly 14:19:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:19:06 INFO - mozversion application_name: Firefox 14:19:06 INFO - mozversion application_remotingname: firefox 14:19:06 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 14:19:06 INFO - mozversion application_vendor: Mozilla 14:19:06 INFO - mozversion application_version: 49.0a1 14:19:06 INFO - mozversion platform_buildid: 20160531122540 14:19:06 INFO - mozversion platform_changeset: 23a96762bc11fdbd26f032d3fcbcbf7417797acb 14:19:06 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 14:19:06 INFO - mozversion platform_version: 49.0a1 14:19:06 INFO - using testdate: 1464729546 14:19:06 INFO - actual date: 1464729546 14:19:06 INFO - starting webserver on 'localhost:50813' 14:19:06 INFO - SUITE-START | Running 2 tests 14:19:06 INFO - TEST-START | dromaeo_css 14:19:06 INFO - operating with platform_type : mac_ 14:19:06 INFO - Initialising browser for dromaeo_css test... 14:19:06 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpAcj64S/profile http://localhost:50813/getInfo.html) 14:19:08 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 14:19:08 INFO - PROCESS | 1941 | colorDepth:24 14:19:08 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 14:19:08 INFO - PROCESS | 1941 | __metrics 14:19:08 INFO - PROCESS | 1941 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:19:08 ERROR - PROCESS | 1941 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:19:09 INFO - TEST-INFO | 1941: exit 0 14:19:09 INFO - Browser initialized. 14:19:09 INFO - Running cycle 1/1 for dromaeo_css test... 14:19:09 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpAcj64S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:21 INFO - PROCESS | 1943 | RSS: Main: 241860608 14:19:21 INFO - PROCESS | 1943 | 14:20:12 INFO - PROCESS | 1943 | 1464729612283 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] 14:22:24 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50813/tests/dromaeo/cssquery-ext.html) 14:22:25 INFO - PROCESS | 1943 | RSS: Main: 372846592 14:22:25 INFO - PROCESS | 1943 | 14:25:29 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-ext.html (next: http://localhost:50813/tests/dromaeo/cssquery-jquery.html) 14:25:29 INFO - PROCESS | 1943 | RSS: Main: 381890560 14:25:29 INFO - PROCESS | 1943 | 14:28:31 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50813/tests/dromaeo/cssquery-mootools.html) 14:28:32 INFO - PROCESS | 1943 | RSS: Main: 375025664 14:28:32 INFO - PROCESS | 1943 | 14:31:34 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50813/tests/dromaeo/cssquery-prototype.html) 14:31:35 INFO - PROCESS | 1943 | RSS: Main: 369033216 14:31:35 INFO - PROCESS | 1943 | 14:34:38 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50813/tests/dromaeo/cssquery-yui.html) 14:34:39 INFO - PROCESS | 1943 | RSS: Main: 337412096 14:34:39 INFO - PROCESS | 1943 | 14:37:42 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50813/tests/dromaeo/cssquery-yui.html (next: http://localhost:50813/tests/dromaeo/cssquery-dojo.html) 14:37:42 INFO - PROCESS | 1943 | RSS: Main: 378552320 14:37:42 INFO - PROCESS | 1943 | 14:37:42 INFO - PROCESS | 1943 | __start_tp_report 14:37:42 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 14:37:42 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 14:37:42 INFO - PROCESS | 1943 | |i|pagename|runs| 14:37:42 INFO - PROCESS | 1943 | |0;dojo.html;3454.7735553878356;3461.2731326421454;3465.2203254267783;3487.511748355229;3527.259275552134;5640.689762063085;5703.914441283378;5737.598368114233;5758.280214973129;5790.797322093727;5713.885722285552;5784.074548072309;5793.420657934204;5799.014167591509;5808.506276966463;5122.052420302256;5192.740362981855;5249.527542521172;5297.629165958385;5340.839774806743;4621.930671039937;5338.065838478251;5338.225957236198;5341.4658534146665;5349.304590403242;5226;5251.2385704072885;5275.709835959021;5280.894382112356;5305.098133317345;5358.410574836765;5369.9463005369835;5371.838844834661;5374.973125134388;5425.728713564306;5115.61632877532;5122.974385128051;5126.359205099363;5242.292290540767;5262.973720124539;5509.614326997112;5583.413741557119;5589.161625756128;5603.887922241533;5653.57598180134;6229.87540249197;6257.436830685215;6264.373562643745;6266.968665156691;6270.278917924452;5049.64652474327;6157.969210153965;6204.131421600934;6236.064590311489;6250.281217659982;5832.766689332421;5845.824625261249;5861.003629383015;5878.647281163144;5904.143899134601;6238.189035425366;6246.968765156145;6261.686915654199;6266.091416744638;6278.591891527036;6084.969575152096;6131.359149582395;6163.476104531165;6166.630002199882;6188.752449902044;6163.229596300463;6176.7838125665385;6223.190985171989;6241.937580624136;6249.375062493805;6224.657643829542;6251.843703907348;6269.279032911182;6278.372162783777;6298.275698294663;6145.231846019247;6237.407446292595;6245.156903817927;6253.249610046823;6253.437190652863;4894.902101957941;4957.281194226817;5017.222330538772;5025.472325405853;5053.8736531587;4510.503844577094;4527.4340707411575;4547.3179023146795;4561.908761824745;4563.748993805372;5639.069553523623;5735.799247026417;5767.798127065533;5809.157672137601;5818.069108942634;6157.96921015392;6190.364675459209;6220.471259942956;6226.782062627786;6243.968780156161;5751;5774.220480235114;5780.132980053026;5797.768089276466;5834.241548598655;5329.173978033411;5386.084365657847;5389.541888939484;5442;5442.428545002717;5231.607629427808;6222.533310001859;6232.8130156095385;6243.43809057178;6260.7495700171485;6128.3503509891225;6186.81439556814;6233.937660623517;6244.46922011616;6253.218347706536;6365.204349456318;6384.680765961776;6387.329330420424;6394.360563943568;6420.871582568416;6125.050617154348;6139.277491147187;6239.875202496016;6246.46905013079;6273.9372606273355;6177.382261773787;6219.875602487835;6219.942609756262;6262.906056409249;6275.37246275387;6098.75604975796;6148.385161483816;6163.075538669235;6173.351798061139;6174.289956654862;5991.340952495135;6005.909911351246;6023.759049638141;6164.8767024658355;6176.845578860564;5938.198343223609;5944.197533332945;5959.612625179349;5966.373530779206;5981.0729336952845;6161.44546990773;6194.473469755121;6203.472704820141;6205.348438414082;6208.751649934133;6022.909656355246;6026.096085587197;6044.607100538451;6059.272887253558;6099.725512352044 14:37:42 INFO - PROCESS | 1943 | |1;ext.html;479.7864950097206;486.24146331722505;489.5007092765379;493.4079105073912;498.5313805023278;31459.842700786492;33387.33225335492;33410;33461.83269083651;33477.16307092321;44209.778951105196;44967;44985.7750711246;47706.28440573394;47775.761121194344;9666.565004574812;9842.50787460624;9852;9869.358491698016;9872.35829671069;8773.517456539888;9048.909510904905;9650.758731031745;9786.5596048178;9806.313558050939;11722.120840936921;11979;11989.40052997351;12204;12241.63275101744;10475.952404759539;12014.939925300405;12028.158028937978;12154.453049612788;12172.81740773889;8618.56907154646;10051.597936082548;11598.652040438801;11973.640790776291;11997.520099196021;37522.24955500902;39321.606783932366;42286.57713422857;42313.57686423158;42406.57593424057;3517.314123745871;3683.079230192452;3887.455756194135;3952.446657467957;4223.97888010558;26723.599146012824;31627.36745265082;31927.361452770816;32664.83667581647;33508.1622959428;3208.4866421372703;3382.746294027958;3383.695467407945;3393.8133402662843;3402.0644322811427;502187;502651;502959;504028;504904.4754776239;65525.34474655193;65594;66145.33854661488;66227.66886165539;66279.66860165668;70238;70328;70494;70515;70540.64729676319;16911.154442277835;17157.485275441755;17178.656426871392;17179.656406871793;17198.1400929953;12982;13039.543615973585;13064.869351306364;13065.738685226432;13068.215907045418;16507.752383714254;16848.410305639245;18408.907955460138;18795.624087518172;18826.52933676669;6498.3826536478955;6571.507136964747;6599.096397585047;6606.669666516655;6637.785006520894;919.4988731141544;979.9706008819746;1037.8131936251548;1079.994600026995;1160.399748299292;30684.67211480938;31128;31248.21879453032;31278.530822037686;32498.862539810994;1068.1561566362486;1086.0280049355783;1104.86741591008;1176.3588844079998;1182.2197349749124;16308.592285192964;16913.069781162154;17037.892536985055;17452.47642570725;17494;592192.039039802;619593;619864;623420;628162.1551353467;49792.75103624531;60521.09218361728;61396.69301653552;61622;65706.67146664236;1390.7079513302117;1403.8736513713816;1408.1621435245806;1409.1545072956144;1425.173399428329;31247.062588122393;31990.84004579962;31999.840000799846;32716.83641581777;32939.83530082334;54429.72785136049;55029.174562380795;55177.89644207174;55262.17106743321;55309;537149;541414;541572;543388;545117;27112.457750845268;27153.321166970192;27381.58927616127;27411.86294068517;29282.267943301587;50465.738356541384;50884.94470655753;51004.489955099976;51389.22916156335;51501.48498515117;41034.38448423216;41202.79398603108;41775.216023968875;42542.7237182885;42711;40643;40877.38683919803;41207;41729.79135104305;42080;38880;39691.00772480711;40105.79947100246;40184.19631607293;40192.59807401888;33206.335873282886;35573.644263557035;35764.105897352776;35926;36020.63979360173;26000.219993400227;26070.34824129412;26235;26451.735482644926;26468.80890359977 14:37:42 INFO - PROCESS | 1943 | |2;jquery.html;386.111942532176;389.2021356219749;391.2507548045493;391.8393458681943;416.8749375187443;19708.014599270035;20778.584428311424;21000.579988400223;21017;21983.780162198374;20443;20993.265235716754;21257.362279131612;21307.25424610139;21315.786842131572;4397.208502469554;4835.734034628103;5193.272941788134;5204.42751297357;5208.114620514502;4538.273876179812;5211.557017653504;5228.398734145565;5889.263842019747;5898.1447690084915;5816.854578635547;5844.678542680151;5846.181534585162;5855.941440585582;5858.121281807742;5268.157094864803;5271.551918086967;5276.419593844674;5351.866203344908;5837.708114594275;5124.103281925648;5125.154349532323;5151.356080489939;5152.943646552448;5160.14857548504;13484.325783710874;15155.318010689436;24307.878460607582;25911.352216194748;25918.611220831703;2061.865978711379;2102.978970210309;2264.3772962435296;2274.886255687209;2409.265174121892;10232.693019209435;10264.384136951807;10269.691909242734;10434.321769084985;10501.212409069274;1869.2336142181641;1900.4488698277482;1916.7316575679415;1949.8635095543318;1956.9412917612494;110330;111542.44228778804;117815;119018;119679.20481192789;11497.367644779617;11622.53509859613;11662.475188616532;11673.941630291964;11797.764044719057;14710.852891470948;14804.777928331083;14836.035657682216;14857.777133343008;14875.70248595022;8476.872846907301;8663.263622592025;8692.174243446863;8745.125487451203;8803.515806630568;8567.357448191411;8596.742097737078;8628.52543110135;8753.080926502753;8760.51817150063;3967.960320396759;4370.409994650745;4551.749653769075;4556.703814252063;4619.69971951822;1837.5850595041743;1923.1826473748602;1947.8441724661993;1948.6005368899341;1951.6389467948475;723.8408930658187;818.8567000774841;906.895706993691;961.1301771896445;1005.527402121002;19584.31454899072;20416;20423.591528169352;20612.17551297961;20911.79088209129;970.6214576315243;987.4174237199943;1015.9339642923186;1041.317936751429;1045.6131231444263;10418.635347762793;10930.781384372269;10955.287906286067;10966.780664386668;10985.6155034575;499925;504560;505991;591886;593979;124044;133746;143109.56890431172;145925;146535;980.2158273381267;988.9554970026222;1019.4444028004755;1031.3244824639874;1033.5710680067687;16109.919450402673;16485.83514164843;17996.820031799514;18437.63124737471;18519.629607407507;54660.45339546713;55859;55890.44109559015;56059;56380.7180964109;81686.18313816786;81827;81946;82710;83945;6320.241571011508;7630.2751238632245;7804.804879878048;7959.283664470226;8136.83726325482;10239.283250172417;10437.32157409766;11700.531978721096;12310.322932238812;12364.690882728004;10393;10500.632477863239;10523.210759193244;10602.151827853908;10773.353598784099;10055;10061.934918548624;10105.898941010495;10396.22028347892;10514.94742526313;11052.226344155832;11256.887431125584;11293.661190163979;11344.489497972776;11409.828852567052;6319.9052014218905;6746.595204287759;7288.599127048064;7483.887741683988;8175.264226219669;9343.766405839908;10907.781844363228;10965.232433729563;11075.889241107487;11123.777524449304 14:37:42 INFO - PROCESS | 1943 | |3;mootools.html;1377.3664114507326;1525.8397868223835;1548.5919395340245;1786.3122697761366;1965.9410217693464;7914.406419518537;8868.645254189832;8871.955640221806;8875.334349923758;9266;8841.292696584274;9071.274298056156;9071.410358326704;9076.773080672987;9096.317776166781;2390.772876576731;2418.9516209675885;2421.8546887186735;2424.7211570669424;2425.2360506440414;2366.6510089249136;2391.557561851063;2411.6503107049466;2419.322589674885;2422.733499315074;2394.1381102803066;2404.4710163763943;2412.023130632088;2419.1049311754678;2419.6854408926815;2355.05797680929;2380.1907351500486;2390.474095698952;2392.2823153054187;2392.3062311929693;2286.4283929017747;2385.427497400629;2386.367612582667;2386.55848667997;2392.306231192952;6927.688254028581;7999.680012799481;8916.777080572972;9024.819503609957;9028.458292502472;4804.711717296974;4914.90170196594;5095.515925987025;5173.637845350827;5358.142697168473;3554.573451185874;3555.1645363339594;3660.963390366089;3667.7615954962844;3671.0638787109115;1066.0139371081718;1075.6772968109535;1084.203110713625;1092.6066616018227;1098.0666433531514;12012.819807702892;12318;12321.260724356567;12332.50669973209;12339.876601234053;4787.42550893888;4840.467548569654;4895.828645997444;4904.926426103611;4906.779194936236;9125.406848554956;10125;10460.895391046144;10466.947665261625;10515.791683746498;5497.0105381031;5510.035743744828;5511;5511.834644960658;5515.338159420895;4872.269159626014;4872.756362181876;4971.37857767779;5021.6735912165595;5036.622253330942;3854.9807250963568;3918.0988372674447;3975.0658595230097;3994.1013272013565;4017;1249.4377530111485;1276.9361531923366;1280.1678908709414;1286.948522059107;1291.799771035491;840.8906842110608;937.3906960475746;946.3517490519016;971.8823353143941;996.6412091647002;5870.853228669317;5901.704914754331;5939.316978547522;6072.51419886408;6092.45167934888;451.2869665927826;451.2892194793214;457.9633629309647;458.99082018359445;462.85188739603007;3959.40608908666;4001.2197621463533;4015.558288588253;4058.431819545266;4061.735987160825;10582.153427725763;10752.94623526893;10887.455627218607;11043.558257669603;11049.723756906142;9146.314026448043;10945.726356840983;10965.122790176767;10978.945105274423;11015.944920275348;8216.548089855116;8340.62467188978;8341.374396920255;8343.290820280345;8355.665773368997;5436.945630543644;5978.910316345346;6002.069679199731;6070.0591408331775;6089.756409743561;9703.417794932328;9767.9023209767;9768.316217864682;9780.559874805793;10254.436006019741;9857.901420985985;10332.690019299433;10366.06705396518;10371.325863818827;10376.118029967538;7583.127940286717;7632.465727399029;7702.614869256403;7853.685852565834;7944.086430060386;8668.869966950628;8728.039915609152;8754.037055923716;8765.561721913751;8777.122287771172;7639.618019098912;7715.652795624098;7775.961120194589;7850.882236766567;7899.328557072713;6579.78274019308;7493.737719180021;7621.199774023869;7770.92229077702;7772.300492955661;6808.93191068083;7201.927980720126;7235.674394652358;7240.782776516713;7254.96372518134;4358.411614432011;4963.553280204799;4970.204767237225;5110.71891045996;5191.247269146028;4202.348635961431;5107.897842043064;5162.716050617252;5263.894722105613;5321.654092484131 14:37:42 INFO - PROCESS | 1943 | |4;prototype.html;296.1352849678937;310.1038000179482;318.58265671969707;320.8588221182681;321.84390570573265;4850.46644869065;5518.296034942874;5518.972405137969;5548.750306236219;5595.38450770416;4668.638580222243;5301.893962120765;5467.507924286823;5490.286262785842;5493.368262649797;3812.370958791796;3959.2279505496367;3995.60043995601;4098.7005460604205;4213.7893105344765;3582.9788510274575;3626.9637303627023;4100.917981640366;4114.950687574665;4196.265653510639;3462.567179102612;4142.1922725068625;4151.979240103795;4170.47869016373;4196.838628106528;3244.5944256967878;3892.579208588859;3925.548561915388;4053.3312003519386;4161.4382058422025;3018.6830382809926;3400.370931377703;3407.5740532433447;3571;3595.209054008114;4589.770511474413;4910.410750709902;5213.53078222958;5220.8694782630355;5242.501962313574;4568.748718820463;4736.5026672199265;4746.477887432379;4786.880327991793;4809.004263230755;5080;5127.333446651912;5641.8589535261535;5702.800401985952;5729.341125770507;3434.295969326282;3975.8012099394914;3989.341758609826;4001.2197621463824;4035.5068624609085;5647.407022262644;5912.852178695524;5984.970075149597;6070.484008859296;6095.329513753483;3890.6498415142773;3933.370660694275;3937.2125574885135;3937.9803100984313;3947.644711975936;3402.6937575618313;3661.4690869823726;3972.205558888234;3989.501312335958;4009.258287216874;3957.6635985940943;4488.887777805516;4651.744154071558;4680.812767489262;4714.137915523349;4408.58118478744;5131.461196574306;5723.540497173194;5748.1031259684205;5768.278965129359;1191.9046476281876;1242.2732701369619;1367.4803574641574;1414.405949501212;1420.1975883625719;749.0748925077525;789.6446599030345;846.7671390367698;871.4771137317558;881.6510738569998;4982.3522942018035;5147.485251474823;5199.558037566773;5331.4401987790725;5377.919331210035;5028.371453568304;5544.7227638618715;5942.841145976579;5979.5515336349945;5995.730192141364;4664.626829853603;5154.871128221749;5177.585793136615;5331.333583302087;5387.952578020328;2571.929596674695;3104.2704964333366;3128.824243392958;3130.3895740330418;3141.6230052393403;5784.884302314014;6156.830202261556;6234.37656234382;6234.407731265523;6254.906176407358;5748.44194333526;5781.479666830005;6126.326104128542;6142.723577439026;6213.316535181127;5021.49785021495;6110.23622047253;6159.384061593805;6203.658798766111;6228;4786.821995991885;5757.5393968481585;5908.970455147697;5947.405259474018;5984.730687119003;5325.440828713085;5348.919766203432;5381.27352807366;5675.829725108253;5838.357780644211;5566.276384070045;5841.561882858718;6146.907796383147;6149.877002459836;6168.814935551941;3316.3201543683967;3654.8903532894055;3656.5246517952496;3828.4448754930936;3944.2505923874373;5234.371875374827;5277.788888444419;5607.54203906979;5921.970390148022;5942.732577033958;5537.335519737496;5740.741666625096;5741.62679425836;5779.884402311846;5792.53659707214;5296.867578310573;5430.886668233082;5649.926513962334;5655.462731040545;5680.9147862781265;4527.547245275446;4700.318453824108;5514.80698175578;5562.218890554723;5569.35952365467;4881.487443818348;5635.435876676184;5707.514861236841;5784.2769653793275;5787.536997040311;4750.5249475052215;5311.3891902431715;5565.970295495346;5668.2064510967675;5673.659580425187 14:37:42 INFO - PROCESS | 1943 | |5;yui.html;610.1092405088572;823.2056065896409;825.1542169276495;828.5318794880895;842.5871323051703;14761.557153285405;14989.47536836211;15237.69524609507;15243.237838108105;15243.618909527268;13511.391987360568;13942.372593233304;13967;13991.300434978235;14028.228447435387;719.722906680929;891.6121487153109;903.6204793986542;908.927285817133;912.110692075228;746.238836386885;831.6548632317581;911.4804561400005;913.6528119314685;916.4501299220481;956.1681337236614;957.1433566957569;957.8467445208768;959.5873774277059;963.3738070254356;752.116263390514;932.7341707613366;949.4540639132526;954.5465903695724;955.106974978394;703.0859882153231;706.0292098364748;706.131458306281;706.6678661029308;707.049019069351;14773.778393324108;14853.108813471226;14869.330880110421;14879.553613391616;14923.776143357749;13871.791923121162;14201.715965680733;14264.928675356558;14270.429182832675;14379;11539.076873850072;11570.652880413601;11660.533578656843;11803.291802491878;12131.93934030333;3818.5417749870194;3854.1328201154797;3856.9807150964066;3876.3604005339366;3914.158455931988;64425.67787161128;79332.60333698352;79972;80599;81753;11048.392338421463;11192.552297907992;11199.440027998568;11278.830817537744;11337.206395552397;21803.345899623037;22122.22572209997;22147.446313842283;22153.889230553745;22171.55656886853;7015.579065256101;7995.640196191185;8024.157463466368;8051;8205.753827385188;4372.672049596293;4407.713498622579;4427.180971520214;4444.73331600105;4467.352233926063;3496.650334966483;3584.784912905182;3593.5148754918273;3596.4425514045365;3598.2443686825536;679.5650783498564;679.7586856665911;685.9588424694534;688.7658196213312;715.1168307140678;454.84080571799603;580.2794268624684;647.9902801457982;671.7212356871844;713.757322510349;8652.615581506929;9221.677241296657;9448.527573621292;9468.431894086378;9515.252257513826;596.4959609130278;645.1999520594522;656.9014647802868;662.192125606759;710.3542829892446;4225.471816022997;4391.253486907233;4392.692511524163;4458.286674132123;4515.81936722534;84800;85075.5746221265;89874.55062724775;90159;90597.54701226583;71059.6447017772;76492.70521884352;81304;81650.18349816544;82056.3588728234;70355.64822175856;71598.64200678964;71950.64024679843;72411.63794180995;73516.63241683757;9313.25493960495;9364.719058428258;9398.483083430478;9653.420794752337;9729;38559.240012507275;44967;45660.771696142634;46566.767166163954;47729;59580.10629840643;60945;63384.04923926052;63708.68145659242;64181.358186417536;12335.136540442083;12482.750344992868;12655.81016284738;12796.296203708886;13229.867701323248;13856.722865542833;13905.582832515041;14098.577042688736;14103.294835257992;14377.496787612383;13167.341632918124;13331.400086995915;13644.181349119084;13792.93103534476;13802.309884505936;13267.734645306848;13426.932865336;13822;13839.654008649864;13845.376958036712;12523.4364453598;12948.8057679133;13119.475220991053;13467.932660336635;13479.39342729599;9331.253499720142;11020.655051711987;11120.388378639253;11157.33056016609;11263.493142808757;10173.847392288973;11173.441327933733;11359.943200283946;11363.659090227306;11390.544378224778 14:37:42 INFO - PROCESS | 1943 | __end_tp_report 14:37:42 INFO - PROCESS | 1943 | __start_cc_report 14:37:42 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,1260 14:37:42 INFO - PROCESS | 1943 | __end_cc_report 14:37:42 INFO - PROCESS | 1943 | __startTimestamp1464730662451__endTimestamp 14:37:42 INFO - PROCESS | 1943 | 14:37:42 INFO - PROCESS | 1943 | ------- Summary: start ------- 14:37:42 INFO - PROCESS | 1943 | Number of tests: 6 14:37:42 INFO - PROCESS | 1943 | 14:37:42 INFO - PROCESS | 1943 | [#0] dojo.html Cycles:165 Average:5764.35 Median:5991.34 stddev:617.64 (10.3%) stddev-sans-first:592.37 14:37:42 INFO - PROCESS | 1943 | Values: 3454.8 3461.3 3465.2 3487.5 3527.3 5640.7 5703.9 5737.6 5758.3 5790.8 5713.9 5784.1 5793.4 5799.0 5808.5 5122.1 5192.7 5249.5 5297.6 5340.8 4621.9 5338.1 5338.2 5341.5 5349.3 5226.0 5251.2 5275.7 5280.9 5305.1 5358.4 5369.9 5371.8 5375.0 5425.7 5115.6 5123.0 5126.4 5242.3 5263.0 5509.6 5583.4 5589.2 5603.9 5653.6 6229.9 6257.4 6264.4 6267.0 6270.3 5049.6 6158.0 6204.1 6236.1 6250.3 5832.8 5845.8 5861.0 5878.6 5904.1 6238.2 6247.0 6261.7 6266.1 6278.6 6085.0 6131.4 6163.5 6166.6 6188.8 6163.2 6176.8 6223.2 6241.9 6249.4 6224.7 6251.8 6269.3 6278.4 6298.3 6145.2 6237.4 6245.2 6253.2 6253.4 4894.9 4957.3 5017.2 5025.5 5053.9 4510.5 4527.4 4547.3 4561.9 4563.7 5639.1 5735.8 5767.8 5809.2 5818.1 6158.0 6190.4 6220.5 6226.8 6244.0 5751.0 5774.2 5780.1 5797.8 5834.2 5329.2 5386.1 5389.5 5442.0 5442.4 5231.6 6222.5 6232.8 6243.4 6260.7 6128.4 6186.8 6233.9 6244.5 6253.2 6365.2 6384.7 6387.3 6394.4 6420.9 6125.1 6139.3 6239.9 6246.5 6273.9 6177.4 6219.9 6219.9 6262.9 6275.4 6098.8 6148.4 6163.1 6173.4 6174.3 5991.3 6005.9 6023.8 6164.9 6176.8 5938.2 5944.2 5959.6 5966.4 5981.1 6161.4 6194.5 6203.5 6205.3 6208.8 6022.9 6026.1 6044.6 6059.3 6099.7 14:37:42 INFO - PROCESS | 1943 | 14:37:42 INFO - PROCESS | 1943 | [#1] ext.html Cycles:180 Average:70210.17 Median:21383.73 stddev:148144.37 (692.8%) stddev-sans-first:148467.44 14:37:42 INFO - PROCESS | 1943 | Values: 479.8 486.2 489.5 493.4 498.5 31459.8 33387.3 33410.0 33461.8 33477.2 44209.8 44967.0 44985.8 47706.3 47775.8 9666.6 9842.5 9852.0 9869.4 9872.4 8773.5 9048.9 9650.8 9786.6 9806.3 11722.1 11979.0 11989.4 12204.0 12241.6 10476.0 12014.9 12028.2 12154.5 12172.8 8618.6 10051.6 11598.7 11973.6 11997.5 37522.2 39321.6 42286.6 42313.6 42406.6 3517.3 3683.1 3887.5 3952.4 4224.0 26723.6 31627.4 31927.4 32664.8 33508.2 3208.5 3382.7 3383.7 3393.8 3402.1 502187.0 502651.0 502959.0 504028.0 504904.5 65525.3 65594.0 66145.3 66227.7 66279.7 70238.0 70328.0 70494.0 70515.0 70540.6 16911.2 17157.5 17178.7 17179.7 17198.1 12982.0 13039.5 13064.9 13065.7 13068.2 16507.8 16848.4 18408.9 18795.6 18826.5 6498.4 6571.5 6599.1 6606.7 6637.8 919.5 980.0 1037.8 1080.0 1160.4 30684.7 31128.0 31248.2 31278.5 32498.9 1068.2 1086.0 1104.9 1176.4 1182.2 16308.6 16913.1 17037.9 17452.5 17494.0 592192.0 619593.0 619864.0 623420.0 628162.2 49792.8 60521.1 61396.7 61622.0 65706.7 1390.7 1403.9 1408.2 1409.2 1425.2 31247.1 31990.8 31999.8 32716.8 32939.8 54429.7 55029.2 55177.9 55262.2 55309.0 537149.0 541414.0 541572.0 543388.0 545117.0 27112.5 27153.3 27381.6 27411.9 29282.3 50465.7 50884.9 51004.5 51389.2 51501.5 41034.4 41202.8 41775.2 42542.7 42711.0 40643.0 40877.4 41207.0 41729.8 42080.0 38880.0 39691.0 40105.8 40184.2 40192.6 33206.3 35573.6 35764.1 35926.0 36020.6 26000.2 26070.3 26235.0 26451.7 26468.8 14:37:42 INFO - PROCESS | 1943 | 14:37:42 INFO - PROCESS | 1943 | [#2] jquery.html Cycles:180 Average:33528.70 Median:11680.17 stddev:91320.45 (781.8%) stddev-sans-first:91542.72 14:37:42 INFO - PROCESS | 1943 | Values: 386.1 389.2 391.3 391.8 416.9 19708.0 20778.6 21000.6 21017.0 21983.8 20443.0 20993.3 21257.4 21307.3 21315.8 4397.2 4835.7 5193.3 5204.4 5208.1 4538.3 5211.6 5228.4 5889.3 5898.1 5816.9 5844.7 5846.2 5855.9 5858.1 5268.2 5271.6 5276.4 5351.9 5837.7 5124.1 5125.2 5151.4 5152.9 5160.1 13484.3 15155.3 24307.9 25911.4 25918.6 2061.9 2103.0 2264.4 2274.9 2409.3 10232.7 10264.4 10269.7 10434.3 10501.2 1869.2 1900.4 1916.7 1949.9 1956.9 110330.0 111542.4 117815.0 119018.0 119679.2 11497.4 11622.5 11662.5 11673.9 11797.8 14710.9 14804.8 14836.0 14857.8 14875.7 8476.9 8663.3 8692.2 8745.1 8803.5 8567.4 8596.7 8628.5 8753.1 8760.5 3968.0 4370.4 4551.7 4556.7 4619.7 1837.6 1923.2 1947.8 1948.6 1951.6 723.8 818.9 906.9 961.1 1005.5 19584.3 20416.0 20423.6 20612.2 20911.8 970.6 987.4 1015.9 1041.3 1045.6 10418.6 10930.8 10955.3 10966.8 10985.6 499925.0 504560.0 505991.0 591886.0 593979.0 124044.0 133746.0 143109.6 145925.0 146535.0 980.2 989.0 1019.4 1031.3 1033.6 16109.9 16485.8 17996.8 18437.6 18519.6 54660.5 55859.0 55890.4 56059.0 56380.7 81686.2 81827.0 81946.0 82710.0 83945.0 6320.2 7630.3 7804.8 7959.3 8136.8 10239.3 10437.3 11700.5 12310.3 12364.7 10393.0 10500.6 10523.2 10602.2 10773.4 10055.0 10061.9 10105.9 10396.2 10514.9 11052.2 11256.9 11293.7 11344.5 11409.8 6319.9 6746.6 7288.6 7483.9 8175.3 9343.8 10907.8 10965.2 11075.9 11123.8 14:37:42 INFO - PROCESS | 1943 | 14:37:42 INFO - PROCESS | 1943 | [#3] mootools.html Cycles:180 Average:5792.39 Median:4900.38 stddev:3285.38 (67.0%) stddev-sans-first:3277.84 14:37:42 INFO - PROCESS | 1943 | Values: 1377.4 1525.8 1548.6 1786.3 1965.9 7914.4 8868.6 8872.0 8875.3 9266.0 8841.3 9071.3 9071.4 9076.8 9096.3 2390.8 2419.0 2421.9 2424.7 2425.2 2366.7 2391.6 2411.7 2419.3 2422.7 2394.1 2404.5 2412.0 2419.1 2419.7 2355.1 2380.2 2390.5 2392.3 2392.3 2286.4 2385.4 2386.4 2386.6 2392.3 6927.7 7999.7 8916.8 9024.8 9028.5 4804.7 4914.9 5095.5 5173.6 5358.1 3554.6 3555.2 3661.0 3667.8 3671.1 1066.0 1075.7 1084.2 1092.6 1098.1 12012.8 12318.0 12321.3 12332.5 12339.9 4787.4 4840.5 4895.8 4904.9 4906.8 9125.4 10125.0 10460.9 10466.9 10515.8 5497.0 5510.0 5511.0 5511.8 5515.3 4872.3 4872.8 4971.4 5021.7 5036.6 3855.0 3918.1 3975.1 3994.1 4017.0 1249.4 1276.9 1280.2 1286.9 1291.8 840.9 937.4 946.4 971.9 996.6 5870.9 5901.7 5939.3 6072.5 6092.5 451.3 451.3 458.0 459.0 462.9 3959.4 4001.2 4015.6 4058.4 4061.7 10582.2 10752.9 10887.5 11043.6 11049.7 9146.3 10945.7 10965.1 10978.9 11015.9 8216.5 8340.6 8341.4 8343.3 8355.7 5436.9 5978.9 6002.1 6070.1 6089.8 9703.4 9767.9 9768.3 9780.6 10254.4 9857.9 10332.7 10366.1 10371.3 10376.1 7583.1 7632.5 7702.6 7853.7 7944.1 8668.9 8728.0 8754.0 8765.6 8777.1 7639.6 7715.7 7776.0 7850.9 7899.3 6579.8 7493.7 7621.2 7770.9 7772.3 6808.9 7201.9 7235.7 7240.8 7255.0 4358.4 4963.6 4970.2 5110.7 5191.2 4202.3 5107.9 5162.7 5263.9 5321.7 14:37:42 INFO - PROCESS | 1943 | 14:37:42 INFO - PROCESS | 1943 | [#4] prototype.html Cycles:180 Average:4611.02 Median:5206.54 stddev:1450.80 (27.9%) stddev-sans-first:1418.26 14:37:42 INFO - PROCESS | 1943 | Values: 296.1 310.1 318.6 320.9 321.8 4850.5 5518.3 5519.0 5548.8 5595.4 4668.6 5301.9 5467.5 5490.3 5493.4 3812.4 3959.2 3995.6 4098.7 4213.8 3583.0 3627.0 4100.9 4115.0 4196.3 3462.6 4142.2 4152.0 4170.5 4196.8 3244.6 3892.6 3925.5 4053.3 4161.4 3018.7 3400.4 3407.6 3571.0 3595.2 4589.8 4910.4 5213.5 5220.9 5242.5 4568.7 4736.5 4746.5 4786.9 4809.0 5080.0 5127.3 5641.9 5702.8 5729.3 3434.3 3975.8 3989.3 4001.2 4035.5 5647.4 5912.9 5985.0 6070.5 6095.3 3890.6 3933.4 3937.2 3938.0 3947.6 3402.7 3661.5 3972.2 3989.5 4009.3 3957.7 4488.9 4651.7 4680.8 4714.1 4408.6 5131.5 5723.5 5748.1 5768.3 1191.9 1242.3 1367.5 1414.4 1420.2 749.1 789.6 846.8 871.5 881.7 4982.4 5147.5 5199.6 5331.4 5377.9 5028.4 5544.7 5942.8 5979.6 5995.7 4664.6 5154.9 5177.6 5331.3 5388.0 2571.9 3104.3 3128.8 3130.4 3141.6 5784.9 6156.8 6234.4 6234.4 6254.9 5748.4 5781.5 6126.3 6142.7 6213.3 5021.5 6110.2 6159.4 6203.7 6228.0 4786.8 5757.5 5909.0 5947.4 5984.7 5325.4 5348.9 5381.3 5675.8 5838.4 5566.3 5841.6 6146.9 6149.9 6168.8 3316.3 3654.9 3656.5 3828.4 3944.3 5234.4 5277.8 5607.5 5922.0 5942.7 5537.3 5740.7 5741.6 5779.9 5792.5 5296.9 5430.9 5649.9 5655.5 5680.9 4527.5 4700.3 5514.8 5562.2 5569.4 4881.5 5635.4 5707.5 5784.3 5787.5 4750.5 5311.4 5566.0 5668.2 5673.7 14:37:42 INFO - PROCESS | 1943 | 14:37:42 INFO - PROCESS | 1943 | [#5] yui.html Cycles:180 Average:18466.10 Median:2485.33 stddev:24679.29 (993.0%) stddev-sans-first:24712.10 14:37:42 INFO - PROCESS | 1943 | Values: 610.1 823.2 825.2 828.5 842.6 14761.6 14989.5 15237.7 15243.2 15243.6 13511.4 13942.4 13967.0 13991.3 14028.2 719.7 891.6 903.6 908.9 912.1 746.2 831.7 911.5 913.7 916.5 956.2 957.1 957.8 959.6 963.4 752.1 932.7 949.5 954.5 955.1 703.1 706.0 706.1 706.7 707.0 14773.8 14853.1 14869.3 14879.6 14923.8 13871.8 14201.7 14264.9 14270.4 14379.0 11539.1 11570.7 11660.5 11803.3 12131.9 3818.5 3854.1 3857.0 3876.4 3914.2 64425.7 79332.6 79972.0 80599.0 81753.0 11048.4 11192.6 11199.4 11278.8 11337.2 21803.3 22122.2 22147.4 22153.9 22171.6 7015.6 7995.6 8024.2 8051.0 8205.8 4372.7 4407.7 4427.2 4444.7 4467.4 3496.7 3584.8 3593.5 3596.4 3598.2 679.6 679.8 686.0 688.8 715.1 454.8 580.3 648.0 671.7 713.8 8652.6 9221.7 9448.5 9468.4 9515.3 596.5 645.2 656.9 662.2 710.4 4225.5 4391.3 4392.7 4458.3 4515.8 84800.0 85075.6 89874.6 90159.0 90597.5 71059.6 76492.7 81304.0 81650.2 82056.4 70355.6 71598.6 71950.6 72411.6 73516.6 9313.3 9364.7 9398.5 9653.4 9729.0 38559.2 44967.0 45660.8 46566.8 47729.0 59580.1 60945.0 63384.0 63708.7 64181.4 12335.1 12482.8 12655.8 12796.3 13229.9 13856.7 13905.6 14098.6 14103.3 14377.5 13167.3 13331.4 13644.2 13792.9 13802.3 13267.7 13426.9 13822.0 13839.7 13845.4 12523.4 12948.8 13119.5 13467.9 13479.4 9331.3 11020.7 11120.4 11157.3 11263.5 10173.8 11173.4 11359.9 11363.7 11390.5 14:37:42 INFO - PROCESS | 1943 | -------- Summary: end -------- 14:37:42 INFO - PROCESS | 1943 | 14:37:43 INFO - TEST-INFO | 1943: exit 0 14:37:43 INFO - TEST-OK | dromaeo_css | took 1116808ms 14:37:43 INFO - TEST-START | kraken 14:37:43 INFO - operating with platform_type : mac_ 14:37:43 INFO - Initialising browser for kraken test... 14:37:43 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpY7KwmJ/profile http://localhost:50813/getInfo.html) 14:37:45 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 14:37:45 INFO - PROCESS | 1948 | colorDepth:24 14:37:45 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 14:37:45 INFO - PROCESS | 1948 | __metrics 14:37:45 INFO - PROCESS | 1948 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:37:45 ERROR - PROCESS | 1948 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:37:46 INFO - TEST-INFO | 1948: exit 0 14:37:46 INFO - Browser initialized. 14:37:46 INFO - Running cycle 1/1 for kraken test... 14:37:46 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpY7KwmJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:37:58 INFO - PROCESS | 1949 | RSS: Main: 241909760 14:37:58 INFO - PROCESS | 1949 | 14:38:00 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50813/tests/kraken/driver.html?testName=audio-beat-detection) 14:38:01 INFO - PROCESS | 1949 | RSS: Main: 291835904 14:38:01 INFO - PROCESS | 1949 | 14:38:04 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50813/tests/kraken/driver.html?testName=audio-dft) 14:38:04 INFO - PROCESS | 1949 | RSS: Main: 301166592 14:38:04 INFO - PROCESS | 1949 | 14:38:08 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50813/tests/kraken/driver.html?testName=audio-fft) 14:38:09 INFO - PROCESS | 1949 | RSS: Main: 386265088 14:38:09 INFO - PROCESS | 1949 | 14:38:11 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50813/tests/kraken/driver.html?testName=audio-oscillator) 14:38:11 INFO - PROCESS | 1949 | RSS: Main: 289079296 14:38:11 INFO - PROCESS | 1949 | 14:38:13 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50813/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:38:14 INFO - PROCESS | 1949 | RSS: Main: 293257216 14:38:14 INFO - PROCESS | 1949 | 14:38:17 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50813/tests/kraken/driver.html?testName=imaging-darkroom) 14:38:17 INFO - PROCESS | 1949 | RSS: Main: 330391552 14:38:17 INFO - PROCESS | 1949 | 14:38:20 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50813/tests/kraken/driver.html?testName=imaging-desaturate) 14:38:21 INFO - PROCESS | 1949 | RSS: Main: 332046336 14:38:21 INFO - PROCESS | 1949 | 14:38:24 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50813/tests/kraken/driver.html?testName=json-parse-financial) 14:38:24 INFO - PROCESS | 1949 | RSS: Main: 330199040 14:38:24 INFO - PROCESS | 1949 | 14:38:26 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50813/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:38:26 INFO - PROCESS | 1949 | RSS: Main: 309600256 14:38:26 INFO - PROCESS | 1949 | 14:38:28 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:38:29 INFO - PROCESS | 1949 | RSS: Main: 316473344 14:38:29 INFO - PROCESS | 1949 | 14:38:31 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:38:32 INFO - PROCESS | 1949 | RSS: Main: 319840256 14:38:32 INFO - PROCESS | 1949 | 14:38:35 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:38:35 INFO - PROCESS | 1949 | RSS: Main: 322383872 14:38:35 INFO - PROCESS | 1949 | 14:38:38 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:38:39 INFO - PROCESS | 1949 | RSS: Main: 323108864 14:38:39 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:50813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50813/tests/kraken/driver.html?testName=ai-astar) 14:38:41 INFO - PROCESS | 1949 | RSS: Main: 366817280 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | __start_tp_report 14:38:41 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 14:38:41 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 14:38:41 INFO - PROCESS | 1949 | |i|pagename|runs| 14:38:41 INFO - PROCESS | 1949 | |0;ai-astar;112;108;112;113;111;111;111;111;113;112 14:38:41 INFO - PROCESS | 1949 | |1;audio-beat-detection;157;144;139;201;137;140;137;118;168;117 14:38:41 INFO - PROCESS | 1949 | |2;audio-dft;186;182;179;182;183;180;181;185;186;184 14:38:41 INFO - PROCESS | 1949 | |3;audio-fft;76;72;57;108;58;56;61;60;96;73 14:38:41 INFO - PROCESS | 1949 | |4;audio-oscillator;85;92;91;71;71;90;125;100;89;100 14:38:41 INFO - PROCESS | 1949 | |5;imaging-gaussian-blur;87;84;87;84;87;83;87;84;87;83 14:38:41 INFO - PROCESS | 1949 | |6;imaging-darkroom;93;93;93;92;92;93;93;93;93;92 14:38:41 INFO - PROCESS | 1949 | |7;imaging-desaturate;85;84;86;86;86;94;137;83;86;86 14:38:41 INFO - PROCESS | 1949 | |8;json-parse-financial;53;55;54;53;53;75;83;44;45;84 14:38:41 INFO - PROCESS | 1949 | |9;json-stringify-tinderbox;65;73;73;75;72;73;70;70;71;71 14:38:41 INFO - PROCESS | 1949 | |10;stanford-crypto-aes;89;87;89;87;90;86;88;90;125;88 14:38:41 INFO - PROCESS | 1949 | |11;stanford-crypto-ccm;148;127;151;153;156;135;139;138;152;142 14:38:41 INFO - PROCESS | 1949 | |12;stanford-crypto-pbkdf2;164;176;172;169;173;168;170;168;169;169 14:38:41 INFO - PROCESS | 1949 | |13;stanford-crypto-sha256-iterative;67;68;68;67;70;66;67;65;66;67 14:38:41 INFO - PROCESS | 1949 | __end_tp_report 14:38:41 INFO - PROCESS | 1949 | __start_cc_report 14:38:41 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,1945 14:38:41 INFO - PROCESS | 1949 | __end_cc_report 14:38:41 INFO - PROCESS | 1949 | __startTimestamp1464730721338__endTimestamp 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | ------- Summary: start ------- 14:38:41 INFO - PROCESS | 1949 | Number of tests: 14 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:1.43 (1.3%) stddev-sans-first:1.50 14:38:41 INFO - PROCESS | 1949 | Values: 112.0 108.0 112.0 113.0 111.0 111.0 111.0 111.0 113.0 112.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#1] audio-beat-detection Cycles:10 Average:145.80 Median:142.00 stddev:24.73 (17.4%) stddev-sans-first:25.90 14:38:41 INFO - PROCESS | 1949 | Values: 157.0 144.0 139.0 201.0 137.0 140.0 137.0 118.0 168.0 117.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.50 stddev:2.44 (1.3%) stddev-sans-first:2.30 14:38:41 INFO - PROCESS | 1949 | Values: 186.0 182.0 179.0 182.0 183.0 180.0 181.0 185.0 186.0 184.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#3] audio-fft Cycles:10 Average:71.70 Median:66.50 stddev:17.73 (26.7%) stddev-sans-first:18.74 14:38:41 INFO - PROCESS | 1949 | Values: 76.0 72.0 57.0 108.0 58.0 56.0 61.0 60.0 96.0 73.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:87.00 stddev:15.49 (17.8%) stddev-sans-first:16.25 14:38:41 INFO - PROCESS | 1949 | Values: 85.0 92.0 91.0 71.0 71.0 90.0 125.0 100.0 89.0 100.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:87.00 stddev:1.83 (2.1%) stddev-sans-first:1.83 14:38:41 INFO - PROCESS | 1949 | Values: 87.0 84.0 87.0 84.0 87.0 83.0 87.0 84.0 87.0 83.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 14:38:41 INFO - PROCESS | 1949 | Values: 93.0 93.0 93.0 92.0 92.0 93.0 93.0 93.0 93.0 92.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:86.00 stddev:16.32 (19.0%) stddev-sans-first:17.15 14:38:41 INFO - PROCESS | 1949 | Values: 85.0 84.0 86.0 86.0 86.0 94.0 137.0 83.0 86.0 86.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:54.50 stddev:14.98 (27.5%) stddev-sans-first:15.68 14:38:41 INFO - PROCESS | 1949 | Values: 53.0 55.0 54.0 53.0 53.0 75.0 83.0 44.0 45.0 84.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.50 stddev:2.71 (3.7%) stddev-sans-first:1.66 14:38:41 INFO - PROCESS | 1949 | Values: 65.0 73.0 73.0 75.0 72.0 73.0 70.0 70.0 71.0 71.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#10] stanford-crypto-aes Cycles:10 Average:91.90 Median:88.50 stddev:11.70 (13.2%) stddev-sans-first:12.37 14:38:41 INFO - PROCESS | 1949 | Values: 89.0 87.0 89.0 87.0 90.0 86.0 88.0 90.0 125.0 88.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:149.50 stddev:9.36 (6.3%) stddev-sans-first:9.82 14:38:41 INFO - PROCESS | 1949 | Values: 148.0 127.0 151.0 153.0 156.0 135.0 139.0 138.0 152.0 142.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:169.50 stddev:3.26 (1.9%) stddev-sans-first:2.70 14:38:41 INFO - PROCESS | 1949 | Values: 164.0 176.0 172.0 169.0 173.0 168.0 170.0 168.0 169.0 169.0 14:38:41 INFO - PROCESS | 1949 | 14:38:41 INFO - PROCESS | 1949 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.37 (2.0%) stddev-sans-first:1.45 14:38:41 INFO - PROCESS | 1949 | Values: 67.0 68.0 68.0 67.0 70.0 66.0 67.0 65.0 66.0 67.0 14:38:41 INFO - PROCESS | 1949 | -------- Summary: end -------- 14:38:41 INFO - PROCESS | 1949 | 14:38:42 INFO - TEST-INFO | 1949: exit 0 14:38:42 INFO - TEST-OK | kraken | took 59098ms 14:38:42 INFO - SUITE-END | took 1175s 14:38:42 INFO - Completed test suite (00:19:36) 14:38:42 INFO - javascript benchmark 14:38:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3454.7735553878356, 3461.2731326421454, 3465.2203254267783, 3487.511748355229, 3527.259275552134, 5640.689762063085, 5703.914441283378, 5737.598368114233, 5758.280214973129, 5790.797322093727, 5713.885722285552, 5784.074548072309, 5793.420657934204, 5799.014167591509, 5808.506276966463, 5122.052420302256, 5192.740362981855, 5249.527542521172, 5297.629165958385, 5340.839774806743, 4621.930671039937, 5338.065838478251, 5338.225957236198, 5341.4658534146665, 5349.304590403242, 5226.0, 5251.2385704072885, 5275.709835959021, 5280.894382112356, 5305.098133317345, 5358.410574836765, 5369.9463005369835, 5371.838844834661, 5374.973125134388, 5425.728713564306, 5115.61632877532, 5122.974385128051, 5126.359205099363, 5242.292290540767, 5262.973720124539, 5509.614326997112, 5583.413741557119, 5589.161625756128, 5603.887922241533, 5653.57598180134, 6229.87540249197, 6257.436830685215, 6264.373562643745, 6266.968665156691, 6270.278917924452, 5049.64652474327, 6157.969210153965, 6204.131421600934, 6236.064590311489, 6250.281217659982, 5832.766689332421, 5845.824625261249, 5861.003629383015, 5878.647281163144, 5904.143899134601, 6238.189035425366, 6246.968765156145, 6261.686915654199, 6266.091416744638, 6278.591891527036, 6084.969575152096, 6131.359149582395, 6163.476104531165, 6166.630002199882, 6188.752449902044, 6163.229596300463, 6176.7838125665385, 6223.190985171989, 6241.937580624136, 6249.375062493805, 6224.657643829542, 6251.843703907348, 6269.279032911182, 6278.372162783777, 6298.275698294663, 6145.231846019247, 6237.407446292595, 6245.156903817927, 6253.249610046823, 6253.437190652863, 4894.902101957941, 4957.281194226817, 5017.222330538772, 5025.472325405853, 5053.8736531587, 4510.503844577094, 4527.4340707411575, 4547.3179023146795, 4561.908761824745, 4563.748993805372, 5639.069553523623, 5735.799247026417, 5767.798127065533, 5809.157672137601, 5818.069108942634, 6157.96921015392, 6190.364675459209, 6220.471259942956, 6226.782062627786, 6243.968780156161, 5751.0, 5774.220480235114, 5780.132980053026, 5797.768089276466, 5834.241548598655, 5329.173978033411, 5386.084365657847, 5389.541888939484, 5442.0, 5442.428545002717, 5231.607629427808, 6222.533310001859, 6232.8130156095385, 6243.43809057178, 6260.7495700171485, 6128.3503509891225, 6186.81439556814, 6233.937660623517, 6244.46922011616, 6253.218347706536, 6365.204349456318, 6384.680765961776, 6387.329330420424, 6394.360563943568, 6420.871582568416, 6125.050617154348, 6139.277491147187, 6239.875202496016, 6246.46905013079, 6273.9372606273355, 6177.382261773787, 6219.875602487835, 6219.942609756262, 6262.906056409249, 6275.37246275387, 6098.75604975796, 6148.385161483816, 6163.075538669235, 6173.351798061139, 6174.289956654862, 5991.340952495135, 6005.909911351246, 6023.759049638141, 6164.8767024658355, 6176.845578860564, 5938.198343223609, 5944.197533332945, 5959.612625179349, 5966.373530779206, 5981.0729336952845, 6161.44546990773, 6194.473469755121, 6203.472704820141, 6205.348438414082, 6208.751649934133, 6022.909656355246, 6026.096085587197, 6044.607100538451, 6059.272887253558, 6099.725512352044], "value": 5727.066499442172, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.7864950097206, 486.24146331722505, 489.5007092765379, 493.4079105073912, 498.5313805023278, 31459.842700786492, 33387.33225335492, 33410.0, 33461.83269083651, 33477.16307092321, 44209.778951105196, 44967.0, 44985.7750711246, 47706.28440573394, 47775.761121194344, 9666.565004574812, 9842.50787460624, 9852.0, 9869.358491698016, 9872.35829671069, 8773.517456539888, 9048.909510904905, 9650.758731031745, 9786.5596048178, 9806.313558050939, 11722.120840936921, 11979.0, 11989.40052997351, 12204.0, 12241.63275101744, 10475.952404759539, 12014.939925300405, 12028.158028937978, 12154.453049612788, 12172.81740773889, 8618.56907154646, 10051.597936082548, 11598.652040438801, 11973.640790776291, 11997.520099196021, 37522.24955500902, 39321.606783932366, 42286.57713422857, 42313.57686423158, 42406.57593424057, 3517.314123745871, 3683.079230192452, 3887.455756194135, 3952.446657467957, 4223.97888010558, 26723.599146012824, 31627.36745265082, 31927.361452770816, 32664.83667581647, 33508.1622959428, 3208.4866421372703, 3382.746294027958, 3383.695467407945, 3393.8133402662843, 3402.0644322811427, 502187.0, 502651.0, 502959.0, 504028.0, 504904.4754776239, 65525.34474655193, 65594.0, 66145.33854661488, 66227.66886165539, 66279.66860165668, 70238.0, 70328.0, 70494.0, 70515.0, 70540.64729676319, 16911.154442277835, 17157.485275441755, 17178.656426871392, 17179.656406871793, 17198.1400929953, 12982.0, 13039.543615973585, 13064.869351306364, 13065.738685226432, 13068.215907045418, 16507.752383714254, 16848.410305639245, 18408.907955460138, 18795.624087518172, 18826.52933676669, 6498.3826536478955, 6571.507136964747, 6599.096397585047, 6606.669666516655, 6637.785006520894, 919.4988731141544, 979.9706008819746, 1037.8131936251548, 1079.994600026995, 1160.399748299292, 30684.67211480938, 31128.0, 31248.21879453032, 31278.530822037686, 32498.862539810994, 1068.1561566362486, 1086.0280049355783, 1104.86741591008, 1176.3588844079998, 1182.2197349749124, 16308.592285192964, 16913.069781162154, 17037.892536985055, 17452.47642570725, 17494.0, 592192.039039802, 619593.0, 619864.0, 623420.0, 628162.1551353467, 49792.75103624531, 60521.09218361728, 61396.69301653552, 61622.0, 65706.67146664236, 1390.7079513302117, 1403.8736513713816, 1408.1621435245806, 1409.1545072956144, 1425.173399428329, 31247.062588122393, 31990.84004579962, 31999.840000799846, 32716.83641581777, 32939.83530082334, 54429.72785136049, 55029.174562380795, 55177.89644207174, 55262.17106743321, 55309.0, 537149.0, 541414.0, 541572.0, 543388.0, 545117.0, 27112.457750845268, 27153.321166970192, 27381.58927616127, 27411.86294068517, 29282.267943301587, 50465.738356541384, 50884.94470655753, 51004.489955099976, 51389.22916156335, 51501.48498515117, 41034.38448423216, 41202.79398603108, 41775.216023968875, 42542.7237182885, 42711.0, 40643.0, 40877.38683919803, 41207.0, 41729.79135104305, 42080.0, 38880.0, 39691.00772480711, 40105.79947100246, 40184.19631607293, 40192.59807401888, 33206.335873282886, 35573.644263557035, 35764.105897352776, 35926.0, 36020.63979360173, 26000.219993400227, 26070.34824129412, 26235.0, 26451.735482644926, 26468.80890359977], "value": 20834.61618156093, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.111942532176, 389.2021356219749, 391.2507548045493, 391.8393458681943, 416.8749375187443, 19708.014599270035, 20778.584428311424, 21000.579988400223, 21017.0, 21983.780162198374, 20443.0, 20993.265235716754, 21257.362279131612, 21307.25424610139, 21315.786842131572, 4397.208502469554, 4835.734034628103, 5193.272941788134, 5204.42751297357, 5208.114620514502, 4538.273876179812, 5211.557017653504, 5228.398734145565, 5889.263842019747, 5898.1447690084915, 5816.854578635547, 5844.678542680151, 5846.181534585162, 5855.941440585582, 5858.121281807742, 5268.157094864803, 5271.551918086967, 5276.419593844674, 5351.866203344908, 5837.708114594275, 5124.103281925648, 5125.154349532323, 5151.356080489939, 5152.943646552448, 5160.14857548504, 13484.325783710874, 15155.318010689436, 24307.878460607582, 25911.352216194748, 25918.611220831703, 2061.865978711379, 2102.978970210309, 2264.3772962435296, 2274.886255687209, 2409.265174121892, 10232.693019209435, 10264.384136951807, 10269.691909242734, 10434.321769084985, 10501.212409069274, 1869.2336142181641, 1900.4488698277482, 1916.7316575679415, 1949.8635095543318, 1956.9412917612494, 110330.0, 111542.44228778804, 117815.0, 119018.0, 119679.20481192789, 11497.367644779617, 11622.53509859613, 11662.475188616532, 11673.941630291964, 11797.764044719057, 14710.852891470948, 14804.777928331083, 14836.035657682216, 14857.777133343008, 14875.70248595022, 8476.872846907301, 8663.263622592025, 8692.174243446863, 8745.125487451203, 8803.515806630568, 8567.357448191411, 8596.742097737078, 8628.52543110135, 8753.080926502753, 8760.51817150063, 3967.960320396759, 4370.409994650745, 4551.749653769075, 4556.703814252063, 4619.69971951822, 1837.5850595041743, 1923.1826473748602, 1947.8441724661993, 1948.6005368899341, 1951.6389467948475, 723.8408930658187, 818.8567000774841, 906.895706993691, 961.1301771896445, 1005.527402121002, 19584.31454899072, 20416.0, 20423.591528169352, 20612.17551297961, 20911.79088209129, 970.6214576315243, 987.4174237199943, 1015.9339642923186, 1041.317936751429, 1045.6131231444263, 10418.635347762793, 10930.781384372269, 10955.287906286067, 10966.780664386668, 10985.6155034575, 499925.0, 504560.0, 505991.0, 591886.0, 593979.0, 124044.0, 133746.0, 143109.56890431172, 145925.0, 146535.0, 980.2158273381267, 988.9554970026222, 1019.4444028004755, 1031.3244824639874, 1033.5710680067687, 16109.919450402673, 16485.83514164843, 17996.820031799514, 18437.63124737471, 18519.629607407507, 54660.45339546713, 55859.0, 55890.44109559015, 56059.0, 56380.7180964109, 81686.18313816786, 81827.0, 81946.0, 82710.0, 83945.0, 6320.241571011508, 7630.2751238632245, 7804.804879878048, 7959.283664470226, 8136.83726325482, 10239.283250172417, 10437.32157409766, 11700.531978721096, 12310.322932238812, 12364.690882728004, 10393.0, 10500.632477863239, 10523.210759193244, 10602.151827853908, 10773.353598784099, 10055.0, 10061.934918548624, 10105.898941010495, 10396.22028347892, 10514.94742526313, 11052.226344155832, 11256.887431125584, 11293.661190163979, 11344.489497972776, 11409.828852567052, 6319.9052014218905, 6746.595204287759, 7288.599127048064, 7483.887741683988, 8175.264226219669, 9343.766405839908, 10907.781844363228, 10965.232433729563, 11075.889241107487, 11123.777524449304], "value": 9342.616406501516, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.3664114507326, 1525.8397868223835, 1548.5919395340245, 1786.3122697761366, 1965.9410217693464, 7914.406419518537, 8868.645254189832, 8871.955640221806, 8875.334349923758, 9266.0, 8841.292696584274, 9071.274298056156, 9071.410358326704, 9076.773080672987, 9096.317776166781, 2390.772876576731, 2418.9516209675885, 2421.8546887186735, 2424.7211570669424, 2425.2360506440414, 2366.6510089249136, 2391.557561851063, 2411.6503107049466, 2419.322589674885, 2422.733499315074, 2394.1381102803066, 2404.4710163763943, 2412.023130632088, 2419.1049311754678, 2419.6854408926815, 2355.05797680929, 2380.1907351500486, 2390.474095698952, 2392.2823153054187, 2392.3062311929693, 2286.4283929017747, 2385.427497400629, 2386.367612582667, 2386.55848667997, 2392.306231192952, 6927.688254028581, 7999.680012799481, 8916.777080572972, 9024.819503609957, 9028.458292502472, 4804.711717296974, 4914.90170196594, 5095.515925987025, 5173.637845350827, 5358.142697168473, 3554.573451185874, 3555.1645363339594, 3660.963390366089, 3667.7615954962844, 3671.0638787109115, 1066.0139371081718, 1075.6772968109535, 1084.203110713625, 1092.6066616018227, 1098.0666433531514, 12012.819807702892, 12318.0, 12321.260724356567, 12332.50669973209, 12339.876601234053, 4787.42550893888, 4840.467548569654, 4895.828645997444, 4904.926426103611, 4906.779194936236, 9125.406848554956, 10125.0, 10460.895391046144, 10466.947665261625, 10515.791683746498, 5497.0105381031, 5510.035743744828, 5511.0, 5511.834644960658, 5515.338159420895, 4872.269159626014, 4872.756362181876, 4971.37857767779, 5021.6735912165595, 5036.622253330942, 3854.9807250963568, 3918.0988372674447, 3975.0658595230097, 3994.1013272013565, 4017.0, 1249.4377530111485, 1276.9361531923366, 1280.1678908709414, 1286.948522059107, 1291.799771035491, 840.8906842110608, 937.3906960475746, 946.3517490519016, 971.8823353143941, 996.6412091647002, 5870.853228669317, 5901.704914754331, 5939.316978547522, 6072.51419886408, 6092.45167934888, 451.2869665927826, 451.2892194793214, 457.9633629309647, 458.99082018359445, 462.85188739603007, 3959.40608908666, 4001.2197621463533, 4015.558288588253, 4058.431819545266, 4061.735987160825, 10582.153427725763, 10752.94623526893, 10887.455627218607, 11043.558257669603, 11049.723756906142, 9146.314026448043, 10945.726356840983, 10965.122790176767, 10978.945105274423, 11015.944920275348, 8216.548089855116, 8340.62467188978, 8341.374396920255, 8343.290820280345, 8355.665773368997, 5436.945630543644, 5978.910316345346, 6002.069679199731, 6070.0591408331775, 6089.756409743561, 9703.417794932328, 9767.9023209767, 9768.316217864682, 9780.559874805793, 10254.436006019741, 9857.901420985985, 10332.690019299433, 10366.06705396518, 10371.325863818827, 10376.118029967538, 7583.127940286717, 7632.465727399029, 7702.614869256403, 7853.685852565834, 7944.086430060386, 8668.869966950628, 8728.039915609152, 8754.037055923716, 8765.561721913751, 8777.122287771172, 7639.618019098912, 7715.652795624098, 7775.961120194589, 7850.882236766567, 7899.328557072713, 6579.78274019308, 7493.737719180021, 7621.199774023869, 7770.92229077702, 7772.300492955661, 6808.93191068083, 7201.927980720126, 7235.674394652358, 7240.782776516713, 7254.96372518134, 4358.411614432011, 4963.553280204799, 4970.204767237225, 5110.71891045996, 5191.247269146028, 4202.348635961431, 5107.897842043064, 5162.716050617252, 5263.894722105613, 5321.654092484131], "value": 4572.373200264677, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.1352849678937, 310.1038000179482, 318.58265671969707, 320.8588221182681, 321.84390570573265, 4850.46644869065, 5518.296034942874, 5518.972405137969, 5548.750306236219, 5595.38450770416, 4668.638580222243, 5301.893962120765, 5467.507924286823, 5490.286262785842, 5493.368262649797, 3812.370958791796, 3959.2279505496367, 3995.60043995601, 4098.7005460604205, 4213.7893105344765, 3582.9788510274575, 3626.9637303627023, 4100.917981640366, 4114.950687574665, 4196.265653510639, 3462.567179102612, 4142.1922725068625, 4151.979240103795, 4170.47869016373, 4196.838628106528, 3244.5944256967878, 3892.579208588859, 3925.548561915388, 4053.3312003519386, 4161.4382058422025, 3018.6830382809926, 3400.370931377703, 3407.5740532433447, 3571.0, 3595.209054008114, 4589.770511474413, 4910.410750709902, 5213.53078222958, 5220.8694782630355, 5242.501962313574, 4568.748718820463, 4736.5026672199265, 4746.477887432379, 4786.880327991793, 4809.004263230755, 5080.0, 5127.333446651912, 5641.8589535261535, 5702.800401985952, 5729.341125770507, 3434.295969326282, 3975.8012099394914, 3989.341758609826, 4001.2197621463824, 4035.5068624609085, 5647.407022262644, 5912.852178695524, 5984.970075149597, 6070.484008859296, 6095.329513753483, 3890.6498415142773, 3933.370660694275, 3937.2125574885135, 3937.9803100984313, 3947.644711975936, 3402.6937575618313, 3661.4690869823726, 3972.205558888234, 3989.501312335958, 4009.258287216874, 3957.6635985940943, 4488.887777805516, 4651.744154071558, 4680.812767489262, 4714.137915523349, 4408.58118478744, 5131.461196574306, 5723.540497173194, 5748.1031259684205, 5768.278965129359, 1191.9046476281876, 1242.2732701369619, 1367.4803574641574, 1414.405949501212, 1420.1975883625719, 749.0748925077525, 789.6446599030345, 846.7671390367698, 871.4771137317558, 881.6510738569998, 4982.3522942018035, 5147.485251474823, 5199.558037566773, 5331.4401987790725, 5377.919331210035, 5028.371453568304, 5544.7227638618715, 5942.841145976579, 5979.5515336349945, 5995.730192141364, 4664.626829853603, 5154.871128221749, 5177.585793136615, 5331.333583302087, 5387.952578020328, 2571.929596674695, 3104.2704964333366, 3128.824243392958, 3130.3895740330418, 3141.6230052393403, 5784.884302314014, 6156.830202261556, 6234.37656234382, 6234.407731265523, 6254.906176407358, 5748.44194333526, 5781.479666830005, 6126.326104128542, 6142.723577439026, 6213.316535181127, 5021.49785021495, 6110.23622047253, 6159.384061593805, 6203.658798766111, 6228.0, 4786.821995991885, 5757.5393968481585, 5908.970455147697, 5947.405259474018, 5984.730687119003, 5325.440828713085, 5348.919766203432, 5381.27352807366, 5675.829725108253, 5838.357780644211, 5566.276384070045, 5841.561882858718, 6146.907796383147, 6149.877002459836, 6168.814935551941, 3316.3201543683967, 3654.8903532894055, 3656.5246517952496, 3828.4448754930936, 3944.2505923874373, 5234.371875374827, 5277.788888444419, 5607.54203906979, 5921.970390148022, 5942.732577033958, 5537.335519737496, 5740.741666625096, 5741.62679425836, 5779.884402311846, 5792.53659707214, 5296.867578310573, 5430.886668233082, 5649.926513962334, 5655.462731040545, 5680.9147862781265, 4527.547245275446, 4700.318453824108, 5514.80698175578, 5562.218890554723, 5569.35952365467, 4881.487443818348, 5635.435876676184, 5707.514861236841, 5784.2769653793275, 5787.536997040311, 4750.5249475052215, 5311.3891902431715, 5565.970295495346, 5668.2064510967675, 5673.659580425187], "value": 4144.483113302351, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.1092405088572, 823.2056065896409, 825.1542169276495, 828.5318794880895, 842.5871323051703, 14761.557153285405, 14989.47536836211, 15237.69524609507, 15243.237838108105, 15243.618909527268, 13511.391987360568, 13942.372593233304, 13967.0, 13991.300434978235, 14028.228447435387, 719.722906680929, 891.6121487153109, 903.6204793986542, 908.927285817133, 912.110692075228, 746.238836386885, 831.6548632317581, 911.4804561400005, 913.6528119314685, 916.4501299220481, 956.1681337236614, 957.1433566957569, 957.8467445208768, 959.5873774277059, 963.3738070254356, 752.116263390514, 932.7341707613366, 949.4540639132526, 954.5465903695724, 955.106974978394, 703.0859882153231, 706.0292098364748, 706.131458306281, 706.6678661029308, 707.049019069351, 14773.778393324108, 14853.108813471226, 14869.330880110421, 14879.553613391616, 14923.776143357749, 13871.791923121162, 14201.715965680733, 14264.928675356558, 14270.429182832675, 14379.0, 11539.076873850072, 11570.652880413601, 11660.533578656843, 11803.291802491878, 12131.93934030333, 3818.5417749870194, 3854.1328201154797, 3856.9807150964066, 3876.3604005339366, 3914.158455931988, 64425.67787161128, 79332.60333698352, 79972.0, 80599.0, 81753.0, 11048.392338421463, 11192.552297907992, 11199.440027998568, 11278.830817537744, 11337.206395552397, 21803.345899623037, 22122.22572209997, 22147.446313842283, 22153.889230553745, 22171.55656886853, 7015.579065256101, 7995.640196191185, 8024.157463466368, 8051.0, 8205.753827385188, 4372.672049596293, 4407.713498622579, 4427.180971520214, 4444.73331600105, 4467.352233926063, 3496.650334966483, 3584.784912905182, 3593.5148754918273, 3596.4425514045365, 3598.2443686825536, 679.5650783498564, 679.7586856665911, 685.9588424694534, 688.7658196213312, 715.1168307140678, 454.84080571799603, 580.2794268624684, 647.9902801457982, 671.7212356871844, 713.757322510349, 8652.615581506929, 9221.677241296657, 9448.527573621292, 9468.431894086378, 9515.252257513826, 596.4959609130278, 645.1999520594522, 656.9014647802868, 662.192125606759, 710.3542829892446, 4225.471816022997, 4391.253486907233, 4392.692511524163, 4458.286674132123, 4515.81936722534, 84800.0, 85075.5746221265, 89874.55062724775, 90159.0, 90597.54701226583, 71059.6447017772, 76492.70521884352, 81304.0, 81650.18349816544, 82056.3588728234, 70355.64822175856, 71598.64200678964, 71950.64024679843, 72411.63794180995, 73516.63241683757, 9313.25493960495, 9364.719058428258, 9398.483083430478, 9653.420794752337, 9729.0, 38559.240012507275, 44967.0, 45660.771696142634, 46566.767166163954, 47729.0, 59580.10629840643, 60945.0, 63384.04923926052, 63708.68145659242, 64181.358186417536, 12335.136540442083, 12482.750344992868, 12655.81016284738, 12796.296203708886, 13229.867701323248, 13856.722865542833, 13905.582832515041, 14098.577042688736, 14103.294835257992, 14377.496787612383, 13167.341632918124, 13331.400086995915, 13644.181349119084, 13792.93103534476, 13802.309884505936, 13267.734645306848, 13426.932865336, 13822.0, 13839.654008649864, 13845.376958036712, 12523.4364453598, 12948.8057679133, 13119.475220991053, 13467.932660336635, 13479.39342729599, 9331.253499720142, 11020.655051711987, 11120.388378639253, 11157.33056016609, 11263.493142808757, 10173.847392288973, 11173.441327933733, 11359.943200283946, 11363.659090227306, 11390.544378224778], "value": 7321.991445752439, "unit": "score"}], "name": "dromaeo_css", "value": 7326.7664657375835, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 112.0, 113.0, 111.0, 111.0, 111.0, 111.0, 113.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 144.0, 139.0, 201.0, 137.0, 140.0, 137.0, 118.0, 168.0, 117.0], "value": 145.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 179.0, 182.0, 183.0, 180.0, 181.0, 185.0, 186.0, 184.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 57.0, 108.0, 58.0, 56.0, 61.0, 60.0, 96.0, 73.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 92.0, 91.0, 71.0, 71.0, 90.0, 125.0, 100.0, 89.0, 100.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 87.0, 83.0, 87.0, 84.0, 87.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 92.0, 92.0, 93.0, 93.0, 93.0, 93.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 86.0, 86.0, 94.0, 137.0, 83.0, 86.0, 86.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 54.0, 53.0, 53.0, 75.0, 83.0, 44.0, 45.0, 84.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 73.0, 75.0, 72.0, 73.0, 70.0, 70.0, 71.0, 71.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 87.0, 90.0, 86.0, 88.0, 90.0, 125.0, 88.0], "value": 91.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 127.0, 151.0, 153.0, 156.0, 135.0, 139.0, 138.0, 152.0, 142.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 176.0, 172.0, 169.0, 173.0, 168.0, 170.0, 168.0, 169.0, 169.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 70.0, 66.0, 67.0, 65.0, 66.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1476.4999999999998, "alertThreshold": 2.0}]} 14:38:42 INFO - Return code: 0 14:38:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:38:42 ERROR - # TBPL SUCCESS # 14:38:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:38:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:38:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:38:42 INFO - 2016-05-31 14:38:42.632 screenresolution[1953:15076] starting screenresolution argv=screenresolution get 14:38:42 INFO - 2016-05-31 14:38:42.635 screenresolution[1953:15076] Display 0: 1600x1200x32@60 14:38:42 INFO - 2016-05-31 14:38:42.640 screenresolution[1954:15079] starting screenresolution argv=screenresolution list 14:38:42 INFO - Available Modes on Display 0 14:38:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:38:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:38:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:38:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:38:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:38:42 INFO - Intel Iris: 14:38:42 INFO - Chipset Model: Intel Iris 14:38:42 INFO - Type: GPU 14:38:42 INFO - Bus: Built-In 14:38:42 INFO - VRAM (Dynamic, Max): 1536 MB 14:38:42 INFO - Vendor: Intel (0x8086) 14:38:42 INFO - Device ID: 0x0a2e 14:38:42 INFO - Revision ID: 0x0009 14:38:42 INFO - Displays: 14:38:42 INFO - Display: 14:38:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:38:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:38:42 INFO - Display Serial Number: 1600x1200 60 14:38:42 INFO - Main Display: Yes 14:38:42 INFO - Mirror: Off 14:38:42 INFO - Online: Yes 14:38:42 INFO - Rotation: Supported 14:38:42 INFO - Return code: 0 14:38:42 INFO - Running post-action listener: _resource_record_post_action 14:38:42 INFO - ##### 14:38:42 INFO - ##### Finished run-tests step (success) 14:38:42 INFO - ##### 14:38:42 INFO - Running post-run listener: _resource_record_post_run 14:38:42 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1003520; Write bytes: 698233344; Read time: 12; Write time: 2022 14:38:42 INFO - TinderboxPrint: CPU usage
53.6% 14:38:42 INFO - TinderboxPrint: I/O read bytes / time
1,003,520 / 12 14:38:42 INFO - TinderboxPrint: I/O write bytes / time
698,233,344 / 2,022 14:38:42 INFO - TinderboxPrint: CPU idle
2,252.4 (46.4%) 14:38:42 INFO - TinderboxPrint: CPU system
960.8 (19.8%) 14:38:42 INFO - TinderboxPrint: CPU user
1,636.4 (33.7%) 14:38:42 INFO - TinderboxPrint: Swap in / out
400,408,576 / 0 14:38:42 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400675328; Write bytes: 402192384; Read time: 29786; Write time: 784 14:38:42 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1003520; Write bytes: 295160320; Read time: 12; Write time: 1234 14:38:43 INFO - Running post-run listener: _upload_blobber_files 14:38:43 INFO - Blob upload gear active. 14:38:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:38:43 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 14:38:43 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:38:43 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:38:43 INFO - (blobuploader) - INFO - Open directory for files ... 14:38:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:38:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:38:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:44 INFO - (blobuploader) - INFO - Done attempting. 14:38:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:38:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:38:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:46 INFO - (blobuploader) - INFO - Done attempting. 14:38:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:38:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:38:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:48 INFO - (blobuploader) - INFO - Done attempting. 14:38:48 INFO - (blobuploader) - INFO - Iteration through files over. 14:38:48 INFO - Return code: 0 14:38:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:38:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:38:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/57b1a6bc34f6eaeaa26b0171602ed25f45dbdb289171c884b7ed6d64328f005c98b11b3225ed2bcff2befe3650430303cfdf7b4f157848d5529fda00add8e5e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a3f260192744827fa15d2ccbea5689945b75ec5578a43fe1039f3eaec3c18af687cb3af3006970790bb489256da0677333d165d4b487a72c1605ad87d4d552da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ceed469e0b9ac7237501dcfd22c162cac4e4562dc50209b0541995c4567e642ec2f3ad76f4590e2dcbc782331599c3aae10e31929552b54f993d08964264bc11"} 14:38:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:38:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:38:48 INFO - Contents: 14:38:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/57b1a6bc34f6eaeaa26b0171602ed25f45dbdb289171c884b7ed6d64328f005c98b11b3225ed2bcff2befe3650430303cfdf7b4f157848d5529fda00add8e5e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a3f260192744827fa15d2ccbea5689945b75ec5578a43fe1039f3eaec3c18af687cb3af3006970790bb489256da0677333d165d4b487a72c1605ad87d4d552da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ceed469e0b9ac7237501dcfd22c162cac4e4562dc50209b0541995c4567e642ec2f3ad76f4590e2dcbc782331599c3aae10e31929552b54f993d08964264bc11"} 14:38:48 INFO - Running post-run listener: copy_logs_to_upload_dir 14:38:48 INFO - Copying logs to upload dir... 14:38:48 INFO - mkdir: /builds/slave/test/build/upload/logs 14:38:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1296.238027 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-05-31 14:38:48.600465) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 14:38:48.604014) ========= 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.fHASSF1ENJ/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.cS18ClHoSw/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/57b1a6bc34f6eaeaa26b0171602ed25f45dbdb289171c884b7ed6d64328f005c98b11b3225ed2bcff2befe3650430303cfdf7b4f157848d5529fda00add8e5e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a3f260192744827fa15d2ccbea5689945b75ec5578a43fe1039f3eaec3c18af687cb3af3006970790bb489256da0677333d165d4b487a72c1605ad87d4d552da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ceed469e0b9ac7237501dcfd22c162cac4e4562dc50209b0541995c4567e642ec2f3ad76f4590e2dcbc782331599c3aae10e31929552b54f993d08964264bc11"} build_url:https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011197 build_url: 'https://queue.taskcluster.net/v1/task/NpYe8BKBR3Ka3bKh0k9yoA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/57b1a6bc34f6eaeaa26b0171602ed25f45dbdb289171c884b7ed6d64328f005c98b11b3225ed2bcff2befe3650430303cfdf7b4f157848d5529fda00add8e5e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a3f260192744827fa15d2ccbea5689945b75ec5578a43fe1039f3eaec3c18af687cb3af3006970790bb489256da0677333d165d4b487a72c1605ad87d4d552da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ceed469e0b9ac7237501dcfd22c162cac4e4562dc50209b0541995c4567e642ec2f3ad76f4590e2dcbc782331599c3aae10e31929552b54f993d08964264bc11"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 14:38:48.635298) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:38:48.635648) ========= 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.fHASSF1ENJ/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.cS18ClHoSw/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005198 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:38:48.684411) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 14:38:48.684756) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 14:38:48.685115) ========= ========= Total master_lag: 0.18 =========