I didn't write a leading dot in front of nav.ipdevpoll.plugins.bridge in my original message, I hope you didn't do that in logging.conf either...
The dot was accidently added the reply, not in the logging.conf ;-)
Ok, i have done what you have asked and i added another Gbe2c to see how it will be populated. It does give some errors now:
I see the profiling job fails, that's interesting in itself, but unrelated to the issue at hand.
The bridge plugin doesn't report any findings of switch ports, which is strange. I want to add some more debugging to see if the bridge plugin is ever run (it runs in the inventory >job, so the log messages marked as inventory are the interesting ones here).
Please add these three options to logging.conf and try again:
nav.ipdevpoll.jobs.jobhandler = DEBUG nav.ipdevpoll.jobs.jobhandler.timings = INFO nav.ipdevpoll.jobs.jobhandler.queue = INFO
Allright, this is the content of the loggings.conf at this moment:
[levels] nav.ipdevpoll = INFO nav.ipdevpoll.plugins.bridge = DEBUG nav.ipdevpoll.jobs.jobhandler = DEBUG nav.ipdevpoll.jobs.jobhandler.timings = INFO nav.ipdevpoll.jobs.jobhandler.queue = INFO
I added another GbE2c (I have some... ;-) ), to see how it does reflect in the loggings:
2012-03-23 11:42:15,756 [DEBUG jobs.jobhandler] [topo 172.17.205.19] Job 'topo' initialized with plugins: ['cam', 'lldp', 'cdp'] 2012-03-23 11:42:15,759 [DEBUG jobs.jobhandler] [topo 172.17.205.19] AgentProxy created for 172.17.205.19: <nav.ipdevpoll.snmp.pynetsnmp.AgentProxy object at 0xb37c35cc> 2012-03-23 11:42:15,781 [DEBUG jobs.jobhandler] [snmpcheck 172.17.205.19] Job 'snmpcheck' initialized with plugins: ['snmpcheck'] 2012-03-23 11:42:15,783 [DEBUG jobs.jobhandler] [snmpcheck 172.17.205.19] AgentProxy created for 172.17.205.19: <nav.ipdevpoll.snmp.pynetsnmp.AgentProxy object at 0xb8cf86c> 2012-03-23 11:42:15,783 [DEBUG jobs.jobhandler] [snmpcheck 172.17.205.19] Plugins to call: SnmpCheck 2012-03-23 11:42:15,784 [DEBUG jobs.jobhandler] [snmpcheck 172.17.205.19] Starting job 'snmpcheck' for 172.17.205.19 2012-03-23 11:42:15,784 [DEBUG jobs.jobhandler] [snmpcheck 172.17.205.19] Now calling plugin: nav.ipdevpoll.plugins.snmpcheck.SnmpCheck(u'172.17.205.19') 2012-03-23 11:42:15,805 [DEBUG jobs.jobhandler] [linkcheck 172.17.205.19] Job 'linkcheck' initialized with plugins: ['linkstate'] 2012-03-23 11:42:15,807 [DEBUG jobs.jobhandler] [linkcheck 172.17.205.19] AgentProxy created for 172.17.205.19: <nav.ipdevpoll.snmp.pynetsnmp.AgentProxy object at 0xaec07ec> 2012-03-23 11:42:15,816 [DEBUG jobs.jobhandler] [linkcheck 172.17.205.19] Plugins to call: LinkState 2012-03-23 11:42:15,816 [DEBUG jobs.jobhandler] [linkcheck 172.17.205.19] Starting job 'linkcheck' for 172.17.205.19 2012-03-23 11:42:15,816 [DEBUG jobs.jobhandler] [linkcheck 172.17.205.19] Now calling plugin: nav.ipdevpoll.plugins.linkstate.LinkState(u'172.17.205.19') 2012-03-23 11:42:15,831 [DEBUG jobs.jobhandler] [inventory 172.17.205.19] Job 'inventory' initialized with plugins: ['dnsname', 'typeoid', 'system', 'modules', 'ciscosup', 'bridge', 'interfaces', 'dot1q', 'ciscovlan', 'extremevlan', 'prefix', 'sensors', 'psu', 'lastupdated'] 2012-03-23 11:42:15,833 [DEBUG jobs.jobhandler] [inventory 172.17.205.19] AgentProxy created for 172.17.205.19: <nav.ipdevpoll.snmp.pynetsnmp.AgentProxy object at 0xaec084c> 2012-03-23 11:42:15,868 [DEBUG jobs.jobhandler] [inventory 172.17.205.19] Plugin ciscosup wouldn't handle 172.17.205.19 2012-03-23 11:42:15,974 [DEBUG jobs.jobhandler] [inventory 172.17.205.19] Plugins to call: DnsName,TypeOid,System,Modules,Bridge,Interfaces,Dot1q,CiscoVlan,ExtremeVlan,Prefix,Sensors,PowerSupplyUnit,LastUpdated 2012-03-23 11:42:15,974 [DEBUG jobs.jobhandler] [inventory 172.17.205.19] Starting job 'inventory' for 172.17.205.19 2012-03-23 11:42:15,975 [DEBUG jobs.jobhandler] [inventory 172.17.205.19] Now calling plugin: nav.ipdevpoll.plugins.dnsname.DnsName(u'172.17.205.19') 2012-03-23 11:42:15,979 [DEBUG jobs.jobhandler] [profiling 172.17.205.19] Job 'profiling' initialized with plugins: ['oidprofiler'] 2012-03-23 11:42:15,981 [DEBUG jobs.jobhandler] [profiling 172.17.205.19] AgentProxy created for 172.17.205.19: <nav.ipdevpoll.snmp.pynetsnmp.AgentProxy object at 0xb8bd24c> 2012-03-23 11:42:15,987 [DEBUG jobs.jobhandler] [profiling 172.17.205.19] Plugins to call: OidProfiler 2012-03-23 11:42:15,987 [DEBUG jobs.jobhandler] [profiling 172.17.205.19] Starting job 'profiling' for 172.17.205.19 2012-03-23 11:42:15,987 [DEBUG jobs.jobhandler] [profiling 172.17.205.19] Now calling plugin: nav.ipdevpoll.plugins.oidprofiler.OidProfiler(u'172.17.205.19') 2012-03-23 11:42:16,016 [DEBUG jobs.jobhandler] [logging 172.17.205.19] Job 'logging' initialized with plugins: ['arp'] 2012-03-23 11:42:16,017 [DEBUG jobs.jobhandler] [logging 172.17.205.19] AgentProxy created for 172.17.205.19: <nav.ipdevpoll.snmp.pynetsnmp.AgentProxy object at 0xb8c666c> 2012-03-23 11:42:16,017 [DEBUG jobs.jobhandler] [logging 172.17.205.19] Plugin arp wouldn't handle 172.17.205.19 2012-03-23 11:42:16,017 [DEBUG jobs.jobhandler] [logging 172.17.205.19] No plugins for this job 2012-03-23 11:42:16,074 [WARNING plugins.dnsname.dnsname] [inventory 172.17.205.19] DNS lookup error for 172.17.205.19: DNSNameError 2012-03-23 11:42:16,074 [WARNING plugins.dnsname.dnsname] [inventory 172.17.205.19] Unable to find PTR record for 172.17.205.19 (19.205.17.172.in-addr.arpa.) 2012-03-23 11:42:16,074 [DEBUG jobs.jobhandler] [inventory 172.17.205.19] Now calling plugin: nav.ipdevpoll.plugins.typeoid.TypeOid(u'172.17.205.19') 2012-03-23 11:42:16,597 [DEBUG jobs.jobhandler] [topo 172.17.205.19] Plugin cam wouldn't handle 172.17.205.19 2012-03-23 11:42:17,187 [DEBUG jobs.jobhandler] [snmpcheck 172.17.205.19] Storing to database complete (11.158 ms) 2012-03-23 11:42:17,884 [DEBUG jobs.jobhandler] [topo 172.17.205.19] Plugin lldp wouldn't handle 172.17.205.19 2012-03-23 11:42:17,897 [DEBUG jobs.jobhandler] [snmpcheck 172.17.205.19] Job snmpcheck for 172.17.205.19 done. 2012-03-23 11:42:17,901 [INFO schedule.netboxjobscheduler] [snmpcheck 172.17.205.19] completed in 0:00:02.119964. next run in 0:29:57.880076. 2012-03-23 11:42:18,848 [DEBUG jobs.jobhandler] [topo 172.17.205.19] Plugin cdp wouldn't handle 172.17.205.19 2012-03-23 11:42:18,848 [DEBUG jobs.jobhandler] [topo 172.17.205.19] No plugins for this job 2012-03-23 11:42:18,855 [DEBUG jobs.jobhandler] [inventory 172.17.205.19] Now calling plugin: nav.ipdevpoll.plugins.system.System(u'172.17.205.19') 2012-03-23 11:42:19,461 [DEBUG jobs.jobhandler] [linkcheck 172.17.205.19] Storing to database complete (217.207 ms) 2012-03-23 11:42:19,677 [DEBUG jobs.jobhandler] [linkcheck 172.17.205.19] Job linkcheck for 172.17.205.19 done. 2012-03-23 11:42:19,682 [INFO schedule.netboxjobscheduler] [linkcheck 172.17.205.19] completed in 0:00:03.876638. next run in 0:04:56.123420. 2012-03-23 11:42:20,542 [DEBUG jobs.jobhandler] [inventory 172.17.205.19] Now calling plugin: nav.ipdevpoll.plugins.modules.Modules(u'172.17.205.19') 2012-03-23 11:42:23,376 [DEBUG jobs.jobhandler] [inventory 172.17.205.19] Now calling plugin: nav.ipdevpoll.plugins.bridge.Bridge(u'172.17.205.19') 2012-03-23 11:43:17,743 [INFO plugins.oidprofiler.oidprofiler] [profiling 172.17.205.19] profile update: add 46 / del 0 2012-03-23 11:43:17,776 [ERROR jobs.jobhandler] [profiling 172.17.205.19] Caught exception during save. Last manager = DefaultManager(<class 'nav.ipdevpoll.shadows.NetboxSnmpOid'>, 'ContainerRepository'(...)). Last model = <class 'nav.ipdevpoll.shadows.NetboxSnmpOid'> 2012-03-23 11:43:17,788 [INFO schedule.netboxjobscheduler] [profiling 172.17.205.19] failed in 0:01:01.808774. next run in 0:09:56.999967. 2012-03-23 11:43:17,802 [ERROR schedule.netboxjobscheduler] [profiling 172.17.205.19] Unhandled exception raised by JobHandler