From RZR, 6 Days ago, written in Plain Text.
Embed
  1. librenms@LibreNMS:~$ ./poller.php -h OSMC -d -v -m applications
  2. LibreNMS Poller
  3. SQL[select `migration` from `migrations` order by `id` desc limit 1 [] 0.27ms]
  4.  
  5. SQL[select count(*) as aggregate from `migrations` limit 1 [] 0.25ms]
  6.  
  7. SQL[select version() [] 0.15ms]
  8.  
  9. ===================================
  10. Version info:
  11. Commit SHA: 759dcb1babf7eb7af52192d30377962c783bb61c
  12. Commit Date: 1641825018
  13. DB Schema: 2021_11_29_165436_improve_ports_search_index (229)
  14. PHP: 7.4.25
  15. MySQL: 10.5.12-MariaDB-0+deb11u1
  16. RRDTool: 1.7.2
  17. SNMP: 5.9
  18. ==================================DEBUG!
  19. Updating os_def.cache  
  20. Override poller modules: applications  
  21. Starting polling run:
  22.  
  23. SQL[SELECT * FROM `devices` WHERE `disabled` = 0 AND `hostname` = 'OSMC' ORDER BY `device_id` ASC [] 0.58ms]
  24.  
  25. SQL[select * from `devices` where `device_id` = ? limit 1 [8] 0.29ms]
  26.  
  27. SQL[select * from `devices_attribs` where `devices_attribs`.`device_id` = ? and `devices_attribs`.`device_id` is not null [8] 0.22ms]
  28.  
  29. Attempting to initialize OS: linux  
  30. Attempting to initialize Group OS: unix  
  31. OS initialized: LibreNMS\OS\Shared\Unix  
  32. Hostname:    OSMC
  33. Device ID:   8
  34. OS:          linux
  35. Assigned IP: 10.227.196.4
  36.  (unix)
  37.  
  38. [FPING] '/usr/bin/fping' '-e' '-q' '-c' '2' '-p' '200' '-t' '200' '-O' '0' '10.227.196.4'
  39.  
  40. response: xmt/rcv/%loss = 2/2/0%, min/avg/max = 0.381/0.394/0.406  
  41. SNMP['/usr/bin/snmpget' '-M' '/opt/librenms/mibs' '-v3' '-l' 'authPriv' '-n' "" '-x' 'AES' '-X' '$Q0GLnG1DhzzNgc6C$sKGJwu' '-a' 'SHA' '-A' '$efjyExR81kNw!PBi#6AbRAx' '-u' 'LibreNMS' '-OQXUte' 'udp:10.227.196.4:161' 'SNMPv2-MIB::sysObjectID.0']  
  42. SNMPv2-MIB::sysObjectID.0 = SNMPv2-SMI::enterprises.8072.3.2.10
  43.  
  44.  
  45. SQL[insert into `device_perf` (`min`, `max`, `avg`, `xmt`, `rcv`, `loss`, `device_id`, `timestamp`) values (?, ?, ?, ?, ?, ?, ?, ?) [0.381,0.406,0.394,2,2,0,8,"2022-01-11 22:47:08"] 0.33ms]
  46.  
  47. SQL[update `devices` set `last_ping_timetaken` = ? where `device_id` = ? [0.394,8] 0.27ms]
  48.  
  49. RRD[last OSMC/ping-perf.rrd  --daemon unix:/var/run/rrdcached.sock]  
  50. RRDtool Output: 1641933900
  51. OK u:0.00 s:0.00 r:0.23
  52. RRD[update OSMC/ping-perf.rrd N:0.394 --daemon unix:/var/run/rrdcached.sock]  
  53. RRDtool Output: SQL[select * from `device_outages` where `device_outages`.`device_id` = ? and `device_outages`.`device_id` is not null and `up_again` is null order by `going_down` desc limit 1 [8] 0.37ms]
  54.  
  55. Modules status: Global+  
  56. OS  
  57. Device  
  58.  
  59. #### Load poller module core ####
  60. SNMP['/usr/bin/snmpget' '-M' '/opt/librenms/mibs' '-v3' '-l' 'authPriv' '-n' "" '-x' 'AES' '-X' '$Q0GLnG1DhzzNgc6C$sKGJwu' '-a' 'SHA' '-A' '$efjyExR81kNw!PBi#6AbRAx' '-u' 'LibreNMS' '-OQXUte' '-On' 'udp:10.227.196.4:161' 'SNMPv2-MIB::sysDescr.0' 'SNMPv2-MIB::sysObjectID.0' 'SNMPv2-MIB::sysUpTime.0' 'SNMPv2-MIB::sysName.0']  
  61. .1.3.6.1.2.1.1.1.0 = Linux osmc 5.10.78-2-osmc #1 SMP PREEMPT Wed Nov 17 04:52:11 UTC 2021 aarch64
  62. .1.3.6.1.2.1.1.2.0 = .1.3.6.1.4.1.8072.3.2.10
  63. .1.3.6.1.2.1.1.3.0 = 64599151
  64. .1.3.6.1.2.1.1.5.0 = osmc
  65.  
  66.  
  67. SNMP['/usr/bin/snmpget' '-M' '/opt/librenms/mibs' '-v3' '-l' 'authPriv' '-n' "" '-x' 'AES' '-X' '$Q0GLnG1DhzzNgc6C$sKGJwu' '-a' 'SHA' '-A' '$efjyExR81kNw!PBi#6AbRAx' '-u' 'LibreNMS' '-OQXUte' 'udp:10.227.196.4:161' 'SNMP-FRAMEWORK-MIB::snmpEngineTime.0' 'HOST-RESOURCES-MIB::hrSystemUptime.0']  
  68. SNMP-FRAMEWORK-MIB::snmpEngineTime.0 = 645992
  69. HOST-RESOURCES-MIB::hrSystemUptime.0 = 129330069
  70.  
  71.  
  72. Uptime seconds: 1293301
  73.  
  74. RRD[last OSMC/uptime.rrd  --daemon unix:/var/run/rrdcached.sock]  
  75. RRDtool Output: 1641933900
  76. OK u:0.00 s:0.00 r:0.28
  77. RRD[update OSMC/uptime.rrd N:1293301 --daemon unix:/var/run/rrdcached.sock]  
  78. RRDtool Output: OK u:0.00 s:0.00 r:0.23
  79. Uptime:  14 days 23 hours 15 minutes 1 second
  80. SQL[update `devices` set `uptime` = ? where `device_id` = ? [1293301,8] 0.31ms]
  81.  
  82.  
  83. >> Runtime for poller module 'core': 0.0487 seconds with 56984 bytes
  84. >> SNMP: [2/0.05s] MySQL: [1/0.00s] RRD: [2/0.00s]  
  85. #### Unload poller module core ####
  86.  
  87. RRD[last OSMC/poller-perf-core.rrd  --daemon unix:/var/run/rrdcached.sock]  
  88. RRDtool Output: 1641933900
  89. OK u:0.00 s:0.00 r:0.28
  90. RRD[update OSMC/poller-perf-core.rrd N:0.048726797103882 --daemon unix:/var/run/rrdcached.sock]  
  91. RRDtool Output: OK u:0.00 s:0.00 r:0.27
  92. Modules status: Global+  
  93. OS  
  94. Device  
  95.  
  96. #### Load poller module applications ####
  97. SQL[SELECT * FROM `applications` WHERE `device_id`  = ? [8] 0.26ms]
  98.  
  99. Application: docker, app_id=10SNMP['/usr/bin/snmpget' '-v3' '-l' 'authPriv' '-n' "" '-a' 'SHA' '-A' '$efjyExR81kNw!PBi#6AbRAx' '-u' 'LibreNMS' '-x' 'AES' '-X' '$Q0GLnG1DhzzNgc6C$sKGJwu' '-Oqv' '-m' 'NET-SNMP-EXTEND-MIB' '-M' '/opt/librenms/mibs' 'udp:10.227.196.4:161' 'nsExtendOutputFull.6.100.111.99.107.101.114']
  100. {"version":"1","data":[{"container":"rtorrent_logs","pids":1,"memory":{"used":"1.68MiB","limit":"3.705GiB","perc":"0.04%"},"cpu":"0.01%"},{"container":"rtorrent","pids":150,"memory":{"used":"334.7MiB","limit":"3.705GiB","perc":"8.82%"},"cpu":"0.28%"},{"container":"rtorrent_geoip","pids":10,"memory":{"used":"25.9MiB","limit":"3.705GiB","perc":"0.68%"},"cpu":"0.00%"}],"error":"0","errorString":""}  
  101.  
  102. RRD[last OSMC/app-docker-10-rtorrent_logs.rrd  --daemon unix:/var/run/rrdcached.sock]  
  103. RRDtool Output: 1641933900
  104. OK u:0.00 s:0.01 r:3.12
  105. RRD[update OSMC/app-docker-10-rtorrent_logs.rrd N:0.01:1:3978213457:1761607:0.04 --daemon unix:/var/run/rrdcached.sock]  
  106. RRDtool Output: OK u:0.00 s:0.00 r:0.28
  107. RRD[last OSMC/app-docker-10-rtorrent.rrd  --daemon unix:/var/run/rrdcached.sock]  
  108. RRDtool Output: 1641933900
  109. OK u:0.00 s:0.01 r:3.12
  110. RRD[update OSMC/app-docker-10-rtorrent.rrd N:0.28:150:3978213457:350958387:8.82 --daemon unix:/var/run/rrdcached.sock]  
  111. RRDtool Output: OK u:0.00 s:0.00 r:3.12
  112. OK u:0.00 s:0.00 r:3.12
  113. RRD[last OSMC/app-docker-10-rtorrent_geoip.rrd  --daemon unix:/var/run/rrdcached.sock]  
  114. RRDtool Output: 1641933900
  115. OK u:0.00 s:0.01 r:3.12
  116. RRD[update OSMC/app-docker-10-rtorrent_geoip.rrd N:0:10:3978213457:27158118:0.68 --daemon unix:/var/run/rrdcached.sock]  
  117. RRDtool Output: SQL[UPDATE `applications` set `app_state`=?,`app_status`=?,`timestamp`=NOW() WHERE `app_id` = ? ["OK","",10] 0.3ms]
  118.  
  119. SQL[SELECT * FROM `application_metrics` WHERE app_id=? [10] 0.22ms]
  120.  
  121. : .....SQL[UPDATE `application_metrics` set `value`=?,`value_prev`=? WHERE app_id=? && metric=? [0.28,0.36,10,"rtorrent_cpu_usage"] 0.15ms]
  122.  
  123. U..SQL[UPDATE `application_metrics` set `value`=?,`value_prev`=? WHERE app_id=? && metric=? [350958387,350643814,10,"rtorrent_mem_used"] 0.18ms]
  124.  
  125. U......
  126. Application: os-updates, app_id=12SNMP['/usr/bin/snmpget' '-v3' '-l' 'authPriv' '-n' "" '-a' 'SHA' '-A' '$efjyExR81kNw!PBi#6AbRAx' '-u' 'LibreNMS' '-x' 'AES' '-X' '$Q0GLnG1DhzzNgc6C$sKGJwu' '-Oqv' '-m' 'NET-SNMP-EXTEND-MIB' '-M' '/opt/librenms/mibs' 'udp:10.227.196.4:161' '.1.3.6.1.4.1.8072.1.3.2.4.1.2.8.111.115.117.112.100.97.116.101.1']
  127. 0  
  128.  
  129. RRD[last OSMC/app-os-updates-12.rrd  --daemon unix:/var/run/rrdcached.sock]  
  130. RRDtool Output: 1641933900
  131. OK u:0.00 s:0.01 r:3.19
  132. RRD[update OSMC/app-os-updates-12.rrd N:0 --daemon unix:/var/run/rrdcached.sock]  
  133. RRDtool Output: OK u:0.00 s:0.00 r:3.12
  134. SQL[UPDATE `applications` set `app_state`=?,`app_status`=?,`timestamp`=NOW() WHERE `app_id` = ? ["OK","0",12] 0.31ms]
  135.  
  136. SQL[SELECT * FROM `application_metrics` WHERE app_id=? [12] 0.24ms]
  137.  
  138. : .
  139.  
  140.  
  141. >> Runtime for poller module 'applications': 2.9104 seconds with 13040 bytes
  142. >> SNMP: [2/2.91s] MySQL: [7/0.02s] RRD: [10/0.00s]  
  143. #### Unload poller module applications ####
  144.  
  145. RRD[last OSMC/poller-perf-applications.rrd  --daemon unix:/var/run/rrdcached.sock]  
  146. RRDtool Output: 1641933900
  147. OK u:0.00 s:0.01 r:3.19
  148. RRD[update OSMC/poller-perf-applications.rrd N:2.9103600978851 --daemon unix:/var/run/rrdcached.sock]  
  149. RRDtool Output: OK u:0.00 s:0.00 r:3.19
  150. RRD[last OSMC/poller-perf.rrd  --daemon unix:/var/run/rrdcached.sock]  
  151. RRDtool Output: 1641933900
  152. OK u:0.00 s:0.01 r:3.19
  153. RRD[update OSMC/poller-perf.rrd N:3.197 --daemon unix:/var/run/rrdcached.sock]  
  154. RRDtool Output: OK u:0.00 s:0.00 r:3.19
  155.  
  156. Polled in 3.197 seconds
  157. ### Start Device Groups ###
  158. SQL[select * from `device_groups` [] 0.14ms]
  159.  
  160. SQL[select * from `device_group_device` where `device_group_device`.`device_id` = ? [8] 0.18ms]
  161.  
  162. Groups Added:  
  163. Groups Removed:  
  164. ### End Device Groups, runtime: 0.0027s ###
  165.  
  166. #### Start Alerts ####
  167. SQL[select `device_groups`.*, `device_group_device`.`device_id` as `pivot_device_id`, `device_group_device`.`device_group_id` as `pivot_device_group_id` from `device_groups` inner join `device_group_device` on `device_groups`.`id` = `device_group_device`.`device_group_id` where `device_group_device`.`device_id` = ? [8] 0.19ms]
  168.  
  169. SQL[select * from `locations` where `locations`.`id` = ? limit 1 [3] 0.2ms]
  170.  
  171. SQL[select exists(select * from `alert_schedule` where (`start` &lt;= ? and `end` >= ? and (`recurring` = ? or (`recurring` = ? and ((time(`start`) < time xss=removed> ?) or (time(`start`) > time(`end`) and (time(`end`) &lt;= ? or time(`start`) > ?))) and (`recurring_day` like ? or `recurring_day` is null)))) and (exists (select * from `devices` inner join `alert_schedulables` on `devices`.`device_id` = `alert_schedulables`.`alert_schedulable_id` where `alert_schedule`.`schedule_id` = `alert_schedulables`.`schedule_id` and `alert_schedulables`.`alert_schedulable_type` = ? and `alert_schedulables`.`alert_schedulable_id` = ?) or exists (select * from `locations` inner join `alert_schedulables` on `locations`.`id` = `alert_schedulables`.`alert_schedulable_id` where `alert_schedule`.`schedule_id` = `alert_schedulables`.`schedule_id` and `alert_schedulables`.`alert_schedulable_type` = ? and `alert_schedulables`.`alert_schedulable_id` = ?))) as `exists` ["2022-01-11T20:47:11.620045Z","2022-01-11T20:47:11.620045Z",0,1,"20:47:11","20:47:11","20:47:11","20:47:11","%","device",8,"location",3] 0.45ms]
  172.  
  173. SQL[select * from `devices` where `devices`.`device_id` = ? limit 1 [8] 0.31ms]
  174.  
  175. SQL[SELECT DISTINCT a.* FROM alert_rules a
  176.         LEFT JOIN alert_device_map d ON a.id=d.rule_id AND (a.invert_map = 0 OR a.invert_map = 1 AND d.device_id = ?)
  177.         LEFT JOIN alert_group_map g ON a.id=g.rule_id AND (a.invert_map = 0 OR a.invert_map = 1 AND g.group_id IN (SELECT DISTINCT device_group_id FROM device_group_device WHERE device_id = ?))
  178.         LEFT JOIN alert_location_map l ON a.id=l.rule_id AND (a.invert_map = 0 OR a.invert_map = 1 AND l.location_id IN (SELECT DISTINCT location_id FROM devices WHERE device_id = ?))
  179.         LEFT JOIN device_group_device dg ON g.group_id=dg.device_group_id AND dg.device_id = ?
  180.         WHERE a.disabled = 0 AND (
  181.             (d.device_id IS NULL AND g.group_id IS NULL)
  182.             OR (a.invert_map = 0 AND (d.device_id=? OR dg.device_id=?))
  183.             OR (a.invert_map = 1  AND (d.device_id != ? OR d.device_id IS NULL) AND (dg.device_id != ? OR dg.device_id IS NULL))
  184.         ) [8,8,8,8,8,8,8,8] 0.67ms]
  185.  
  186. #### End Alerts ####
  187. ./poller.php OSMC 2022-01-11 22:47:11 - 1 devices polled in 3.408 secs  
  188.  
  189. SNMP [5/2.98s]: Snmpget[5/2.98s]  
  190. SQL [27/0.09s]: Select[20/0.07s] Insert[1/0.00s] Update[6/0.02s]  
  191. RRD [18/0.00s]: Other[9/0.00s] Update[9/0.00s]  
  192. librenms@LibreNMS:~$