builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0253 starttime: 1464817448.78 results: success (0) buildid: 20160601125549 builduid: 28de62a60f624693919506460f2479dd revision: b86cd69b94c7f4b31a1f665b91a44c84638bedd6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:08.781632) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:08.782141) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:08.782431) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:08.822116) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:08.822595) ========= 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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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-06-01 14:44:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2016-06-01 14:44:09 (18.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.455450 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:09.299032) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:09.299336) ========= 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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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.024791 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:09.347856) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-01 14:44:09.348203) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev b86cd69b94c7f4b31a1f665b91a44c84638bedd6 --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 b86cd69b94c7f4b31a1f665b91a44c84638bedd6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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-06-01 14:44:09,430 truncating revision to first 12 chars 2016-06-01 14:44:09,430 Setting DEBUG logging. 2016-06-01 14:44:09,431 attempt 1/10 2016-06-01 14:44:09,431 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/b86cd69b94c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 14:44:11,568 unpacking tar archive at: larch-b86cd69b94c7/testing/mozharness/ program finished with exit code 0 elapsedTime=2.400613 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-01 14:44:11.768656) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:11.769000) ========= script_repo_revision: b86cd69b94c7f4b31a1f665b91a44c84638bedd6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:11.769402) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:11.769667) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 14:44:11.785914) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-06-01 14:44:11.786261) ========= /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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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:44:11 INFO - MultiFileLogger online at 20160601 14:44:11 in /builds/slave/test 14:44:11 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:44:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:44:11 INFO - {'append_to_log': False, 14:44:11 INFO - 'base_work_dir': '/builds/slave/test', 14:44:11 INFO - 'blob_upload_branch': 'Larch', 14:44:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:44:11 INFO - 'branch': 'Larch', 14:44:11 INFO - 'buildbot_json_path': 'buildprops.json', 14:44:11 INFO - 'config_files': ('talos/mac_config.py',), 14:44:11 INFO - 'default_actions': ('clobber', 14:44:11 INFO - 'read-buildbot-config', 14:44:11 INFO - 'download-and-extract', 14:44:11 INFO - 'populate-webroot', 14:44:11 INFO - 'create-virtualenv', 14:44:11 INFO - 'install', 14:44:11 INFO - 'run-tests'), 14:44:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:44:11 INFO - 'download_minidump_stackwalk': True, 14:44:11 INFO - 'download_symbols': 'ondemand', 14:44:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:44:11 INFO - 'tooltool.py': '/tools/tooltool.py', 14:44:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:44:11 INFO - '/tools/misc-python/virtualenv.py')}, 14:44:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:44:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:44:11 INFO - 'installer_path': 'installer.exe', 14:44:11 INFO - 'log_level': 'info', 14:44:11 INFO - 'log_name': 'talos', 14:44:11 INFO - 'log_to_console': True, 14:44:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:44:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:44:11 INFO - 'opt_config_files': (), 14:44:11 INFO - 'pip_index': False, 14:44:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:44:11 INFO - 'cmd': ('bash', 14:44:11 INFO - '-c', 14:44:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:44:11 INFO - 'enabled': True, 14:44:11 INFO - 'halt_on_failure': False, 14:44:11 INFO - 'name': 'check_screen_resolution'},), 14:44:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:44:11 INFO - 'cmd': ('bash', 14:44:11 INFO - '-c', 14:44:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:44:11 INFO - 'enabled': True, 14:44:11 INFO - 'halt_on_failure': False, 14:44:11 INFO - 'name': 'check_screen_resolution'},), 14:44:11 INFO - 'run_cmd_checks_enabled': True, 14:44:11 INFO - 'sps_profile': False, 14:44:11 INFO - 'sps_profile_interval': 0, 14:44:11 INFO - 'suite': 'dromaeojs', 14:44:11 INFO - 'system_bits': '32', 14:44:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:44:11 INFO - 'title': 't-yosemite-r7-0253', 14:44:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:44:11 INFO - 'use_talos_json': True, 14:44:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:44:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:44:11 INFO - 'work_dir': 'build'} 14:44:11 INFO - ##### 14:44:11 INFO - ##### Running clobber step. 14:44:11 INFO - ##### 14:44:11 INFO - Running pre-action listener: _resource_record_pre_action 14:44:11 INFO - Running main action method: clobber 14:44:11 INFO - rmtree: /builds/slave/test/build 14:44:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:44:13 INFO - Running post-action listener: _resource_record_post_action 14:44:13 INFO - ##### 14:44:13 INFO - ##### Finished clobber step (success) 14:44:13 INFO - ##### 14:44:13 INFO - ##### 14:44:13 INFO - ##### Running read-buildbot-config step. 14:44:13 INFO - ##### 14:44:13 INFO - Running pre-action listener: _resource_record_pre_action 14:44:13 INFO - Running main action method: read_buildbot_config 14:44:13 INFO - Using buildbot properties: 14:44:13 INFO - { 14:44:13 INFO - "project": "", 14:44:13 INFO - "product": "firefox", 14:44:13 INFO - "script_repo_revision": "production", 14:44:13 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 14:44:13 INFO - "repository": "", 14:44:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 14:44:13 INFO - "buildid": "20160601125549", 14:44:13 INFO - "pgo_build": "False", 14:44:13 INFO - "basedir": "/builds/slave/test", 14:44:13 INFO - "buildnumber": 2, 14:44:13 INFO - "slavename": "t-yosemite-r7-0253", 14:44:13 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:44:13 INFO - "platform": "yosemite_r7", 14:44:13 INFO - "scheduler": "tests-larch-macosx64-talos", 14:44:13 INFO - "branch": "larch", 14:44:13 INFO - "repo_path": "projects/larch", 14:44:13 INFO - "revision": "b86cd69b94c7f4b31a1f665b91a44c84638bedd6", 14:44:13 INFO - "stage_platform": "macosx64", 14:44:13 INFO - "builduid": "28de62a60f624693919506460f2479dd", 14:44:13 INFO - "slavebuilddir": "test" 14:44:13 INFO - } 14:44:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:44:13 INFO - Running post-action listener: _resource_record_post_action 14:44:13 INFO - ##### 14:44:13 INFO - ##### Finished read-buildbot-config step (success) 14:44:13 INFO - ##### 14:44:13 INFO - ##### 14:44:13 INFO - ##### Running download-and-extract step. 14:44:13 INFO - ##### 14:44:13 INFO - Running pre-action listener: _resource_record_pre_action 14:44:13 INFO - Running main action method: download_and_extract 14:44:13 INFO - mkdir: /builds/slave/test/build/tests 14:44: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:44:13 INFO - https://queue.taskcluster.net/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:44:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:44:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:44:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/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:44:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/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:44:16 INFO - Downloaded 1199 bytes. 14:44:16 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:44:16 INFO - Using the following test package requirements: 14:44:16 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:44:16 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:44:16 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:44:16 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:44:16 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:44:16 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:44:16 INFO - u'jsshell-mac.zip'], 14:44:16 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:44:16 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:44:16 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:44:16 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:44:16 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:44:16 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:44:16 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:44:16 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:44:16 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:44:16 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:44:16 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:44:16 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:44:16 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:44:16 INFO - https://queue.taskcluster.net/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:44:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:44:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:44:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/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:44:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/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:44:18 INFO - Downloaded 18696997 bytes. 14:44:18 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:44:18 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:44:21 INFO - Return code: 0 14:44:21 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:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:21 INFO - https://queue.taskcluster.net/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:44:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:44:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:44:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/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:44:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/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:44:27 INFO - Downloaded 18696997 bytes. 14:44:27 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:44:27 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:44:31 INFO - Return code: 0 14:44:31 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:44:31 INFO - https://queue.taskcluster.net/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:44:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:44:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:44:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/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:44:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/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:44:32 INFO - Downloaded 10834063 bytes. 14:44:32 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:44:32 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:44:32 INFO - Return code: 0 14:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:32 INFO - https://queue.taskcluster.net/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:44:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:44:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:44:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:44:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RFF75Kp2SparVrd4n0AhrQ/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:44:38 INFO - Downloaded 111286075 bytes. 14:44:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:44:38 INFO - mkdir: /builds/slave/test/properties 14:44:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:44:38 INFO - Writing to file /builds/slave/test/properties/build_url 14:44:38 INFO - Contents: 14:44:38 INFO - build_url:https://queue.taskcluster.net/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:44:39 INFO - Running post-action listener: _resource_record_post_action 14:44:39 INFO - Running post-action listener: set_extra_try_arguments 14:44:39 INFO - ##### 14:44:39 INFO - ##### Finished download-and-extract step (success) 14:44:39 INFO - ##### 14:44:39 INFO - ##### 14:44:39 INFO - ##### Running populate-webroot step. 14:44:39 INFO - ##### 14:44:39 INFO - Running pre-action listener: _resource_record_pre_action 14:44:39 INFO - Running main action method: populate_webroot 14:44:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:44:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:44:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:44:39 INFO - '--tppagecycles', 14:44:39 INFO - '7'], 14:44:39 INFO - 'tests': ['tsvgm']}}, 14:44:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:44:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:44:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:44:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:44:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:44:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:44:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:44:39 INFO - 'talos_options': ['--disable-e10s'], 14:44:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:44:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:44:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:44:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:44:39 INFO - 'talos_options': ['--disable-e10s'], 14:44:39 INFO - 'tests': ['damp', 'tps']}, 14:44:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:44:39 INFO - 'tests': ['damp', 'tps']}, 14:44:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:44:39 INFO - 'tests': ['dromaeo_dom']}, 14:44:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:44:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:44:39 INFO - 'tests': ['a11yr', 14:44:39 INFO - 'ts_paint', 14:44:39 INFO - 'tpaint', 14:44:39 INFO - 'sessionrestore', 14:44:39 INFO - 'sessionrestore_no_auto_restore', 14:44:39 INFO - 'tabpaint']}, 14:44:39 INFO - 'other-e10s': {'tests': ['a11yr', 14:44:39 INFO - 'ts_paint', 14:44:39 INFO - 'tpaint', 14:44:39 INFO - 'sessionrestore', 14:44:39 INFO - 'sessionrestore_no_auto_restore', 14:44:39 INFO - 'tabpaint']}, 14:44:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:44:39 INFO - 'ts_paint', 14:44:39 INFO - 'tpaint', 14:44:39 INFO - 'sessionrestore', 14:44:39 INFO - 'sessionrestore_no_auto_restore', 14:44:39 INFO - 'tabpaint']}, 14:44:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:44:39 INFO - 'ts_paint', 14:44:39 INFO - 'tpaint', 14:44:39 INFO - 'sessionrestore', 14:44:39 INFO - 'sessionrestore_no_auto_restore', 14:44:39 INFO - 'tabpaint']}, 14:44:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:44:39 INFO - 'tests': ['a11yr', 14:44:39 INFO - 'ts_paint', 14:44:39 INFO - 'tpaint', 14:44:39 INFO - 'sessionrestore', 14:44:39 INFO - 'sessionrestore_no_auto_restore', 14:44:39 INFO - 'tabpaint']}, 14:44:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:44:39 INFO - 'tests': ['a11yr', 14:44:39 INFO - 'ts_paint', 14:44:39 INFO - 'tpaint', 14:44:39 INFO - 'sessionrestore', 14:44:39 INFO - 'sessionrestore_no_auto_restore', 14:44:39 INFO - 'tabpaint']}, 14:44:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:44:39 INFO - 'tests': ['tsvgx', 14:44:39 INFO - 'tsvgr_opacity', 14:44:39 INFO - 'tart', 14:44:39 INFO - 'tscrollx', 14:44:39 INFO - 'cart']}, 14:44:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:44:39 INFO - 'tsvgr_opacity', 14:44:39 INFO - 'tart', 14:44:39 INFO - 'tscrollx', 14:44:39 INFO - 'cart']}, 14:44:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:44:39 INFO - 'talos_options': ['--disable-e10s'], 14:44:39 INFO - 'tests': ['tp5o']}, 14:44:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:44:39 INFO - 'tests': ['tp5o']}, 14:44:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:44:39 INFO - 'talos_options': ['--disable-e10s', 14:44:39 INFO - '--xperf_path', 14:44:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:44:39 INFO - 'tests': ['tp5n']}, 14:44:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:44:39 INFO - 'talos_options': ['--xperf_path', 14:44:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:44:39 INFO - 'tests': ['tp5n']}}, 14:44:39 INFO - 'talos.zip': {'path': '', 14:44:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:44:39 INFO - Running post-action listener: _resource_record_post_action 14:44:39 INFO - ##### 14:44:39 INFO - ##### Finished populate-webroot step (success) 14:44:39 INFO - ##### 14:44:39 INFO - ##### 14:44:39 INFO - ##### Running create-virtualenv step. 14:44:39 INFO - ##### 14:44:39 INFO - Running pre-action listener: _resource_record_pre_action 14:44:39 INFO - Running main action method: create_virtualenv 14:44:39 INFO - Creating virtualenv /builds/slave/test/build/venv 14:44:39 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:44:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:44:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:44:39 INFO - Using real prefix '/tools/python27' 14:44:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:44:40 INFO - Installing distribute.............................................................................................................................................................................................done. 14:44:43 INFO - Installing pip.................done. 14:44:43 INFO - Return code: 0 14:44:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:44: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:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44: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:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feadb5dccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d059d30>, '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 0x10ce7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d050b20>, '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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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:44:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eqNPWzNZ0v/Render', 14:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:44:43 INFO - 'HOME': '/Users/cltbld', 14:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:44:43 INFO - 'LOGNAME': 'cltbld', 14:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:43 INFO - 'MOZ_NO_REMOTE': '1', 14:44:43 INFO - 'NO_EM_RESTART': '1', 14:44:43 INFO - 'PAGER': '/bin/cat', 14:44:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:43 INFO - 'PWD': '/builds/slave/test', 14:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:44:43 INFO - 'SHELL': '/bin/bash', 14:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QgIbiQXf3K/Listeners', 14:44:43 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 14:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:44:43 INFO - 'USER': 'cltbld', 14:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:43 INFO - 'XPC_FLAGS': '0x0', 14:44:43 INFO - 'XPC_SERVICE_NAME': '0', 14:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:43 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:44:43 INFO - Cleaning up... 14:44:43 INFO - Return code: 0 14:44:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:44: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:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44: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:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d1b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feadb5dccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d059d30>, '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 0x10ce7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d050b20>, '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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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:44:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eqNPWzNZ0v/Render', 14:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:44:43 INFO - 'HOME': '/Users/cltbld', 14:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:44:43 INFO - 'LOGNAME': 'cltbld', 14:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:43 INFO - 'MOZ_NO_REMOTE': '1', 14:44:43 INFO - 'NO_EM_RESTART': '1', 14:44:43 INFO - 'PAGER': '/bin/cat', 14:44:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:43 INFO - 'PWD': '/builds/slave/test', 14:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:44:43 INFO - 'SHELL': '/bin/bash', 14:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QgIbiQXf3K/Listeners', 14:44:43 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 14:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:44:43 INFO - 'USER': 'cltbld', 14:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:43 INFO - 'XPC_FLAGS': '0x0', 14:44:43 INFO - 'XPC_SERVICE_NAME': '0', 14:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:43 INFO - Downloading/unpacking psutil>=3.1.1 14:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:43 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:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:44:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:44:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:44:47 INFO - Installing collected packages: psutil 14:44:47 INFO - Running setup.py install for psutil 14:44:47 INFO - building 'psutil._psutil_osx' extension 14:44:47 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:44:47 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:44:48 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:44:48 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:44:48 INFO - building 'psutil._psutil_posix' extension 14:44:48 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:44:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:44:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:44:48 INFO - ^ 14:44:48 INFO - 1 warning generated. 14:44:48 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:44:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:44:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:44:48 INFO - Successfully installed psutil 14:44:48 INFO - Cleaning up... 14:44:48 INFO - Return code: 0 14:44:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:44:48 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:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:48 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:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:48 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 0x10d1b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feadb5dccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d059d30>, '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 0x10ce7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d050b20>, '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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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:44:48 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:44:48 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:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eqNPWzNZ0v/Render', 14:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:44:48 INFO - 'HOME': '/Users/cltbld', 14:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:44:48 INFO - 'LOGNAME': 'cltbld', 14:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:48 INFO - 'MOZ_NO_REMOTE': '1', 14:44:48 INFO - 'NO_EM_RESTART': '1', 14:44:48 INFO - 'PAGER': '/bin/cat', 14:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:48 INFO - 'PWD': '/builds/slave/test', 14:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:44:48 INFO - 'SHELL': '/bin/bash', 14:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QgIbiQXf3K/Listeners', 14:44:48 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 14:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:44:48 INFO - 'USER': 'cltbld', 14:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:48 INFO - 'XPC_FLAGS': '0x0', 14:44:48 INFO - 'XPC_SERVICE_NAME': '0', 14:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:44:51 INFO - Installing collected packages: mozsystemmonitor 14:44:51 INFO - Running setup.py install for mozsystemmonitor 14:44:51 INFO - Successfully installed mozsystemmonitor 14:44:51 INFO - Cleaning up... 14:44:51 INFO - Return code: 0 14:44:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d1b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feadb5dccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d059d30>, '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 0x10ce7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d050b20>, '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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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:44:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:44:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eqNPWzNZ0v/Render', 14:44:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:44:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:44:51 INFO - 'HOME': '/Users/cltbld', 14:44:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:44:51 INFO - 'LOGNAME': 'cltbld', 14:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:51 INFO - 'MOZ_NO_REMOTE': '1', 14:44:51 INFO - 'NO_EM_RESTART': '1', 14:44:51 INFO - 'PAGER': '/bin/cat', 14:44:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:51 INFO - 'PWD': '/builds/slave/test', 14:44:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:44:51 INFO - 'SHELL': '/bin/bash', 14:44:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QgIbiQXf3K/Listeners', 14:44:51 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 14:44:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:44:51 INFO - 'USER': 'cltbld', 14:44:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:44:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:51 INFO - 'XPC_FLAGS': '0x0', 14:44:51 INFO - 'XPC_SERVICE_NAME': '0', 14:44:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:51 INFO - Downloading/unpacking blobuploader==1.2.4 14:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:54 INFO - Downloading blobuploader-1.2.4.tar.gz 14:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:44:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:44:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:55 INFO - Downloading docopt-0.6.1.tar.gz 14:44:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:44:55 INFO - Installing collected packages: blobuploader, requests, docopt 14:44:55 INFO - Running setup.py install for blobuploader 14:44:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:44:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:44:55 INFO - Running setup.py install for requests 14:44:56 INFO - Running setup.py install for docopt 14:44:56 INFO - Successfully installed blobuploader requests docopt 14:44:56 INFO - Cleaning up... 14:44:56 INFO - Return code: 0 14:44:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:56 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 0x10d1b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feadb5dccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d059d30>, '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 0x10ce7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d050b20>, '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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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:44:56 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:44:56 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:44:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eqNPWzNZ0v/Render', 14:44:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:44:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:44:56 INFO - 'HOME': '/Users/cltbld', 14:44:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:44:56 INFO - 'LOGNAME': 'cltbld', 14:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:56 INFO - 'MOZ_NO_REMOTE': '1', 14:44:56 INFO - 'NO_EM_RESTART': '1', 14:44:56 INFO - 'PAGER': '/bin/cat', 14:44:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:56 INFO - 'PWD': '/builds/slave/test', 14:44:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:44:56 INFO - 'SHELL': '/bin/bash', 14:44:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QgIbiQXf3K/Listeners', 14:44:56 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 14:44:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:44:56 INFO - 'USER': 'cltbld', 14:44:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:44:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:56 INFO - 'XPC_FLAGS': '0x0', 14:44:56 INFO - 'XPC_SERVICE_NAME': '0', 14:44:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:44:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-TbbWQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:44:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-8R3ZXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:44:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-9wsUzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:44:56 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Hg4he1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:44:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-DqYEoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:44:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-vrEMbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:44:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-SKyvVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:44:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-TB8bbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:44:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Vf92M4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:44:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-tjJvNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:44:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-2eBRC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:44:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-DiAPSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:44:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-UpImdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:44:57 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-WOIQ3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:44:58 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-rSc0wW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:44:58 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-JtDadZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:44:58 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-cEJ6lD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:44:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:44:58 INFO - Running setup.py install for manifestparser 14:44:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:44:58 INFO - Running setup.py install for mozcrash 14:44:58 INFO - Running setup.py install for mozdebug 14:44:58 INFO - Running setup.py install for mozdevice 14:44:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:44:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:44:58 INFO - Running setup.py install for mozfile 14:44:59 INFO - Running setup.py install for mozhttpd 14:44:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:44:59 INFO - Running setup.py install for mozinfo 14:44:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:44:59 INFO - Running setup.py install for mozInstall 14:44:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:44:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:44:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:44:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:44:59 INFO - Running setup.py install for mozleak 14:44:59 INFO - Running setup.py install for mozlog 14:44:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:44:59 INFO - Running setup.py install for moznetwork 14:44:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:44:59 INFO - Running setup.py install for mozprocess 14:45:00 INFO - Running setup.py install for mozprofile 14:45:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:45:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:45:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:45:00 INFO - Running setup.py install for mozrunner 14:45:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:45:00 INFO - Running setup.py install for mozscreenshot 14:45:00 INFO - Running setup.py install for moztest 14:45:00 INFO - Running setup.py install for mozversion 14:45:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:45:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:45:00 INFO - Cleaning up... 14:45:00 INFO - Return code: 0 14:45:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:45: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:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:45: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:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:45:00 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 0x10d1b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feadb5dccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d059d30>, '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 0x10ce7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d050b20>, '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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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:45:00 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:45:00 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:45:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eqNPWzNZ0v/Render', 14:45:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:45:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:45:00 INFO - 'HOME': '/Users/cltbld', 14:45:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:45:00 INFO - 'LOGNAME': 'cltbld', 14:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:45:00 INFO - 'MOZ_NO_REMOTE': '1', 14:45:00 INFO - 'NO_EM_RESTART': '1', 14:45:00 INFO - 'PAGER': '/bin/cat', 14:45:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:45:00 INFO - 'PWD': '/builds/slave/test', 14:45:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:45:00 INFO - 'SHELL': '/bin/bash', 14:45:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QgIbiQXf3K/Listeners', 14:45:00 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 14:45:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:45:00 INFO - 'USER': 'cltbld', 14:45:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:45:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:45:00 INFO - 'XPC_FLAGS': '0x0', 14:45:00 INFO - 'XPC_SERVICE_NAME': '0', 14:45:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:45:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-0ecqjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:45:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-4I9NB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:45:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-E3YVF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:45:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-6FAlKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:45:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-ULZjjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:45:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Gy6yQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:45:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-nLZIpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:45:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-GDtzYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:45:01 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-lNymSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:45:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-FF7wzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:45:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-GD5_Xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:45:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-TBNYSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:45:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-ztrIvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:45:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-YXDNRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:45:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-2lKcyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:45:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-wyKyRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:45:02 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-e4tvNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:45:02 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:45:02 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:45:02 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:45:02 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:45:02 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:45:02 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:45:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:45:02 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:45:02 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:45:02 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:45:02 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:45:02 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:45:02 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:45:05 INFO - Downloading blessings-1.6.tar.gz 14:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:45:05 INFO - Installing collected packages: blessings 14:45:05 INFO - Running setup.py install for blessings 14:45:05 INFO - Successfully installed blessings 14:45:05 INFO - Cleaning up... 14:45:05 INFO - Return code: 0 14:45:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:45:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:45:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:45:06 INFO - Reading from file tmpfile_stdout 14:45:06 INFO - Current package versions: 14:45:06 INFO - blessings == 1.6 14:45:06 INFO - blobuploader == 1.2.4 14:45:06 INFO - docopt == 0.6.1 14:45:06 INFO - manifestparser == 1.1 14:45:06 INFO - mozInstall == 1.12 14:45:06 INFO - mozcrash == 0.17 14:45:06 INFO - mozdebug == 0.1 14:45:06 INFO - mozdevice == 0.48 14:45:06 INFO - mozfile == 1.2 14:45:06 INFO - mozhttpd == 0.7 14:45:06 INFO - mozinfo == 0.9 14:45:06 INFO - mozleak == 0.1 14:45:06 INFO - mozlog == 3.2 14:45:06 INFO - moznetwork == 0.27 14:45:06 INFO - mozprocess == 0.22 14:45:06 INFO - mozprofile == 0.28 14:45:06 INFO - mozrunner == 6.11 14:45:06 INFO - mozscreenshot == 0.1 14:45:06 INFO - mozsystemmonitor == 0.3 14:45:06 INFO - moztest == 0.7 14:45:06 INFO - mozversion == 1.4 14:45:06 INFO - psutil == 3.1.1 14:45:06 INFO - requests == 1.2.3 14:45:06 INFO - wsgiref == 0.1.2 14:45:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:45:06 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:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:45:06 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:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:45:06 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 0x10d1b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feadb5dccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d059d30>, '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 0x10ce7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d050b20>, '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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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:45:06 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:45:06 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:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eqNPWzNZ0v/Render', 14:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:45:06 INFO - 'HOME': '/Users/cltbld', 14:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:45:06 INFO - 'LOGNAME': 'cltbld', 14:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:45:06 INFO - 'MOZ_NO_REMOTE': '1', 14:45:06 INFO - 'NO_EM_RESTART': '1', 14:45:06 INFO - 'PAGER': '/bin/cat', 14:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:45:06 INFO - 'PWD': '/builds/slave/test', 14:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:45:06 INFO - 'SHELL': '/bin/bash', 14:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QgIbiQXf3K/Listeners', 14:45:06 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 14:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:45:06 INFO - 'USER': 'cltbld', 14:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:45:06 INFO - 'XPC_FLAGS': '0x0', 14:45:06 INFO - 'XPC_SERVICE_NAME': '0', 14:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 INFO - Cleaning up... 14:45:06 INFO - Return code: 0 14:45:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:45:06 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:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:45:06 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:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:45:06 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 0x10d1b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feadb5dccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d059d30>, '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 0x10ce7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d050b20>, '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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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:45:06 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:45:06 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:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eqNPWzNZ0v/Render', 14:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:45:06 INFO - 'HOME': '/Users/cltbld', 14:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:45:06 INFO - 'LOGNAME': 'cltbld', 14:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:45:06 INFO - 'MOZ_NO_REMOTE': '1', 14:45:06 INFO - 'NO_EM_RESTART': '1', 14:45:06 INFO - 'PAGER': '/bin/cat', 14:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:45:06 INFO - 'PWD': '/builds/slave/test', 14:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:45:06 INFO - 'SHELL': '/bin/bash', 14:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QgIbiQXf3K/Listeners', 14:45:06 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 14:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:45:06 INFO - 'USER': 'cltbld', 14:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:45:06 INFO - 'XPC_FLAGS': '0x0', 14:45:06 INFO - 'XPC_SERVICE_NAME': '0', 14:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:45:06 INFO - Downloading/unpacking jsonschema 14:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:45:09 INFO - Downloading/unpacking functools32 (from jsonschema) 14:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:45:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:45:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:45:09 INFO - Downloading functools32-3.2.3-2.tar.gz 14:45:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:45:09 INFO - warning: no files found matching '*.txt' 14:45:09 INFO - no previously-included directories found matching 'build' 14:45:09 INFO - no previously-included directories found matching 'dist' 14:45:09 INFO - no previously-included directories found matching '.git*' 14:45:09 INFO - Installing collected packages: jsonschema, functools32 14:45:09 INFO - Running setup.py install for functools32 14:45:09 INFO - warning: no files found matching '*.txt' 14:45:09 INFO - no previously-included directories found matching 'build' 14:45:09 INFO - no previously-included directories found matching 'dist' 14:45:09 INFO - no previously-included directories found matching '.git*' 14:45:09 INFO - Successfully installed jsonschema functools32 14:45:09 INFO - Cleaning up... 14:45:09 INFO - Return code: 0 14:45:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1b5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feadb5dccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d059d30>, '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 0x10ce7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d050b20>, '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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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:45:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eqNPWzNZ0v/Render', 14:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:45:09 INFO - 'HOME': '/Users/cltbld', 14:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:45:09 INFO - 'LOGNAME': 'cltbld', 14:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:45:09 INFO - 'MOZ_NO_REMOTE': '1', 14:45:09 INFO - 'NO_EM_RESTART': '1', 14:45:09 INFO - 'PAGER': '/bin/cat', 14:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:45:09 INFO - 'PWD': '/builds/slave/test', 14:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:45:09 INFO - 'SHELL': '/bin/bash', 14:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QgIbiQXf3K/Listeners', 14:45:09 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 14:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:45:09 INFO - 'USER': 'cltbld', 14:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:45:09 INFO - 'XPC_FLAGS': '0x0', 14:45:09 INFO - 'XPC_SERVICE_NAME': '0', 14:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:45:10 INFO - Downloading/unpacking flake8 14:45: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:45: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:45: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:45: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:45: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:45: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:45:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:45:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:45:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:45:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:45:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:45:13 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:45:13 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:45:13 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:45:13 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:45:13 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:45:13 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:45:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:45:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:45:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:45:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:45:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:45:13 INFO - no previously-included directories found matching 'docs/_build' 14:45:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:45:13 INFO - Running setup.py install for pep8 14:45:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:45:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:45:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:45:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:45:14 INFO - no previously-included directories found matching 'docs/_build' 14:45:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:45:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:45:14 INFO - Cleaning up... 14:45:14 INFO - Return code: 0 14:45:14 INFO - Running post-action listener: _resource_record_post_action 14:45:14 INFO - Running post-action listener: _start_resource_monitoring 14:45:14 INFO - Starting resource monitoring. 14:45:14 INFO - ##### 14:45:14 INFO - ##### Finished create-virtualenv step (success) 14:45:14 INFO - ##### 14:45:14 INFO - ##### 14:45:14 INFO - ##### Running install step. 14:45:14 INFO - ##### 14:45:14 INFO - Running pre-action listener: _resource_record_pre_action 14:45:14 INFO - Running main action method: install 14:45:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:45:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:45:14 INFO - Reading from file tmpfile_stdout 14:45:14 INFO - Detecting whether we're running mozinstall >=1.0... 14:45:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:45:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:45:14 INFO - Reading from file tmpfile_stdout 14:45:14 INFO - Output received: 14:45:14 INFO - Usage: mozinstall [options] installer 14:45:14 INFO - Options: 14:45:14 INFO - -h, --help show this help message and exit 14:45:14 INFO - -d DEST, --destination=DEST 14:45:14 INFO - Directory to install application into. [default: 14:45:14 INFO - "/builds/slave/test"] 14:45:14 INFO - --app=APP Application being installed. [default: firefox] 14:45:14 INFO - mkdir: /builds/slave/test/build/application 14:45:14 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:45:14 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:45:48 INFO - Reading from file tmpfile_stdout 14:45:48 INFO - Output received: 14:45:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:45:48 INFO - Running post-action listener: _resource_record_post_action 14:45:48 INFO - ##### 14:45:48 INFO - ##### Finished install step (success) 14:45:48 INFO - ##### 14:45:48 INFO - ##### 14:45:48 INFO - ##### Running run-tests step. 14:45:48 INFO - ##### 14:45:48 INFO - Running pre-action listener: _resource_record_pre_action 14:45:48 INFO - Running main action method: run_tests 14:45:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:45:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:45:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:45:48 INFO - 2016-06-01 14:45:48.865 screenresolution[1648:8162] starting screenresolution argv=screenresolution get 14:45:48 INFO - 2016-06-01 14:45:48.867 screenresolution[1648:8162] Display 0: 1600x1200x32@60 14:45:48 INFO - 2016-06-01 14:45:48.873 screenresolution[1649:8164] starting screenresolution argv=screenresolution list 14:45:48 INFO - Available Modes on Display 0 14:45:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:45:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:45:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:45:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:45:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:45:49 INFO - Intel Iris: 14:45:49 INFO - Chipset Model: Intel Iris 14:45:49 INFO - Type: GPU 14:45:49 INFO - Bus: Built-In 14:45:49 INFO - VRAM (Dynamic, Max): 1536 MB 14:45:49 INFO - Vendor: Intel (0x8086) 14:45:49 INFO - Device ID: 0x0a2e 14:45:49 INFO - Revision ID: 0x0009 14:45:49 INFO - Displays: 14:45:49 INFO - Display: 14:45:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:45:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:45:49 INFO - Display Serial Number: 1600x1200 60 14:45:49 INFO - Main Display: Yes 14:45:49 INFO - Mirror: Off 14:45:49 INFO - Online: Yes 14:45:49 INFO - Rotation: Supported 14:45:49 INFO - Return code: 0 14:45:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:45:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:45:49 INFO - Python 2.7.3 14:45:49 INFO - Return code: 0 14:45:49 INFO - grabbing minidump binary from tooltool 14:45:49 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:45:49 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 0x10d059d30>, '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 0x10ce7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d050b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:45:49 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:45:49 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:45:49 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:45:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:45:49 INFO - Return code: 0 14:45:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:45:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:45:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:45:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:45:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:45:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:45:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:45:50 INFO - Return code: 0 14:45:50 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/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--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:45:50 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/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0253 --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:45:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eqNPWzNZ0v/Render', 14:45:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:45:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:45:50 INFO - 'HOME': '/Users/cltbld', 14:45:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:45:50 INFO - 'LOGNAME': 'cltbld', 14:45:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:45:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:45:50 INFO - 'MOZ_NO_REMOTE': '1', 14:45:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:45:50 INFO - 'NO_EM_RESTART': '1', 14:45:50 INFO - 'PAGER': '/bin/cat', 14:45:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:45:50 INFO - 'PWD': '/builds/slave/test', 14:45:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:45:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:45:50 INFO - 'SHELL': '/bin/bash', 14:45:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QgIbiQXf3K/Listeners', 14:45:50 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 14:45:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:45:50 INFO - 'USER': 'cltbld', 14:45:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:45:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:45:50 INFO - 'XPC_FLAGS': '0x0', 14:45:50 INFO - 'XPC_SERVICE_NAME': '0', 14:45:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:45:50 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/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--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:45:50 INFO - mozversion application_buildid: 20160601125549 14:45:50 INFO - mozversion application_changeset: b86cd69b94c7f4b31a1f665b91a44c84638bedd6 14:45:50 INFO - mozversion application_display_name: Nightly 14:45:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:45:50 INFO - mozversion application_name: Firefox 14:45:50 INFO - mozversion application_remotingname: firefox 14:45:50 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 14:45:50 INFO - mozversion application_vendor: Mozilla 14:45:50 INFO - mozversion application_version: 49.0a1 14:45:50 INFO - mozversion platform_buildid: 20160601125549 14:45:50 INFO - mozversion platform_changeset: b86cd69b94c7f4b31a1f665b91a44c84638bedd6 14:45:50 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 14:45:50 INFO - mozversion platform_version: 49.0a1 14:45:50 INFO - using testdate: 1464817550 14:45:50 INFO - actual date: 1464817550 14:45:50 INFO - starting webserver on 'localhost:49275' 14:45:50 INFO - SUITE-START | Running 2 tests 14:45:50 INFO - TEST-START | dromaeo_css 14:45:50 INFO - operating with platform_type : mac_ 14:45:50 INFO - Initialising browser for dromaeo_css test... 14:45:50 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmp1O6CQ7/profile http://localhost:49275/getInfo.html) 14:45:53 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 14:45:53 INFO - PROCESS | 1667 | colorDepth:24 14:45:53 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 14:45:53 INFO - PROCESS | 1667 | __metrics 14:45:53 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:45:53 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:45:53 INFO - TEST-INFO | 1667: exit 0 14:45:53 INFO - Browser initialized. 14:45:53 INFO - Running cycle 1/1 for dromaeo_css test... 14:45:53 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmp1O6CQ7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:46:05 INFO - PROCESS | 1670 | RSS: Main: 241676288 14:46:05 INFO - PROCESS | 1670 | 14:46:57 INFO - PROCESS | 1670 | 1464817617040 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:49:09 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 14:49:10 INFO - PROCESS | 1670 | RSS: Main: 370536448 14:49:10 INFO - PROCESS | 1670 | 14:52:13 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 14:52:14 INFO - PROCESS | 1670 | RSS: Main: 380166144 14:52:14 INFO - PROCESS | 1670 | 14:55:16 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 14:55:17 INFO - PROCESS | 1670 | RSS: Main: 372789248 14:55:17 INFO - PROCESS | 1670 | 14:58:19 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 14:58:20 INFO - PROCESS | 1670 | RSS: Main: 371736576 14:58:20 INFO - PROCESS | 1670 | 15:01:23 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 15:01:24 INFO - PROCESS | 1670 | RSS: Main: 345112576 15:01:24 INFO - PROCESS | 1670 | 15:04:27 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 15:04:27 INFO - PROCESS | 1670 | RSS: Main: 387645440 15:04:27 INFO - PROCESS | 1670 | 15:04:27 INFO - PROCESS | 1670 | __start_tp_report 15:04:27 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 15:04:27 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 15:04:27 INFO - PROCESS | 1670 | |i|pagename|runs| 15:04:27 INFO - PROCESS | 1670 | |0;dojo.html;3477.3393055319493;3490.982545087274;3516.261585067136;3538.310029544237;3565.572131344239;5567;5642.181883626871;5829.5919285650025;5951.375105613908;5995.520358371331;5524.558035357172;5590.133529302964;5616.157576363547;5641.8589535261635;5911.674857882826;5236.345456817898;5243;5318.441563635821;5326.680399176043;5436.103042997901;5425.945740542603;5431;5436.401995780461;5458.781648734046;5485.232067510552;4410.25025745624;5214.582833373321;5286.60350473716;5299.152135658276;5359.5712343012665;5329;5351.170568561879;5358.946410535884;5361.758720857571;5373.516383525502;5242.135047717122;5300.125479295912;5320;5331.893362132774;5337.226102215196;5682.431756824326;5711.600187986843;5755.107958266475;5792.362840087587;5839.357670656224;6243.469305109071;6251.28110267316;6269.655168965705;6269.87460250797;6273.592216505913;6102.664353460558;6238.189035425411;6248.750049997994;6266.310705822356;6290.937090629081;5822.301323841166;5898.64608123514;5914.556408269397;5978.85052873673;6005.6997150142315;6321.367863213642;6323.30443651198;6326.905096423558;6329.841753956096;6333.334999825044;6306.810795676137;6307.747690092345;6316.968415157895;6323.588966717149;6329.588576742497;6182.072689096672;6188.44304012641;6240.5319601030105;6260.7495700171485;6261.405166509175;6273.435390814849;6281.560290779602;6287.685615719196;6293;6298.748050077946;5187.351581052369;6270.153529273581;6275.497960163176;6310.400511951358;6328.398802113792;4342.34864770287;4899.706017638881;4951.10880041596;4993.950060499421;4996.150654388763;4528.230200865861;4538.659600529973;4573.405457290531;4590.724556526619;4591.380163677861;4874.439439464436;5808.680522571215;5826.883462330815;5842.065269556851;5915.733791979371;6214.502839772807;6219.533534984804;6231.719572619243;6233.1273621693;6235.844103897439;5765.913511297334;5861.765529378862;5869.4717475427415;5875.912956103135;5901.645901245853;5353.652012619167;5403.189521571717;5423.918641220384;5432.592555558349;5485.61600687956;6369.554131210771;6373.936260637427;6380.457661098859;6380.617162970144;6393;6148.292946311231;6236.656983865931;6251.9062214067735;6259.561830671809;6265.8120256392385;5357.223202635674;6132.110769108063;6164.3835616438;6249.875002500015;6376.585521941059;6171.783987560414;6202.2867370253;6202.317745047951;6218.81343559694;6227.937720622917;5461.699606521679;6196.628202307876;6215.81352559424;6218.06728990655;6237.438630523275;6171.506279497719;6204.069389591597;6257.655828929453;6278.497720182283;6293.716782744696;6060.454559089527;6062;6104.337893874414;6116.785912493041;6266.122742816101;6023.066424704178;6076.058210977306;6077.361877002851;6080.0575910733905;6088.056351265561;6117.10892948092;6130.092410195535;6152.138700582012;6153.630782153085;6156.199694039925;5970.0746384310505;6045.879082418415;6052.334243233329;6082.330943596113;6123.387661233841 15:04:27 INFO - PROCESS | 1670 | |1;ext.html;502.3369152718412;508.35692848546597;525.9053370393327;534.7406507843693;536.5948708724912;31034.68965310346;31271.218219544524;32516;32517.187070323253;32711.67288327116;32995.505067424005;38813.41779873304;45074.09851802962;45493.77253113738;47738.761306193504;9457.196138328232;9636.903630963705;9647.758806029835;9663.516824158763;9682.56428460721;9468.053194680511;9601.951990240073;9608.183304419133;9623.615055397775;9660;8573.52845593492;8806.031336552975;11450.48472818721;11657.766844663101;11675.591354302602;11758.05935525156;11835.40822958853;11864.822027669592;11877.16859819813;11900.642980710592;8511.148885111439;11353;11598;11754.941225293904;11755.764884702343;41195.1760964782;41804.790976044926;41833;41854.79072604617;42024.78987605073;3515.3145136698354;3540.433530635086;3639.4722765199162;3659.2315613721153;3865.871833084948;31760.841195794103;31918.840405797822;31979.360412791615;32437.837810810794;33273.833630831934;3275.5250488679008;3292.983535082333;3297.406466835969;3332.733381329512;3379.290349026707;507190;507760;509366;509690.4515477436;510993;54437.91124177494;61623;62631.37368626255;62753.68623156855;64640.03039954404;68549.65725171342;68803.65598171977;68824.31175688279;68934;68953;16901.408450704166;17179.05515196676;17186.31254749821;17206.655866882844;17251.482455526355;13146.60560183196;13165.47338106465;13185.934070329778;13196;13297.202167869766;15741.763873541906;16090.63229625495;16118.95226810277;17992.460226193234;18256.178471968793;6042.486388657014;7100.396466300319;7212.170600380919;7254.528455650366;7297.452691048086;915.6428992692855;952.9809403811885;1116.4752566293828;1123.3596849795538;1179.2747460311846;27642.170734877986;30027;30175.094747157622;30571.694283057328;31397.52903706446;1032.4115254304975;1083.5665733706423;1145.0839328537138;1170.9121815863843;1196.174639498743;16046.438374656831;16111.436099736453;16120.919395403182;16327.529298971815;16505.009699418075;665797;671365;673725;675001;675003;53536.19695704568;54419;61925.380746191964;62012.379876201565;62293.68853155796;1302.583173384508;1328.5682153300215;1329.8005299205197;1330.5476138113088;1443.2855736410545;30501.23746906345;31933.36133277368;32135.357292854478;32296.515552266264;33176;53702.462975369745;54121.45878541322;54600.45399545954;54653.72673136609;54658;475432;581818;586803;586977;587636;35251.47122793109;36688.899333020054;45162.77418612886;45858.312125318815;46061.23632582056;49423;49512.50487495079;49584;49799.00401991868;50570.49429505805;40540.797296013334;40594.79702601468;41106.9723256909;41376.1724765509;41731.58268417277;39705.60294397019;39890.800545998245;40315.798421007705;41158.794206028775;41313;32155.035348939567;38923.02692432598;39419.605803941595;39762.801185993885;39897.60102398939;29555.704442955295;31006;33204.66795332016;35634.64365356313;35661.64338356687;24767.752322476543;25748.227553173434;25820;26111.216663500127;26415.471690565697 15:04:27 INFO - PROCESS | 1670 | |2;jquery.html;382.13637179973256;385.09118480386286;385.6625452728861;399.37497815918107;404.48832227232555;17965.73051404227;19850.305239316625;20251.08870100845;21714.674279885796;21794.673079903794;11893.405329733521;12367.938160309208;12392.079227719696;12753.681157971056;21561.353159405204;5266.552343050836;5433.402325744165;5438.395673276393;5448.073827449343;5478.91781623276;3860.884173474786;4075.7758323292205;4207.990082380222;6929.72281108755;6946.062281591996;6346.2067241594805;6483.675816209195;6498.350164983487;6523.7064332105165;6887.4158943443035;6385.042243663464;6485.189351331083;6493.70778314977;6590.143281373391;6776.966115169442;3829.4447305140875;4029.9194016119513;4062.329715596923;4552.795124219418;5947.167396564484;13366.264855432948;13507.594772156852;13741.381637826322;13919.373628186655;13925.025248232527;2211.6903633491324;2219.5671843990426;2310.353101131686;2332.6151185054446;2508.611165269386;9096.317776166814;9281.21109705676;9309.953450232706;9614.807703845883;9651.613935442574;1792.4085051932832;1808.4032269351083;1869.5699989002605;1870.5978214683907;1926.2776458827939;120817.39591302075;124560.37719811434;127794;128018.3599082008;128152;8348.749537513884;11611.76776464466;11631;11641.53433862636;11981.161318707607;14918.179500127597;14918.328675209425;14942;14951.476698315506;14992.550223493312;8643.438176518506;8885.111488851064;8891.688790892416;8989.41568798026;9012.774680633036;8863.257543908298;8873.467591944504;8880.333974951775;8995.145461181177;9035.909640903637;3859.517560304962;4051.503169272981;4442.822287108479;4568.817247310137;4579.702319349299;1714.9024624240496;1900.686386746199;1911.9102111796142;1955.2081407030175;1960.362882063335;858.2618947704968;938.15565990609;970.815545046439;993.4833886379042;995.9402435853872;16688.499345019667;19960.33248948519;21407.78592214089;21426.357209283433;21937.122515099218;931.2968708625081;945.4421891084294;959.7792507723262;970.9854352184723;1027.686555600549;10821.567137314576;11375.374354410587;11380.886191138148;11389.544418223344;11421;540379;566505;569831.1508442514;594010;602599;135495;138193;141689;142172.28913855363;143213;983.7638966647953;989.8416253399422;993.215359865698;1015.8425444056184;1016.207358260558;18457.446276611725;18687.43937681872;18708.719369209746;18743.81256187475;18844.6231075375;36817.26365472729;46773;48273.758631208024;52278.738606306724;55398;80205;80823;80856.59571702103;81592;82767;7134.500584959211;7533.54798712079;7533.886991695019;8165.959170204111;8612.569371531274;9370.156685898302;12303.323317217639;12332.630021099381;12901;13580.660483487993;7078.575285482888;9878.259130565093;10903.83644245321;11681.189415640589;11687.88312116868;5659.660420374791;8569.014563325298;11397.772044559228;11515.596954106566;11606.825897611712;11594.76810463803;11652.184347095623;11765.823512647488;11786.351750653796;11850;7196.928030719626;7478.4765066444825;7531.510451820614;7640.235976402538;7892.84214315699;10381.429021403896;11666.591669291873;11761.823572646246;11786.823197651871;11805.76388472243 15:04:27 INFO - PROCESS | 1670 | |3;mootools.html;1388.7569675306818;1561.5939855637534;1565.178281402264;1794.9371771987983;1957.0508303472823;7873.055233371996;8533.189347012032;8750;8757.386982911199;8901.243394311492;8562.571871406435;8757.781055473617;8766.868496972551;8773.25427338677;8781.473111613308;2094.1728017433106;2184.4757258257982;2343.8476499027593;2385.7614238576175;2415.9516809663796;2393.8563686178886;2446.926592202237;2448.6449464827588;2451.595486744685;2456.9017239310406;2359.6932398788135;2385.988070059656;2406.7352591214953;2407.987960060197;2410.9035638574437;2090.790920907912;2307.284741730069;2322.477442575424;2381.1903952656007;2389.8685572293516;2402.1232250228713;2421.83047186697;2422.3770074050253;2423.491066875958;2426.46617744096;8639.827203455958;8698.913010869874;8716.869246961302;8723.25852302555;8755.781105472413;4881.194602890519;5048.469910659364;5159.793608255665;5201.973990130063;5330.946690533084;3462.3940810358085;3566.1797786509246;3653.30587188436;3657.213699054689;3657.8536858525626;1058.745900983766;1072.399456304472;1082.8213344798098;1089.967300980972;1094.4965315954591;11529.538818447252;11540.192186546945;11540.596079137189;11543.134264930164;11562.884371156264;4083.959160408358;4666.416697912761;4698.436187657435;4822.927656085161;4835.492273311322;9883.752906177404;9891.455969921722;9904;9913.306068575275;9919.355241909252;5288.312519372534;5364.222187782828;5367.78528858849;5371.301730775052;5371.704556249366;4397.582229688174;4558.703684260512;4720.409948756405;4855.150348688965;4863.197572400585;3471.878484253039;3934.9213015739524;3959.3269144245546;4052.3516237401873;4095.488063992001;1279.9296038717773;1288.903332250085;1290.2258644813226;1303.4720938020116;1308.5681725030718;918.9862152067724;927.2535608834953;934.9439033658002;937.1706040154512;1000.7898341348252;5799.159121927381;5888.388587822781;5954.642721436728;6015.39846015395;6105.297890742621;405.8417217285261;444.293573218584;451.4717780197177;455.92933095370273;459.06580109477056;3915.8433662653742;4000.19996000801;4054.2296963576828;4066.6543343816106;4077.571854955187;10059.647912323033;10844.915508449092;10851.131909447226;10852.240343175901;10872.347659140318;10393.53229104692;10645.20160987929;10730.678079657466;10814.35113893169;10820.24258301927;7514.4364172687265;7860.882086768703;8190.918090819135;8275;8339.45793523419;5772.307323121252;5835;5835.9282454729655;5985.940140598713;6015.879682406415;9689.12797848197;10016.248781341574;10144.746381340525;10155.7461063474;10178.898211017795;10309.896901031194;10317.587296508356;10324.741881453023;10331.225158113322;10334.431606261427;5784.652920824596;6893;7161.713531458683;7520.398368130646;7611.809704757426;7182.676779545038;8492.74521764348;8498.830023399621;8505.95747021261;8517.020542637429;7253.492255542062;7441.702331906879;7502.024736784183;7598.468107232441;7677.654505547152;7206.783796486114;7345.816354591178;7393.778186654409;7468.887966680395;7545.396368290414;7033.156021277479;7066.081409416743;7140.964560138855;7149.82125446868;7154.284571542804;4854.975725121493;4977;5000.67495612784;5079.415867175322;5132.30713853625;5039.470855560114;5100.336956195671;5161.148410512374;5183.170692689152;5266.473352664703 15:04:27 INFO - PROCESS | 1670 | |4;prototype.html;261.7003530957054;302.16602177988744;310.5279974438853;317.10892392377434;317.6997737138404;4441.290812604313;4999.975000125003;5083.339165908426;5286.3392075990505;5374.7043912584795;4629.351890735291;4811.30236115763;4992.627027567421;5163.432022477534;5272.340957380327;3397.59228892532;4017.437558741764;4026.6242916679153;4030.496187976503;4061.431399604065;3065.2183693158327;3239;3442.122258823996;4045.0898547826796;4076.673866090706;3858.6527212550864;3950.170464202521;3993.9600603993877;4040.4949381327333;4056.5132184137947;3432.6567343265724;3528.5060091587197;3943.895709201414;4022.416749571325;4030.45588845505;2930.750886174678;3246.7077962983444;3482.181687303482;3487.511748355232;3504.8948531544092;4559.746069830823;4717.716936983792;4754.811297175706;5096.949030509684;5164.741762911839;3674.2835147146316;3901.9219615607813;4196.769177695225;4561.703489273186;4592.357070010201;5327.627066105374;5523.2543606613;5529.225908372831;5556.91664625031;5576.804811831607;2923.532234842436;3186.5379519969474;3776.509053823013;3790.3556395412847;3834.175652234755;4702.858914232578;5774.740136693817;5788.308436577012;5824.563157763142;5844.532437404998;3574.410222313289;3823.0824602095854;3825.5983121771815;3826.6556009959236;3832.1222600925344;3331.3836940166148;3880.825362858678;3895;3898.688104951597;3918.7648741075627;3872.206197729459;4274.422952901381;4323.48118225815;4390.648748100144;4531.705439146445;5524.33707955048;5575.215930902073;5578.581606379537;5580.246666700024;5617.662940223599;1145.5360578965533;1213.9635810925686;1238.8017917133216;1295.352323838081;1330.447864136392;759.8366351234511;775.2557544756984;811.1685522339649;811.8822770698334;816.9101398846122;4075.4294398784195;4264.402983582301;4300.397944287803;4306.698531102793;4328.5455027222315;3922.156736301709;4124.360409338026;4200.227849619237;4281.935770963438;4444.82220711168;3964.6520183137873;3972.152948878785;4119.814608342632;4247.405363249148;4283;2247.3033359658557;2313.6760853480528;2390.521895620848;2406.9157579484986;2535.404180017694;4584.9541504585195;4671.944344488482;4750.904981900343;4878.341363936208;4886.511348865085;4516.525764794715;4616.376789133491;4699;4793.32893394925;4805.975970120127;4568.588827005586;4897.282008485254;4911.214205727067;4943.7640589852535;4980.90038199234;3813.4089216171537;4128.050548373951;4480.686351955332;4505.617022552989;4664.696794708333;4094.733842300241;4140.23405669952;4202.747835129902;4393.330534396909;4482.618977386958;4079.9075000728635;4361.5202327744555;4646.907061858677;4737.549932756383;4890.902181956412;3052.3974913170764;3189.6491385947065;3289.0461766087565;3313.5526703894666;3358.3619112368574;4540.230459419818;4724.976375118103;4766.6901651392545;4900.093482705711;5165.745580284397;4895.996320754308;5261;5290.947090529045;5427.918581221206;5539.9169012464035;5510.614257001971;5520.723963801714;5526.806561770319;5530.8429712413;5540.501354878081;4457.2839456808715;5090;5293.470652934676;5394.757235924471;5435.728213589226;4912.508749125059;5324.9733751331;5480.342358916796;5540.556755459635;5547.7780888764;5398.838034858961;5422.945770542244;5429.674219546682;5451.536619387396;5459.754311055933 15:04:27 INFO - PROCESS | 1670 | |5;yui.html;723.8371366442551;759.4493991855906;792.7106606088779;795.9811441355064;812.5693382507274;15407.614809629766;15784.131872746995;15937.601559961007;15953.042817430947;15978.201089945514;14358.569242922704;14787.926060369684;14790.630234244149;14807.185604791732;14812.851871481282;702;728.420905380224;877.0878286581947;884.9159329863669;891.3315013739696;873.9388242823005;902.62992173209;905.3933864310894;905.425055090016;907.8184363127368;947.2516711797672;952.4951775558965;953.2231231546302;953.5280036382001;953.6995846308425;742.2354974376401;792.2829838995718;909.7816524034184;947.2895328503588;948.2556193388192;691.5401258163315;694.4756708684961;694.5173104692241;695.0963747128762;695.1345574759457;15037;15094.773578396333;15106;15107.924460377737;15108.54674359771;13880;14127.646808829759;14148.292585370793;14308.28458577067;14379.928100359535;12884.677883052906;12926.15979961309;13139.474421023147;13207.009474289467;13522.256275904821;4515.14212299662;4581.862544123648;4583.495815460296;4588.655850811169;4799.568038876518;100586;102914;103033.48483257536;103457;103707.96292037134;10368.637097701545;11072.003519683267;11252.66242012741;11357.829632555518;11742.823857642143;22546.549069018856;22561.88719056427;22566.774332256467;22589.322320330415;22601.660975085386;8049.114597394282;8063.233992770677;8083.434159608771;8319.334453243726;8326.583670816435;4423;4434.11317736454;4453.242948698729;4644.581987621063;4736.621070314367;3258.853351599136;3438.896833095011;3442.5524681791208;3589.7307701921936;3594.982025089858;634.6985182038495;659.7690808217181;662.6189940784069;672.7510820996165;676.6785776756002;531.9760610772524;595.6634501506635;630.2122347066168;654.7773756922574;656.7569999100364;9222.174174532474;9263;9264.11990860867;9314.813703726024;9496.620135194653;571.8999175144334;596.6837576084682;596.8179705189958;638.3074364314771;642.3865208725656;4006.7996600169877;4323.7621930793475;4448.243798554189;4542.409486766699;4569.634429245652;115073;124504.37747811383;125152;143182.13635727228;143211;119019;119075.21387179199;119177.40411297888;121988;125422;106030;106730.93269067365;107783.46108269409;107992;109540;9361.953190234006;9389.671361502587;9421.38760980534;9436.103570160823;9463.479508626817;58474.24577262689;60920;61361.77276454535;61715.6914215444;62197.37802622097;85325.7201141971;87360;90848;91515.54242228746;92790.53604731933;12709.936450318059;12856.807147892601;12975.351232438528;12983.091183617056;13101.527421225126;12654.367281635698;14786.482473113389;14994.175320357484;15027.023243489575;15311.387544498095;13844.238566878515;13915.512957046454;13945.3724582392;14034.965625916502;14142.50501232452;13899.861001389856;14245.21651309188;14280.571582852532;14359.994800363875;14378.928105359406;13504.797428038784;13678.93160534191;13690;14176.929115354356;14312.141271523742;10392.48037598102;10842.80729119812;11218.382988935688;11398.772024559297;11779.52881884715;10749.570017199225;10802.297850639683;11403.258788178742;11430.428478575872;11434.406320944665 15:04:27 INFO - PROCESS | 1670 | __end_tp_report 15:04:27 INFO - PROCESS | 1670 | __start_cc_report 15:04:27 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1298 15:04:27 INFO - PROCESS | 1670 | __end_cc_report 15:04:27 INFO - PROCESS | 1670 | __startTimestamp1464818667854__endTimestamp 15:04:27 INFO - PROCESS | 1670 | 15:04:27 INFO - PROCESS | 1670 | ------- Summary: start ------- 15:04:27 INFO - PROCESS | 1670 | Number of tests: 6 15:04:27 INFO - PROCESS | 1670 | 15:04:27 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5788.88 Median:6060.45 stddev:629.07 (10.4%) stddev-sans-first:604.29 15:04:27 INFO - PROCESS | 1670 | Values: 3477.3 3491.0 3516.3 3538.3 3565.6 5567.0 5642.2 5829.6 5951.4 5995.5 5524.6 5590.1 5616.2 5641.9 5911.7 5236.3 5243.0 5318.4 5326.7 5436.1 5425.9 5431.0 5436.4 5458.8 5485.2 4410.3 5214.6 5286.6 5299.2 5359.6 5329.0 5351.2 5358.9 5361.8 5373.5 5242.1 5300.1 5320.0 5331.9 5337.2 5682.4 5711.6 5755.1 5792.4 5839.4 6243.5 6251.3 6269.7 6269.9 6273.6 6102.7 6238.2 6248.8 6266.3 6290.9 5822.3 5898.6 5914.6 5978.9 6005.7 6321.4 6323.3 6326.9 6329.8 6333.3 6306.8 6307.7 6317.0 6323.6 6329.6 6182.1 6188.4 6240.5 6260.7 6261.4 6273.4 6281.6 6287.7 6293.0 6298.7 5187.4 6270.2 6275.5 6310.4 6328.4 4342.3 4899.7 4951.1 4994.0 4996.2 4528.2 4538.7 4573.4 4590.7 4591.4 4874.4 5808.7 5826.9 5842.1 5915.7 6214.5 6219.5 6231.7 6233.1 6235.8 5765.9 5861.8 5869.5 5875.9 5901.6 5353.7 5403.2 5423.9 5432.6 5485.6 6369.6 6373.9 6380.5 6380.6 6393.0 6148.3 6236.7 6251.9 6259.6 6265.8 5357.2 6132.1 6164.4 6249.9 6376.6 6171.8 6202.3 6202.3 6218.8 6227.9 5461.7 6196.6 6215.8 6218.1 6237.4 6171.5 6204.1 6257.7 6278.5 6293.7 6060.5 6062.0 6104.3 6116.8 6266.1 6023.1 6076.1 6077.4 6080.1 6088.1 6117.1 6130.1 6152.1 6153.6 6156.2 5970.1 6045.9 6052.3 6082.3 6123.4 15:04:27 INFO - PROCESS | 1670 | 15:04:27 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:72277.10 Median:39171.32 stddev:156560.58 (399.7%) stddev-sans-first:156907.03 15:04:27 INFO - PROCESS | 1670 | Values: 502.3 508.4 525.9 534.7 536.6 31034.7 31271.2 32516.0 32517.2 32711.7 32995.5 38813.4 45074.1 45493.8 47738.8 9457.2 9636.9 9647.8 9663.5 9682.6 9468.1 9602.0 9608.2 9623.6 9660.0 8573.5 8806.0 11450.5 11657.8 11675.6 11758.1 11835.4 11864.8 11877.2 11900.6 8511.1 11353.0 11598.0 11754.9 11755.8 41195.2 41804.8 41833.0 41854.8 42024.8 3515.3 3540.4 3639.5 3659.2 3865.9 31760.8 31918.8 31979.4 32437.8 33273.8 3275.5 3293.0 3297.4 3332.7 3379.3 507190.0 507760.0 509366.0 509690.5 510993.0 54437.9 61623.0 62631.4 62753.7 64640.0 68549.7 68803.7 68824.3 68934.0 68953.0 16901.4 17179.1 17186.3 17206.7 17251.5 13146.6 13165.5 13185.9 13196.0 13297.2 15741.8 16090.6 16119.0 17992.5 18256.2 6042.5 7100.4 7212.2 7254.5 7297.5 915.6 953.0 1116.5 1123.4 1179.3 27642.2 30027.0 30175.1 30571.7 31397.5 1032.4 1083.6 1145.1 1170.9 1196.2 16046.4 16111.4 16120.9 16327.5 16505.0 665797.0 671365.0 673725.0 675001.0 675003.0 53536.2 54419.0 61925.4 62012.4 62293.7 1302.6 1328.6 1329.8 1330.5 1443.3 30501.2 31933.4 32135.4 32296.5 33176.0 53702.5 54121.5 54600.5 54653.7 54658.0 475432.0 581818.0 586803.0 586977.0 587636.0 35251.5 36688.9 45162.8 45858.3 46061.2 49423.0 49512.5 49584.0 49799.0 50570.5 40540.8 40594.8 41107.0 41376.2 41731.6 39705.6 39890.8 40315.8 41158.8 41313.0 32155.0 38923.0 39419.6 39762.8 39897.6 29555.7 31006.0 33204.7 35634.6 35661.6 24767.8 25748.2 25820.0 26111.2 26415.5 15:04:27 INFO - PROCESS | 1670 | 15:04:27 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34277.71 Median:385.38 stddev:96894.63 (25142.8%) stddev-sans-first:97133.02 15:04:27 INFO - PROCESS | 1670 | Values: 382.1 385.1 385.7 399.4 404.5 17965.7 19850.3 20251.1 21714.7 21794.7 11893.4 12367.9 12392.1 12753.7 21561.4 5266.6 5433.4 5438.4 5448.1 5478.9 3860.9 4075.8 4208.0 6929.7 6946.1 6346.2 6483.7 6498.4 6523.7 6887.4 6385.0 6485.2 6493.7 6590.1 6777.0 3829.4 4029.9 4062.3 4552.8 5947.2 13366.3 13507.6 13741.4 13919.4 13925.0 2211.7 2219.6 2310.4 2332.6 2508.6 9096.3 9281.2 9310.0 9614.8 9651.6 1792.4 1808.4 1869.6 1870.6 1926.3 120817.4 124560.4 127794.0 128018.4 128152.0 8348.7 11611.8 11631.0 11641.5 11981.2 14918.2 14918.3 14942.0 14951.5 14992.6 8643.4 8885.1 8891.7 8989.4 9012.8 8863.3 8873.5 8880.3 8995.1 9035.9 3859.5 4051.5 4442.8 4568.8 4579.7 1714.9 1900.7 1911.9 1955.2 1960.4 858.3 938.2 970.8 993.5 995.9 16688.5 19960.3 21407.8 21426.4 21937.1 931.3 945.4 959.8 971.0 1027.7 10821.6 11375.4 11380.9 11389.5 11421.0 540379.0 566505.0 569831.2 594010.0 602599.0 135495.0 138193.0 141689.0 142172.3 143213.0 983.8 989.8 993.2 1015.8 1016.2 18457.4 18687.4 18708.7 18743.8 18844.6 36817.3 46773.0 48273.8 52278.7 55398.0 80205.0 80823.0 80856.6 81592.0 82767.0 7134.5 7533.5 7533.9 8166.0 8612.6 9370.2 12303.3 12332.6 12901.0 13580.7 7078.6 9878.3 10903.8 11681.2 11687.9 5659.7 8569.0 11397.8 11515.6 11606.8 11594.8 11652.2 11765.8 11786.4 11850.0 7196.9 7478.5 7531.5 7640.2 7892.8 10381.4 11666.6 11761.8 11786.8 11805.8 15:04:27 INFO - PROCESS | 1670 | 15:04:27 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5694.47 Median:4771.67 stddev:3206.98 (67.2%) stddev-sans-first:3199.65 15:04:27 INFO - PROCESS | 1670 | Values: 1388.8 1561.6 1565.2 1794.9 1957.1 7873.1 8533.2 8750.0 8757.4 8901.2 8562.6 8757.8 8766.9 8773.3 8781.5 2094.2 2184.5 2343.8 2385.8 2416.0 2393.9 2446.9 2448.6 2451.6 2456.9 2359.7 2386.0 2406.7 2408.0 2410.9 2090.8 2307.3 2322.5 2381.2 2389.9 2402.1 2421.8 2422.4 2423.5 2426.5 8639.8 8698.9 8716.9 8723.3 8755.8 4881.2 5048.5 5159.8 5202.0 5330.9 3462.4 3566.2 3653.3 3657.2 3657.9 1058.7 1072.4 1082.8 1090.0 1094.5 11529.5 11540.2 11540.6 11543.1 11562.9 4084.0 4666.4 4698.4 4822.9 4835.5 9883.8 9891.5 9904.0 9913.3 9919.4 5288.3 5364.2 5367.8 5371.3 5371.7 4397.6 4558.7 4720.4 4855.2 4863.2 3471.9 3934.9 3959.3 4052.4 4095.5 1279.9 1288.9 1290.2 1303.5 1308.6 919.0 927.3 934.9 937.2 1000.8 5799.2 5888.4 5954.6 6015.4 6105.3 405.8 444.3 451.5 455.9 459.1 3915.8 4000.2 4054.2 4066.7 4077.6 10059.6 10844.9 10851.1 10852.2 10872.3 10393.5 10645.2 10730.7 10814.4 10820.2 7514.4 7860.9 8190.9 8275.0 8339.5 5772.3 5835.0 5835.9 5985.9 6015.9 9689.1 10016.2 10144.7 10155.7 10178.9 10309.9 10317.6 10324.7 10331.2 10334.4 5784.7 6893.0 7161.7 7520.4 7611.8 7182.7 8492.7 8498.8 8506.0 8517.0 7253.5 7441.7 7502.0 7598.5 7677.7 7206.8 7345.8 7393.8 7468.9 7545.4 7033.2 7066.1 7141.0 7149.8 7154.3 4855.0 4977.0 5000.7 5079.4 5132.3 5039.5 5100.3 5161.1 5183.2 5266.5 15:04:27 INFO - PROCESS | 1670 | 15:04:27 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4143.19 Median:4451.05 stddev:1285.20 (28.9%) stddev-sans-first:1255.35 15:04:27 INFO - PROCESS | 1670 | Values: 261.7 302.2 310.5 317.1 317.7 4441.3 5000.0 5083.3 5286.3 5374.7 4629.4 4811.3 4992.6 5163.4 5272.3 3397.6 4017.4 4026.6 4030.5 4061.4 3065.2 3239.0 3442.1 4045.1 4076.7 3858.7 3950.2 3994.0 4040.5 4056.5 3432.7 3528.5 3943.9 4022.4 4030.5 2930.8 3246.7 3482.2 3487.5 3504.9 4559.7 4717.7 4754.8 5096.9 5164.7 3674.3 3901.9 4196.8 4561.7 4592.4 5327.6 5523.3 5529.2 5556.9 5576.8 2923.5 3186.5 3776.5 3790.4 3834.2 4702.9 5774.7 5788.3 5824.6 5844.5 3574.4 3823.1 3825.6 3826.7 3832.1 3331.4 3880.8 3895.0 3898.7 3918.8 3872.2 4274.4 4323.5 4390.6 4531.7 5524.3 5575.2 5578.6 5580.2 5617.7 1145.5 1214.0 1238.8 1295.4 1330.4 759.8 775.3 811.2 811.9 816.9 4075.4 4264.4 4300.4 4306.7 4328.5 3922.2 4124.4 4200.2 4281.9 4444.8 3964.7 3972.2 4119.8 4247.4 4283.0 2247.3 2313.7 2390.5 2406.9 2535.4 4585.0 4671.9 4750.9 4878.3 4886.5 4516.5 4616.4 4699.0 4793.3 4806.0 4568.6 4897.3 4911.2 4943.8 4980.9 3813.4 4128.1 4480.7 4505.6 4664.7 4094.7 4140.2 4202.7 4393.3 4482.6 4079.9 4361.5 4646.9 4737.5 4890.9 3052.4 3189.6 3289.0 3313.6 3358.4 4540.2 4725.0 4766.7 4900.1 5165.7 4896.0 5261.0 5290.9 5427.9 5539.9 5510.6 5520.7 5526.8 5530.8 5540.5 4457.3 5090.0 5293.5 5394.8 5435.7 4912.5 5325.0 5480.3 5540.6 5547.8 5398.8 5422.9 5429.7 5451.5 5459.8 15:04:27 INFO - PROCESS | 1670 | 15:04:27 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:23925.07 Median:3348.88 stddev:36705.89 (1096.1%) stddev-sans-first:36767.52 15:04:27 INFO - PROCESS | 1670 | Values: 723.8 759.4 792.7 796.0 812.6 15407.6 15784.1 15937.6 15953.0 15978.2 14358.6 14787.9 14790.6 14807.2 14812.9 702.0 728.4 877.1 884.9 891.3 873.9 902.6 905.4 905.4 907.8 947.3 952.5 953.2 953.5 953.7 742.2 792.3 909.8 947.3 948.3 691.5 694.5 694.5 695.1 695.1 15037.0 15094.8 15106.0 15107.9 15108.5 13880.0 14127.6 14148.3 14308.3 14379.9 12884.7 12926.2 13139.5 13207.0 13522.3 4515.1 4581.9 4583.5 4588.7 4799.6 100586.0 102914.0 103033.5 103457.0 103708.0 10368.6 11072.0 11252.7 11357.8 11742.8 22546.5 22561.9 22566.8 22589.3 22601.7 8049.1 8063.2 8083.4 8319.3 8326.6 4423.0 4434.1 4453.2 4644.6 4736.6 3258.9 3438.9 3442.6 3589.7 3595.0 634.7 659.8 662.6 672.8 676.7 532.0 595.7 630.2 654.8 656.8 9222.2 9263.0 9264.1 9314.8 9496.6 571.9 596.7 596.8 638.3 642.4 4006.8 4323.8 4448.2 4542.4 4569.6 115073.0 124504.4 125152.0 143182.1 143211.0 119019.0 119075.2 119177.4 121988.0 125422.0 106030.0 106730.9 107783.5 107992.0 109540.0 9362.0 9389.7 9421.4 9436.1 9463.5 58474.2 60920.0 61361.8 61715.7 62197.4 85325.7 87360.0 90848.0 91515.5 92790.5 12709.9 12856.8 12975.4 12983.1 13101.5 12654.4 14786.5 14994.2 15027.0 15311.4 13844.2 13915.5 13945.4 14035.0 14142.5 13899.9 14245.2 14280.6 14360.0 14378.9 13504.8 13678.9 13690.0 14176.9 14312.1 10392.5 10842.8 11218.4 11398.8 11779.5 10749.6 10802.3 11403.3 11430.4 11434.4 15:04:27 INFO - PROCESS | 1670 | -------- Summary: end -------- 15:04:27 INFO - PROCESS | 1670 | 15:04:28 INFO - TEST-INFO | 1670: exit 0 15:04:28 INFO - TEST-OK | dromaeo_css | took 1117896ms 15:04:28 INFO - TEST-START | kraken 15:04:28 INFO - operating with platform_type : mac_ 15:04:28 INFO - Initialising browser for kraken test... 15:04:28 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpU3qDX8/profile http://localhost:49275/getInfo.html) 15:04:30 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 15:04:30 INFO - PROCESS | 1675 | colorDepth:24 15:04:30 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 15:04:30 INFO - PROCESS | 1675 | __metrics 15:04:30 INFO - PROCESS | 1675 | 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" 15:04:30 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:04:31 INFO - TEST-INFO | 1675: exit 0 15:04:31 INFO - Browser initialized. 15:04:31 INFO - Running cycle 1/1 for kraken test... 15:04:31 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpU3qDX8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:04:43 INFO - PROCESS | 1676 | RSS: Main: 241942528 15:04:43 INFO - PROCESS | 1676 | 15:04:46 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 15:04:47 INFO - PROCESS | 1676 | RSS: Main: 292151296 15:04:47 INFO - PROCESS | 1676 | 15:04:49 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 15:04:50 INFO - PROCESS | 1676 | RSS: Main: 301731840 15:04:50 INFO - PROCESS | 1676 | 15:04:53 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 15:04:54 INFO - PROCESS | 1676 | RSS: Main: 388771840 15:04:54 INFO - PROCESS | 1676 | 15:04:56 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 15:04:57 INFO - PROCESS | 1676 | RSS: Main: 289955840 15:04:57 INFO - PROCESS | 1676 | 15:04:59 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:04:59 INFO - PROCESS | 1676 | RSS: Main: 293646336 15:04:59 INFO - PROCESS | 1676 | 15:05:02 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 15:05:03 INFO - PROCESS | 1676 | RSS: Main: 330805248 15:05:03 INFO - PROCESS | 1676 | 15:05:06 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 15:05:07 INFO - PROCESS | 1676 | RSS: Main: 334929920 15:05:07 INFO - PROCESS | 1676 | 15:05:09 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 15:05:10 INFO - PROCESS | 1676 | RSS: Main: 332976128 15:05:10 INFO - PROCESS | 1676 | 15:05:12 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:05:12 INFO - PROCESS | 1676 | RSS: Main: 312254464 15:05:12 INFO - PROCESS | 1676 | 15:05:14 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:05:15 INFO - PROCESS | 1676 | RSS: Main: 318566400 15:05:15 INFO - PROCESS | 1676 | 15:05:17 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:05:18 INFO - PROCESS | 1676 | RSS: Main: 323657728 15:05:18 INFO - PROCESS | 1676 | 15:05:21 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:05:21 INFO - PROCESS | 1676 | RSS: Main: 325656576 15:05:21 INFO - PROCESS | 1676 | 15:05:24 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:05:25 INFO - PROCESS | 1676 | RSS: Main: 326881280 15:05:25 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 15:05:27 INFO - PROCESS | 1676 | RSS: Main: 369946624 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | __start_tp_report 15:05:27 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 15:05:27 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 15:05:27 INFO - PROCESS | 1676 | |i|pagename|runs| 15:05:27 INFO - PROCESS | 1676 | |0;ai-astar;113;106;113;113;113;114;113;113;115;113 15:05:27 INFO - PROCESS | 1676 | |1;audio-beat-detection;156;124;117;166;118;119;140;119;150;117 15:05:27 INFO - PROCESS | 1676 | |2;audio-dft;194;251;184;182;184;184;183;182;184;179 15:05:27 INFO - PROCESS | 1676 | |3;audio-fft;80;76;57;123;79;77;78;65;83;57 15:05:27 INFO - PROCESS | 1676 | |4;audio-oscillator;85;95;95;71;70;69;95;72;68;100 15:05:27 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;88;85;88;85;88;86;89;85;88;86 15:05:27 INFO - PROCESS | 1676 | |6;imaging-darkroom;139;146;145;95;94;95;96;95;95;95 15:05:27 INFO - PROCESS | 1676 | |7;imaging-desaturate;84;80;84;84;98;119;125;138;84;83 15:05:27 INFO - PROCESS | 1676 | |8;json-parse-financial;56;56;56;57;54;56;54;55;55;55 15:05:27 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;65;67;81;76;72;69;70;70;69;71 15:05:27 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;86;86;91;87;85;86;86;87;118;87 15:05:27 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;156;151;139;141;138;136;142;138;158;130 15:05:27 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;167;166;168;170;168;220;221;173;166;165 15:05:27 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;59;60;85;66;65;65;66;66;66;65 15:05:27 INFO - PROCESS | 1676 | __end_tp_report 15:05:27 INFO - PROCESS | 1676 | __start_cc_report 15:05:27 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1966 15:05:27 INFO - PROCESS | 1676 | __end_cc_report 15:05:27 INFO - PROCESS | 1676 | __startTimestamp1464818727333__endTimestamp 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | ------- Summary: start ------- 15:05:27 INFO - PROCESS | 1676 | Number of tests: 14 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.00 stddev:2.41 (2.1%) stddev-sans-first:2.55 15:05:27 INFO - PROCESS | 1676 | Values: 113.0 106.0 113.0 113.0 113.0 114.0 113.0 113.0 115.0 113.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:132.00 stddev:18.75 (14.2%) stddev-sans-first:17.87 15:05:27 INFO - PROCESS | 1676 | Values: 156.0 124.0 117.0 166.0 118.0 119.0 140.0 119.0 150.0 117.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:190.70 Median:184.00 stddev:21.54 (11.7%) stddev-sans-first:22.81 15:05:27 INFO - PROCESS | 1676 | Values: 194.0 251.0 184.0 182.0 184.0 184.0 183.0 182.0 184.0 179.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:77.50 Median:77.50 stddev:18.58 (24.0%) stddev-sans-first:19.69 15:05:27 INFO - PROCESS | 1676 | Values: 80.0 76.0 57.0 123.0 79.0 77.0 78.0 65.0 83.0 57.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:82.00 Median:78.50 stddev:13.21 (16.8%) stddev-sans-first:13.96 15:05:27 INFO - PROCESS | 1676 | Values: 85.0 95.0 95.0 71.0 70.0 69.0 95.0 72.0 68.0 100.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:1.55 (1.8%) stddev-sans-first:1.58 15:05:27 INFO - PROCESS | 1676 | Values: 88.0 85.0 88.0 85.0 88.0 86.0 89.0 85.0 88.0 86.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:109.50 Median:95.00 stddev:23.42 (24.7%) stddev-sans-first:22.28 15:05:27 INFO - PROCESS | 1676 | Values: 139.0 146.0 145.0 95.0 94.0 95.0 96.0 95.0 95.0 95.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:97.90 Median:84.00 stddev:21.35 (25.4%) stddev-sans-first:22.05 15:05:27 INFO - PROCESS | 1676 | Values: 84.0 80.0 84.0 84.0 98.0 119.0 125.0 138.0 84.0 83.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:56.00 stddev:0.97 (1.7%) stddev-sans-first:1.00 15:05:27 INFO - PROCESS | 1676 | Values: 56.0 56.0 56.0 57.0 54.0 56.0 54.0 55.0 55.0 55.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:70.50 stddev:4.57 (6.5%) stddev-sans-first:4.30 15:05:27 INFO - PROCESS | 1676 | Values: 65.0 67.0 81.0 76.0 72.0 69.0 70.0 70.0 69.0 71.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:86.50 stddev:10.00 (11.6%) stddev-sans-first:10.51 15:05:27 INFO - PROCESS | 1676 | Values: 86.0 86.0 91.0 87.0 85.0 86.0 86.0 87.0 118.0 87.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:141.50 stddev:9.11 (6.4%) stddev-sans-first:8.34 15:05:27 INFO - PROCESS | 1676 | Values: 156.0 151.0 139.0 141.0 138.0 136.0 142.0 138.0 158.0 130.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.40 Median:169.00 stddev:22.31 (13.2%) stddev-sans-first:23.28 15:05:27 INFO - PROCESS | 1676 | Values: 167.0 166.0 168.0 170.0 168.0 220.0 221.0 173.0 166.0 165.0 15:05:27 INFO - PROCESS | 1676 | 15:05:27 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:7.06 (10.7%) stddev-sans-first:6.97 15:05:27 INFO - PROCESS | 1676 | Values: 59.0 60.0 85.0 66.0 65.0 65.0 66.0 66.0 66.0 65.0 15:05:27 INFO - PROCESS | 1676 | -------- Summary: end -------- 15:05:27 INFO - PROCESS | 1676 | 15:05:28 INFO - TEST-INFO | 1676: exit 0 15:05:28 INFO - TEST-OK | kraken | took 59717ms 15:05:28 INFO - SUITE-END | took 1177s 15:05:28 INFO - Completed test suite (00:19:37) 15:05:28 INFO - javascript benchmark 15:05:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3477.3393055319493, 3490.982545087274, 3516.261585067136, 3538.310029544237, 3565.572131344239, 5567.0, 5642.181883626871, 5829.5919285650025, 5951.375105613908, 5995.520358371331, 5524.558035357172, 5590.133529302964, 5616.157576363547, 5641.8589535261635, 5911.674857882826, 5236.345456817898, 5243.0, 5318.441563635821, 5326.680399176043, 5436.103042997901, 5425.945740542603, 5431.0, 5436.401995780461, 5458.781648734046, 5485.232067510552, 4410.25025745624, 5214.582833373321, 5286.60350473716, 5299.152135658276, 5359.5712343012665, 5329.0, 5351.170568561879, 5358.946410535884, 5361.758720857571, 5373.516383525502, 5242.135047717122, 5300.125479295912, 5320.0, 5331.893362132774, 5337.226102215196, 5682.431756824326, 5711.600187986843, 5755.107958266475, 5792.362840087587, 5839.357670656224, 6243.469305109071, 6251.28110267316, 6269.655168965705, 6269.87460250797, 6273.592216505913, 6102.664353460558, 6238.189035425411, 6248.750049997994, 6266.310705822356, 6290.937090629081, 5822.301323841166, 5898.64608123514, 5914.556408269397, 5978.85052873673, 6005.6997150142315, 6321.367863213642, 6323.30443651198, 6326.905096423558, 6329.841753956096, 6333.334999825044, 6306.810795676137, 6307.747690092345, 6316.968415157895, 6323.588966717149, 6329.588576742497, 6182.072689096672, 6188.44304012641, 6240.5319601030105, 6260.7495700171485, 6261.405166509175, 6273.435390814849, 6281.560290779602, 6287.685615719196, 6293.0, 6298.748050077946, 5187.351581052369, 6270.153529273581, 6275.497960163176, 6310.400511951358, 6328.398802113792, 4342.34864770287, 4899.706017638881, 4951.10880041596, 4993.950060499421, 4996.150654388763, 4528.230200865861, 4538.659600529973, 4573.405457290531, 4590.724556526619, 4591.380163677861, 4874.439439464436, 5808.680522571215, 5826.883462330815, 5842.065269556851, 5915.733791979371, 6214.502839772807, 6219.533534984804, 6231.719572619243, 6233.1273621693, 6235.844103897439, 5765.913511297334, 5861.765529378862, 5869.4717475427415, 5875.912956103135, 5901.645901245853, 5353.652012619167, 5403.189521571717, 5423.918641220384, 5432.592555558349, 5485.61600687956, 6369.554131210771, 6373.936260637427, 6380.457661098859, 6380.617162970144, 6393.0, 6148.292946311231, 6236.656983865931, 6251.9062214067735, 6259.561830671809, 6265.8120256392385, 5357.223202635674, 6132.110769108063, 6164.3835616438, 6249.875002500015, 6376.585521941059, 6171.783987560414, 6202.2867370253, 6202.317745047951, 6218.81343559694, 6227.937720622917, 5461.699606521679, 6196.628202307876, 6215.81352559424, 6218.06728990655, 6237.438630523275, 6171.506279497719, 6204.069389591597, 6257.655828929453, 6278.497720182283, 6293.716782744696, 6060.454559089527, 6062.0, 6104.337893874414, 6116.785912493041, 6266.122742816101, 6023.066424704178, 6076.058210977306, 6077.361877002851, 6080.0575910733905, 6088.056351265561, 6117.10892948092, 6130.092410195535, 6152.138700582012, 6153.630782153085, 6156.199694039925, 5970.0746384310505, 6045.879082418415, 6052.334243233329, 6082.330943596113, 6123.387661233841], "value": 5751.407462048829, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.3369152718412, 508.35692848546597, 525.9053370393327, 534.7406507843693, 536.5948708724912, 31034.68965310346, 31271.218219544524, 32516.0, 32517.187070323253, 32711.67288327116, 32995.505067424005, 38813.41779873304, 45074.09851802962, 45493.77253113738, 47738.761306193504, 9457.196138328232, 9636.903630963705, 9647.758806029835, 9663.516824158763, 9682.56428460721, 9468.053194680511, 9601.951990240073, 9608.183304419133, 9623.615055397775, 9660.0, 8573.52845593492, 8806.031336552975, 11450.48472818721, 11657.766844663101, 11675.591354302602, 11758.05935525156, 11835.40822958853, 11864.822027669592, 11877.16859819813, 11900.642980710592, 8511.148885111439, 11353.0, 11598.0, 11754.941225293904, 11755.764884702343, 41195.1760964782, 41804.790976044926, 41833.0, 41854.79072604617, 42024.78987605073, 3515.3145136698354, 3540.433530635086, 3639.4722765199162, 3659.2315613721153, 3865.871833084948, 31760.841195794103, 31918.840405797822, 31979.360412791615, 32437.837810810794, 33273.833630831934, 3275.5250488679008, 3292.983535082333, 3297.406466835969, 3332.733381329512, 3379.290349026707, 507190.0, 507760.0, 509366.0, 509690.4515477436, 510993.0, 54437.91124177494, 61623.0, 62631.37368626255, 62753.68623156855, 64640.03039954404, 68549.65725171342, 68803.65598171977, 68824.31175688279, 68934.0, 68953.0, 16901.408450704166, 17179.05515196676, 17186.31254749821, 17206.655866882844, 17251.482455526355, 13146.60560183196, 13165.47338106465, 13185.934070329778, 13196.0, 13297.202167869766, 15741.763873541906, 16090.63229625495, 16118.95226810277, 17992.460226193234, 18256.178471968793, 6042.486388657014, 7100.396466300319, 7212.170600380919, 7254.528455650366, 7297.452691048086, 915.6428992692855, 952.9809403811885, 1116.4752566293828, 1123.3596849795538, 1179.2747460311846, 27642.170734877986, 30027.0, 30175.094747157622, 30571.694283057328, 31397.52903706446, 1032.4115254304975, 1083.5665733706423, 1145.0839328537138, 1170.9121815863843, 1196.174639498743, 16046.438374656831, 16111.436099736453, 16120.919395403182, 16327.529298971815, 16505.009699418075, 665797.0, 671365.0, 673725.0, 675001.0, 675003.0, 53536.19695704568, 54419.0, 61925.380746191964, 62012.379876201565, 62293.68853155796, 1302.583173384508, 1328.5682153300215, 1329.8005299205197, 1330.5476138113088, 1443.2855736410545, 30501.23746906345, 31933.36133277368, 32135.357292854478, 32296.515552266264, 33176.0, 53702.462975369745, 54121.45878541322, 54600.45399545954, 54653.72673136609, 54658.0, 475432.0, 581818.0, 586803.0, 586977.0, 587636.0, 35251.47122793109, 36688.899333020054, 45162.77418612886, 45858.312125318815, 46061.23632582056, 49423.0, 49512.50487495079, 49584.0, 49799.00401991868, 50570.49429505805, 40540.797296013334, 40594.79702601468, 41106.9723256909, 41376.1724765509, 41731.58268417277, 39705.60294397019, 39890.800545998245, 40315.798421007705, 41158.794206028775, 41313.0, 32155.035348939567, 38923.02692432598, 39419.605803941595, 39762.801185993885, 39897.60102398939, 29555.704442955295, 31006.0, 33204.66795332016, 35634.64365356313, 35661.64338356687, 24767.752322476543, 25748.227553173434, 25820.0, 26111.216663500127, 26415.471690565697], "value": 20790.913817494038, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.13637179973256, 385.09118480386286, 385.6625452728861, 399.37497815918107, 404.48832227232555, 17965.73051404227, 19850.305239316625, 20251.08870100845, 21714.674279885796, 21794.673079903794, 11893.405329733521, 12367.938160309208, 12392.079227719696, 12753.681157971056, 21561.353159405204, 5266.552343050836, 5433.402325744165, 5438.395673276393, 5448.073827449343, 5478.91781623276, 3860.884173474786, 4075.7758323292205, 4207.990082380222, 6929.72281108755, 6946.062281591996, 6346.2067241594805, 6483.675816209195, 6498.350164983487, 6523.7064332105165, 6887.4158943443035, 6385.042243663464, 6485.189351331083, 6493.70778314977, 6590.143281373391, 6776.966115169442, 3829.4447305140875, 4029.9194016119513, 4062.329715596923, 4552.795124219418, 5947.167396564484, 13366.264855432948, 13507.594772156852, 13741.381637826322, 13919.373628186655, 13925.025248232527, 2211.6903633491324, 2219.5671843990426, 2310.353101131686, 2332.6151185054446, 2508.611165269386, 9096.317776166814, 9281.21109705676, 9309.953450232706, 9614.807703845883, 9651.613935442574, 1792.4085051932832, 1808.4032269351083, 1869.5699989002605, 1870.5978214683907, 1926.2776458827939, 120817.39591302075, 124560.37719811434, 127794.0, 128018.3599082008, 128152.0, 8348.749537513884, 11611.76776464466, 11631.0, 11641.53433862636, 11981.161318707607, 14918.179500127597, 14918.328675209425, 14942.0, 14951.476698315506, 14992.550223493312, 8643.438176518506, 8885.111488851064, 8891.688790892416, 8989.41568798026, 9012.774680633036, 8863.257543908298, 8873.467591944504, 8880.333974951775, 8995.145461181177, 9035.909640903637, 3859.517560304962, 4051.503169272981, 4442.822287108479, 4568.817247310137, 4579.702319349299, 1714.9024624240496, 1900.686386746199, 1911.9102111796142, 1955.2081407030175, 1960.362882063335, 858.2618947704968, 938.15565990609, 970.815545046439, 993.4833886379042, 995.9402435853872, 16688.499345019667, 19960.33248948519, 21407.78592214089, 21426.357209283433, 21937.122515099218, 931.2968708625081, 945.4421891084294, 959.7792507723262, 970.9854352184723, 1027.686555600549, 10821.567137314576, 11375.374354410587, 11380.886191138148, 11389.544418223344, 11421.0, 540379.0, 566505.0, 569831.1508442514, 594010.0, 602599.0, 135495.0, 138193.0, 141689.0, 142172.28913855363, 143213.0, 983.7638966647953, 989.8416253399422, 993.215359865698, 1015.8425444056184, 1016.207358260558, 18457.446276611725, 18687.43937681872, 18708.719369209746, 18743.81256187475, 18844.6231075375, 36817.26365472729, 46773.0, 48273.758631208024, 52278.738606306724, 55398.0, 80205.0, 80823.0, 80856.59571702103, 81592.0, 82767.0, 7134.500584959211, 7533.54798712079, 7533.886991695019, 8165.959170204111, 8612.569371531274, 9370.156685898302, 12303.323317217639, 12332.630021099381, 12901.0, 13580.660483487993, 7078.575285482888, 9878.259130565093, 10903.83644245321, 11681.189415640589, 11687.88312116868, 5659.660420374791, 8569.014563325298, 11397.772044559228, 11515.596954106566, 11606.825897611712, 11594.76810463803, 11652.184347095623, 11765.823512647488, 11786.351750653796, 11850.0, 7196.928030719626, 7478.4765066444825, 7531.510451820614, 7640.235976402538, 7892.84214315699, 10381.429021403896, 11666.591669291873, 11761.823572646246, 11786.823197651871, 11805.76388472243], "value": 9219.423481243866, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.7569675306818, 1561.5939855637534, 1565.178281402264, 1794.9371771987983, 1957.0508303472823, 7873.055233371996, 8533.189347012032, 8750.0, 8757.386982911199, 8901.243394311492, 8562.571871406435, 8757.781055473617, 8766.868496972551, 8773.25427338677, 8781.473111613308, 2094.1728017433106, 2184.4757258257982, 2343.8476499027593, 2385.7614238576175, 2415.9516809663796, 2393.8563686178886, 2446.926592202237, 2448.6449464827588, 2451.595486744685, 2456.9017239310406, 2359.6932398788135, 2385.988070059656, 2406.7352591214953, 2407.987960060197, 2410.9035638574437, 2090.790920907912, 2307.284741730069, 2322.477442575424, 2381.1903952656007, 2389.8685572293516, 2402.1232250228713, 2421.83047186697, 2422.3770074050253, 2423.491066875958, 2426.46617744096, 8639.827203455958, 8698.913010869874, 8716.869246961302, 8723.25852302555, 8755.781105472413, 4881.194602890519, 5048.469910659364, 5159.793608255665, 5201.973990130063, 5330.946690533084, 3462.3940810358085, 3566.1797786509246, 3653.30587188436, 3657.213699054689, 3657.8536858525626, 1058.745900983766, 1072.399456304472, 1082.8213344798098, 1089.967300980972, 1094.4965315954591, 11529.538818447252, 11540.192186546945, 11540.596079137189, 11543.134264930164, 11562.884371156264, 4083.959160408358, 4666.416697912761, 4698.436187657435, 4822.927656085161, 4835.492273311322, 9883.752906177404, 9891.455969921722, 9904.0, 9913.306068575275, 9919.355241909252, 5288.312519372534, 5364.222187782828, 5367.78528858849, 5371.301730775052, 5371.704556249366, 4397.582229688174, 4558.703684260512, 4720.409948756405, 4855.150348688965, 4863.197572400585, 3471.878484253039, 3934.9213015739524, 3959.3269144245546, 4052.3516237401873, 4095.488063992001, 1279.9296038717773, 1288.903332250085, 1290.2258644813226, 1303.4720938020116, 1308.5681725030718, 918.9862152067724, 927.2535608834953, 934.9439033658002, 937.1706040154512, 1000.7898341348252, 5799.159121927381, 5888.388587822781, 5954.642721436728, 6015.39846015395, 6105.297890742621, 405.8417217285261, 444.293573218584, 451.4717780197177, 455.92933095370273, 459.06580109477056, 3915.8433662653742, 4000.19996000801, 4054.2296963576828, 4066.6543343816106, 4077.571854955187, 10059.647912323033, 10844.915508449092, 10851.131909447226, 10852.240343175901, 10872.347659140318, 10393.53229104692, 10645.20160987929, 10730.678079657466, 10814.35113893169, 10820.24258301927, 7514.4364172687265, 7860.882086768703, 8190.918090819135, 8275.0, 8339.45793523419, 5772.307323121252, 5835.0, 5835.9282454729655, 5985.940140598713, 6015.879682406415, 9689.12797848197, 10016.248781341574, 10144.746381340525, 10155.7461063474, 10178.898211017795, 10309.896901031194, 10317.587296508356, 10324.741881453023, 10331.225158113322, 10334.431606261427, 5784.652920824596, 6893.0, 7161.713531458683, 7520.398368130646, 7611.809704757426, 7182.676779545038, 8492.74521764348, 8498.830023399621, 8505.95747021261, 8517.020542637429, 7253.492255542062, 7441.702331906879, 7502.024736784183, 7598.468107232441, 7677.654505547152, 7206.783796486114, 7345.816354591178, 7393.778186654409, 7468.887966680395, 7545.396368290414, 7033.156021277479, 7066.081409416743, 7140.964560138855, 7149.82125446868, 7154.284571542804, 4854.975725121493, 4977.0, 5000.67495612784, 5079.415867175322, 5132.30713853625, 5039.470855560114, 5100.336956195671, 5161.148410512374, 5183.170692689152, 5266.473352664703], "value": 4508.388134580359, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.7003530957054, 302.16602177988744, 310.5279974438853, 317.10892392377434, 317.6997737138404, 4441.290812604313, 4999.975000125003, 5083.339165908426, 5286.3392075990505, 5374.7043912584795, 4629.351890735291, 4811.30236115763, 4992.627027567421, 5163.432022477534, 5272.340957380327, 3397.59228892532, 4017.437558741764, 4026.6242916679153, 4030.496187976503, 4061.431399604065, 3065.2183693158327, 3239.0, 3442.122258823996, 4045.0898547826796, 4076.673866090706, 3858.6527212550864, 3950.170464202521, 3993.9600603993877, 4040.4949381327333, 4056.5132184137947, 3432.6567343265724, 3528.5060091587197, 3943.895709201414, 4022.416749571325, 4030.45588845505, 2930.750886174678, 3246.7077962983444, 3482.181687303482, 3487.511748355232, 3504.8948531544092, 4559.746069830823, 4717.716936983792, 4754.811297175706, 5096.949030509684, 5164.741762911839, 3674.2835147146316, 3901.9219615607813, 4196.769177695225, 4561.703489273186, 4592.357070010201, 5327.627066105374, 5523.2543606613, 5529.225908372831, 5556.91664625031, 5576.804811831607, 2923.532234842436, 3186.5379519969474, 3776.509053823013, 3790.3556395412847, 3834.175652234755, 4702.858914232578, 5774.740136693817, 5788.308436577012, 5824.563157763142, 5844.532437404998, 3574.410222313289, 3823.0824602095854, 3825.5983121771815, 3826.6556009959236, 3832.1222600925344, 3331.3836940166148, 3880.825362858678, 3895.0, 3898.688104951597, 3918.7648741075627, 3872.206197729459, 4274.422952901381, 4323.48118225815, 4390.648748100144, 4531.705439146445, 5524.33707955048, 5575.215930902073, 5578.581606379537, 5580.246666700024, 5617.662940223599, 1145.5360578965533, 1213.9635810925686, 1238.8017917133216, 1295.352323838081, 1330.447864136392, 759.8366351234511, 775.2557544756984, 811.1685522339649, 811.8822770698334, 816.9101398846122, 4075.4294398784195, 4264.402983582301, 4300.397944287803, 4306.698531102793, 4328.5455027222315, 3922.156736301709, 4124.360409338026, 4200.227849619237, 4281.935770963438, 4444.82220711168, 3964.6520183137873, 3972.152948878785, 4119.814608342632, 4247.405363249148, 4283.0, 2247.3033359658557, 2313.6760853480528, 2390.521895620848, 2406.9157579484986, 2535.404180017694, 4584.9541504585195, 4671.944344488482, 4750.904981900343, 4878.341363936208, 4886.511348865085, 4516.525764794715, 4616.376789133491, 4699.0, 4793.32893394925, 4805.975970120127, 4568.588827005586, 4897.282008485254, 4911.214205727067, 4943.7640589852535, 4980.90038199234, 3813.4089216171537, 4128.050548373951, 4480.686351955332, 4505.617022552989, 4664.696794708333, 4094.733842300241, 4140.23405669952, 4202.747835129902, 4393.330534396909, 4482.618977386958, 4079.9075000728635, 4361.5202327744555, 4646.907061858677, 4737.549932756383, 4890.902181956412, 3052.3974913170764, 3189.6491385947065, 3289.0461766087565, 3313.5526703894666, 3358.3619112368574, 4540.230459419818, 4724.976375118103, 4766.6901651392545, 4900.093482705711, 5165.745580284397, 4895.996320754308, 5261.0, 5290.947090529045, 5427.918581221206, 5539.9169012464035, 5510.614257001971, 5520.723963801714, 5526.806561770319, 5530.8429712413, 5540.501354878081, 4457.2839456808715, 5090.0, 5293.470652934676, 5394.757235924471, 5435.728213589226, 4912.508749125059, 5324.9733751331, 5480.342358916796, 5540.556755459635, 5547.7780888764, 5398.838034858961, 5422.945770542244, 5429.674219546682, 5451.536619387396, 5459.754311055933], "value": 3741.1174107447127, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.8371366442551, 759.4493991855906, 792.7106606088779, 795.9811441355064, 812.5693382507274, 15407.614809629766, 15784.131872746995, 15937.601559961007, 15953.042817430947, 15978.201089945514, 14358.569242922704, 14787.926060369684, 14790.630234244149, 14807.185604791732, 14812.851871481282, 702.0, 728.420905380224, 877.0878286581947, 884.9159329863669, 891.3315013739696, 873.9388242823005, 902.62992173209, 905.3933864310894, 905.425055090016, 907.8184363127368, 947.2516711797672, 952.4951775558965, 953.2231231546302, 953.5280036382001, 953.6995846308425, 742.2354974376401, 792.2829838995718, 909.7816524034184, 947.2895328503588, 948.2556193388192, 691.5401258163315, 694.4756708684961, 694.5173104692241, 695.0963747128762, 695.1345574759457, 15037.0, 15094.773578396333, 15106.0, 15107.924460377737, 15108.54674359771, 13880.0, 14127.646808829759, 14148.292585370793, 14308.28458577067, 14379.928100359535, 12884.677883052906, 12926.15979961309, 13139.474421023147, 13207.009474289467, 13522.256275904821, 4515.14212299662, 4581.862544123648, 4583.495815460296, 4588.655850811169, 4799.568038876518, 100586.0, 102914.0, 103033.48483257536, 103457.0, 103707.96292037134, 10368.637097701545, 11072.003519683267, 11252.66242012741, 11357.829632555518, 11742.823857642143, 22546.549069018856, 22561.88719056427, 22566.774332256467, 22589.322320330415, 22601.660975085386, 8049.114597394282, 8063.233992770677, 8083.434159608771, 8319.334453243726, 8326.583670816435, 4423.0, 4434.11317736454, 4453.242948698729, 4644.581987621063, 4736.621070314367, 3258.853351599136, 3438.896833095011, 3442.5524681791208, 3589.7307701921936, 3594.982025089858, 634.6985182038495, 659.7690808217181, 662.6189940784069, 672.7510820996165, 676.6785776756002, 531.9760610772524, 595.6634501506635, 630.2122347066168, 654.7773756922574, 656.7569999100364, 9222.174174532474, 9263.0, 9264.11990860867, 9314.813703726024, 9496.620135194653, 571.8999175144334, 596.6837576084682, 596.8179705189958, 638.3074364314771, 642.3865208725656, 4006.7996600169877, 4323.7621930793475, 4448.243798554189, 4542.409486766699, 4569.634429245652, 115073.0, 124504.37747811383, 125152.0, 143182.13635727228, 143211.0, 119019.0, 119075.21387179199, 119177.40411297888, 121988.0, 125422.0, 106030.0, 106730.93269067365, 107783.46108269409, 107992.0, 109540.0, 9361.953190234006, 9389.671361502587, 9421.38760980534, 9436.103570160823, 9463.479508626817, 58474.24577262689, 60920.0, 61361.77276454535, 61715.6914215444, 62197.37802622097, 85325.7201141971, 87360.0, 90848.0, 91515.54242228746, 92790.53604731933, 12709.936450318059, 12856.807147892601, 12975.351232438528, 12983.091183617056, 13101.527421225126, 12654.367281635698, 14786.482473113389, 14994.175320357484, 15027.023243489575, 15311.387544498095, 13844.238566878515, 13915.512957046454, 13945.3724582392, 14034.965625916502, 14142.50501232452, 13899.861001389856, 14245.21651309188, 14280.571582852532, 14359.994800363875, 14378.928105359406, 13504.797428038784, 13678.93160534191, 13690.0, 14176.929115354356, 14312.141271523742, 10392.48037598102, 10842.80729119812, 11218.382988935688, 11398.772024559297, 11779.52881884715, 10749.570017199225, 10802.297850639683, 11403.258788178742, 11430.428478575872, 11434.406320944665], "value": 7879.028103662115, "unit": "score"}], "name": "dromaeo_css", "value": 7260.777892358504, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 115.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 117.0, 166.0, 118.0, 119.0, 140.0, 119.0, 150.0, 117.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 251.0, 184.0, 182.0, 184.0, 184.0, 183.0, 182.0, 184.0, 179.0], "value": 190.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 57.0, 123.0, 79.0, 77.0, 78.0, 65.0, 83.0, 57.0], "value": 77.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 95.0, 95.0, 71.0, 70.0, 69.0, 95.0, 72.0, 68.0, 100.0], "value": 82.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 86.0, 89.0, 85.0, 88.0, 86.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 146.0, 145.0, 95.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 109.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 80.0, 84.0, 84.0, 98.0, 119.0, 125.0, 138.0, 84.0, 83.0], "value": 97.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 57.0, 54.0, 56.0, 54.0, 55.0, 55.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 81.0, 76.0, 72.0, 69.0, 70.0, 70.0, 69.0, 71.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 91.0, 87.0, 85.0, 86.0, 86.0, 87.0, 118.0, 87.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 151.0, 139.0, 141.0, 138.0, 136.0, 142.0, 138.0, 158.0, 130.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 168.0, 170.0, 168.0, 220.0, 221.0, 173.0, 166.0, 165.0], "value": 178.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 85.0, 66.0, 65.0, 65.0, 66.0, 66.0, 66.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1493.5, "alertThreshold": 2.0}]} 15:05:28 INFO - Return code: 0 15:05:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:05:28 ERROR - # TBPL SUCCESS # 15:05:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:05:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:05:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:05:28 INFO - 2016-06-01 15:05:28.636 screenresolution[1680:10120] starting screenresolution argv=screenresolution get 15:05:28 INFO - 2016-06-01 15:05:28.639 screenresolution[1680:10120] Display 0: 1600x1200x32@60 15:05:28 INFO - 2016-06-01 15:05:28.645 screenresolution[1681:10123] starting screenresolution argv=screenresolution list 15:05:28 INFO - Available Modes on Display 0 15:05:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:05:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:05:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:05:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:05:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:05:28 INFO - Intel Iris: 15:05:28 INFO - Chipset Model: Intel Iris 15:05:28 INFO - Type: GPU 15:05:28 INFO - Bus: Built-In 15:05:28 INFO - VRAM (Dynamic, Max): 1536 MB 15:05:28 INFO - Vendor: Intel (0x8086) 15:05:28 INFO - Device ID: 0x0a2e 15:05:28 INFO - Revision ID: 0x0009 15:05:28 INFO - Displays: 15:05:28 INFO - Display: 15:05:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:05:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:05:28 INFO - Display Serial Number: 1600x1200 60 15:05:28 INFO - Main Display: Yes 15:05:28 INFO - Mirror: Off 15:05:28 INFO - Online: Yes 15:05:28 INFO - Rotation: Supported 15:05:28 INFO - Return code: 0 15:05:28 INFO - Running post-action listener: _resource_record_post_action 15:05:28 INFO - ##### 15:05:28 INFO - ##### Finished run-tests step (success) 15:05:28 INFO - ##### 15:05:28 INFO - Running post-run listener: _resource_record_post_run 15:05:28 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 31518208; Write bytes: 697494016; Read time: 196; Write time: 1879 15:05:28 INFO - TinderboxPrint: CPU usage
54.0% 15:05:28 INFO - TinderboxPrint: I/O read bytes / time
31,518,208 / 196 15:05:28 INFO - TinderboxPrint: I/O write bytes / time
697,494,016 / 1,879 15:05:28 INFO - TinderboxPrint: CPU idle
2,234.6 (46.0%) 15:05:28 INFO - TinderboxPrint: CPU system
975.5 (20.1%) 15:05:28 INFO - TinderboxPrint: CPU user
1,647.5 (33.9%) 15:05:28 INFO - TinderboxPrint: Swap in / out
427,450,368 / 0 15:05:28 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387805696; Write bytes: 382314496; Read time: 29089; Write time: 764 15:05:28 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29744640; Write bytes: 293380608; Read time: 180; Write time: 1061 15:05:29 INFO - Running post-run listener: _upload_blobber_files 15:05:29 INFO - Blob upload gear active. 15:05:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:05:29 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 15:05:29 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'] 15:05:29 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 15:05:29 INFO - (blobuploader) - INFO - Open directory for files ... 15:05:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:05:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:05:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:05:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:05:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:05:30 INFO - (blobuploader) - INFO - Done attempting. 15:05:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:05:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:05:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:05:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:05:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:05:31 INFO - (blobuploader) - INFO - Done attempting. 15:05:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:05:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:05:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:05:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:05:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:05:34 INFO - (blobuploader) - INFO - Done attempting. 15:05:34 INFO - (blobuploader) - INFO - Iteration through files over. 15:05:34 INFO - Return code: 0 15:05:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:05:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:05:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/679fb9100cef8e874918e9cfc4b5f07e0f8a6589104827da262849ca60a0cb41314706d0d00786c5ab2776cf6e4aeb7e812dec9141cc200d78649093dffad909", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/bccfa3760272865efdb2e4428fda5e4cff82e6f6ca34a917856a34cbada2bf24cb4ed8dae7605c33e185a812610d6c1c1ef2024fcfa6cc5b0b3fbac02abe002a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/18aa8b529e3534ddc61c816f845eab2cf13c45cd5db2e625d192284013d689d48b0af1b6e16c2405aeea13b7843943ed32f6f323d81ab42611089d7483d74edc"} 15:05:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:05:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:05:34 INFO - Contents: 15:05:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/679fb9100cef8e874918e9cfc4b5f07e0f8a6589104827da262849ca60a0cb41314706d0d00786c5ab2776cf6e4aeb7e812dec9141cc200d78649093dffad909", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/bccfa3760272865efdb2e4428fda5e4cff82e6f6ca34a917856a34cbada2bf24cb4ed8dae7605c33e185a812610d6c1c1ef2024fcfa6cc5b0b3fbac02abe002a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/18aa8b529e3534ddc61c816f845eab2cf13c45cd5db2e625d192284013d689d48b0af1b6e16c2405aeea13b7843943ed32f6f323d81ab42611089d7483d74edc"} 15:05:34 INFO - Running post-run listener: copy_logs_to_upload_dir 15:05:34 INFO - Copying logs to upload dir... 15:05:34 INFO - mkdir: /builds/slave/test/build/upload/logs 15:05:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.268906 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-06-01 15:05:34.097849) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 15:05:34.101161) ========= 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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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/679fb9100cef8e874918e9cfc4b5f07e0f8a6589104827da262849ca60a0cb41314706d0d00786c5ab2776cf6e4aeb7e812dec9141cc200d78649093dffad909", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/bccfa3760272865efdb2e4428fda5e4cff82e6f6ca34a917856a34cbada2bf24cb4ed8dae7605c33e185a812610d6c1c1ef2024fcfa6cc5b0b3fbac02abe002a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/18aa8b529e3534ddc61c816f845eab2cf13c45cd5db2e625d192284013d689d48b0af1b6e16c2405aeea13b7843943ed32f6f323d81ab42611089d7483d74edc"} build_url:https://queue.taskcluster.net/v1/task/RFF75Kp2SparVrd4n0AhrQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011284 build_url: 'https://queue.taskcluster.net/v1/task/RFF75Kp2SparVrd4n0AhrQ/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/679fb9100cef8e874918e9cfc4b5f07e0f8a6589104827da262849ca60a0cb41314706d0d00786c5ab2776cf6e4aeb7e812dec9141cc200d78649093dffad909", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/bccfa3760272865efdb2e4428fda5e4cff82e6f6ca34a917856a34cbada2bf24cb4ed8dae7605c33e185a812610d6c1c1ef2024fcfa6cc5b0b3fbac02abe002a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/18aa8b529e3534ddc61c816f845eab2cf13c45cd5db2e625d192284013d689d48b0af1b6e16c2405aeea13b7843943ed32f6f323d81ab42611089d7483d74edc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 15:05:34.131882) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 15:05:34.132209) ========= 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.eqNPWzNZ0v/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.QgIbiQXf3K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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.005108 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 15:05:34.181349) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 15:05:34.181640) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 15:05:34.181977) ========= ========= Total master_lag: 0.17 =========