RTC 4.0 zOS bfagent -Why do we see cyclical login attempts in bfagent log - every 15 seconds?
![]()
In this case there is one build engine and no one was using it. The user on the build engine had an expired password that we were trying to resume. We had all users stop running any build requests and even after that we keep seeing a request to log in to the mainframe every 15 seconds.
From the person trying to get the build engine user id (BUILDENG) password resumed....... We are seeing cyclical errors every 15.5 seconds or so. I see ICH408I messages in the SYSLOG for the BUILDENG having been revoked. If I have the security team resume the password, and we test the build engine definition associated with the BUILDENG ID, it succeeds. However, after several of the 15.5 second iterations, all failing, the ID is revoked again. 3 cycles from the log are below. Tue Nov 6 11:24:12 2012 [33752463] daemon.c : 283: [0.0.0.0/5555] accepting [10.161.134.29/59813] (testrtc201as1l.usaa.com) Tue Nov 6 11:24:12 2012 [33752463] daemon.c : 307: [10.161.134.29/59813]: attached to pid 67306593 Tue Nov 6 11:24:12 2012 [67306593] daemon.c : 300: === NEW AGENT === Tue Nov 6 11:24:12 2012 [67306593] platform.c : 156: ICONV ok [IBM-1047] Tue Nov 6 11:24:12 2012 [67306593] platform.c :1873: LOCALE ok(LANG) [C] Tue Nov 6 11:24:12 2012 [67306593] io.c : 381: In start_SSL Tue Nov 6 11:24:12 2012 [67306593] io.c : 303: SSL init Tue Nov 6 11:24:12 2012 [67306593] io.c : 314: SSL ssl_key_location=(null) -> SSL off Tue Nov 6 11:24:12 2012 [67306593] agent.c : 529: tag: cmd Tue Nov 6 11:24:12 2012 [67306593] agent.c : 529: tag: username Tue Nov 6 11:24:12 2012 [67306593] agent.c : 529: tag: password Tue Nov 6 11:24:12 2012 [67306593] agent.c : 427: tag: go Tue Nov 6 11:24:12 2012 [67306593] agent.c : 358: agent_process_request Tue Nov 6 11:24:12 2012 [67306593] agent.c : 227: agent_process_command cmd=[ping] Tue Nov 6 11:24:12 2012 [67306593] agent.c : 81: agent_authenticate Tue Nov 6 11:24:12 2012 [67306593] agent.c : 119: AUTH normal Tue Nov 6 11:24:12 2012 [67306593] pam.c : 125: AUTH __passwd (z/OS) Tue Nov 6 11:24:12 2012 [67306593] agent.c : 264: AUTH failed Tue Nov 6 11:24:12 2012 [67306593] agent.c : 200: ClobberPasswords Tue Nov 6 11:24:12 2012 [67306593] agent.c : 411: tag: quit Tue Nov 6 11:24:12 2012 [67306593] agent.c : 596: cleanup Tue Nov 6 11:24:12 2012 [33752463] platform.c : 224: SIGCHLD(20) 67306593 -> bfdaemon: status=0 Tue Nov 6 11:24:28 2012 [33752463] daemon.c : 283: [0.0.0.0/5555] accepting [10.161.134.29/59817] (testrtc201as1l.usaa.com) Tue Nov 6 11:24:28 2012 [33752463] daemon.c : 307: [10.161.134.29/59817]: attached to pid 16975309 Tue Nov 6 11:24:28 2012 [16975309] daemon.c : 300: === NEW AGENT === Tue Nov 6 11:24:28 2012 [16975309] platform.c : 156: ICONV ok [IBM-1047] Tue Nov 6 11:24:28 2012 [16975309] platform.c :1873: LOCALE ok(LANG) [C] Tue Nov 6 11:24:28 2012 [16975309] io.c : 381: In start_SSL Tue Nov 6 11:24:28 2012 [16975309] io.c : 303: SSL init Tue Nov 6 11:24:28 2012 [16975309] io.c : 314: SSL ssl_key_location=(null) -> SSL off Tue Nov 6 11:24:28 2012 [16975309] agent.c : 529: tag: cmd Tue Nov 6 11:24:28 2012 [16975309] agent.c : 529: tag: username Tue Nov 6 11:24:28 2012 [16975309] agent.c : 529: tag: password Tue Nov 6 11:24:28 2012 [16975309] agent.c : 427: tag: go Tue Nov 6 11:24:28 2012 [16975309] agent.c : 358: agent_process_request Tue Nov 6 11:24:28 2012 [16975309] agent.c : 227: agent_process_command cmd=[ping] Tue Nov 6 11:24:28 2012 [16975309] agent.c : 81: agent_authenticate Tue Nov 6 11:24:28 2012 [16975309] agent.c : 119: AUTH normal Tue Nov 6 11:24:28 2012 [16975309] pam.c : 125: AUTH __passwd (z/OS) Tue Nov 6 11:24:28 2012 [16975309] agent.c : 264: AUTH failed Tue Nov 6 11:24:28 2012 [16975309] agent.c : 200: ClobberPasswords Tue Nov 6 11:24:28 2012 [16975309] agent.c : 411: tag: quit Tue Nov 6 11:24:29 2012 [16975309] agent.c : 596: cleanup Tue Nov 6 11:24:29 2012 [33752463] platform.c : 224: SIGCHLD(20) 16975309 -> bfdaemon: status=0 Tue Nov 6 11:24:43 2012 [33752463] daemon.c : 283: [0.0.0.0/5555] accepting [10.161.134.29/59819] (testrtc201as1l.usaa.com) Tue Nov 6 11:24:43 2012 [33752463] daemon.c : 307: [10.161.134.29/59819]: attached to pid 84083831 Tue Nov 6 11:24:43 2012 [84083831] daemon.c : 300: === NEW AGENT === Tue Nov 6 11:24:43 2012 [84083831] platform.c : 156: ICONV ok [IBM-1047] Tue Nov 6 11:24:43 2012 [84083831] platform.c :1873: LOCALE ok(LANG) [C] Tue Nov 6 11:24:43 2012 [84083831] io.c : 381: In start_SSL Tue Nov 6 11:24:43 2012 [84083831] io.c : 303: SSL init Tue Nov 6 11:24:43 2012 [84083831] io.c : 314: SSL ssl_key_location=(null) -> SSL off Tue Nov 6 11:24:43 2012 [84083831] agent.c : 529: tag: cmd Tue Nov 6 11:24:44 2012 [84083831] agent.c : 529: tag: username Tue Nov 6 11:24:44 2012 [84083831] agent.c : 529: tag: password Tue Nov 6 11:24:44 2012 [84083831] agent.c : 427: tag: go Tue Nov 6 11:24:44 2012 [84083831] agent.c : 358: agent_process_request Tue Nov 6 11:24:44 2012 [84083831] agent.c : 227: agent_process_command cmd=[ping] Tue Nov 6 11:24:44 2012 [84083831] agent.c : 81: agent_authenticate Tue Nov 6 11:24:44 2012 [84083831] agent.c : 119: AUTH normal Tue Nov 6 11:24:44 2012 [84083831] pam.c : 125: AUTH __passwd (z/OS) Tue Nov 6 11:24:44 2012 [84083831] agent.c : 264: AUTH failed Tue Nov 6 11:24:44 2012 [84083831] agent.c : 200: ClobberPasswords Tue Nov 6 11:24:44 2012 [84083831] agent.c : 411: tag: quit Tue Nov 6 11:24:44 2012 [84083831] agent.c : 596: cleanup Tue Nov 6 11:24:44 2012 [33752463] platform.c : 224: SIGCHLD(20) 84083831 -> bfdaemon: status=0 Tue Nov 6 11:24:59 2012 [33752463] daemon.c : 283: [0.0.0.0/5555] accepting [10.161.134.29/59823] (testrtc201as1l.usaa.com) Tue Nov 6 11:24:59 2012 [33752463] daemon.c : 307: [10.161.134.29/59823]: attached to pid 50529461 Tue Nov 6 11:24:59 2012 [50529461] daemon.c : 300: === NEW AGENT === Tue Nov 6 11:24:59 2012 [50529461] platform.c : 156: ICONV ok [IBM-1047] Tue Nov 6 11:24:59 2012 [50529461] platform.c :1873: LOCALE ok(LANG) [C] Tue Nov 6 11:24:59 2012 [50529461] io.c : 381: In start_SSL Tue Nov 6 11:24:59 2012 [50529461] io.c : 303: SSL init Tue Nov 6 11:24:59 2012 [50529461] io.c : 314: SSL ssl_key_location=(null) -> SSL off Tue Nov 6 11:24:59 2012 [50529461] agent.c : 529: tag: cmd Tue Nov 6 11:24:59 2012 [50529461] agent.c : 529: tag: username Tue Nov 6 11:24:59 2012 [50529461] agent.c : 529: tag: password Tue Nov 6 11:24:59 2012 [50529461] agent.c : 427: tag: go Tue Nov 6 11:24:59 2012 [50529461] agent.c : 358: agent_process_request Tue Nov 6 11:24:59 2012 [50529461] agent.c : 227: agent_process_command cmd=[ping] Tue Nov 6 11:24:59 2012 [50529461] agent.c : 81: agent_authenticate Tue Nov 6 11:24:59 2012 [50529461] agent.c : 119: AUTH normal Tue Nov 6 11:24:59 2012 [50529461] pam.c : 125: AUTH __passwd (z/OS) Tue Nov 6 11:24:59 2012 [50529461] agent.c : 264: AUTH failed Tue Nov 6 11:24:59 2012 [50529461] agent.c : 200: ClobberPasswords Tue Nov 6 11:25:00 2012 [50529461] agent.c : 411: tag: quit Tue Nov 6 11:25:00 2012 [50529461] agent.c : 596: cleanup Tue Nov 6 11:25:00 2012 [33752463] platform.c : 224: SIGCHLD(20) 50529461 -> bfdaemon: status=0 |