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:~$