So, if we focus in the node where introspection run properly (c6f8f4ba-9c7c-4c87-b95a-67a5861b7bec), we can see following info:
1. Introspection finishes successfully with right information according to inspector logs:
2016-12-21 06:41:24.030 24006 INFO ironic_inspector.plugins.standard [-] [node: c6f8f4ba-9c7c-4c87-b95a-67a5861b7bec state processing MAC 00:fc:e0:2e:a1:ec] Discovered data: CPUs: 2 x86_64, memory 8192 MiB, disk 49 GiB 2016-12-21 06:41:26.408 24006 INFO ironic_inspector.process [-] [node: c6f8f4ba-9c7c-4c87-b95a-67a5861b7bec state None MAC 00:fc:e0:2e:a1:ec] Introspection finished successfully
2. Nova scheduler discards it by RamFilter:
2016-12-21 06:45:57.537 17759 DEBUG nova.filters [req-a2319864-8629-4b7f-8b94-d41911ad02d0 4f103e0230074c2488b7359bc079d323 f21dbfb3b2c840059ec2a0bba03b7385 - - -] Filter AvailabilityZoneFilter returned 1 host(s) get_filtered_objects /usr/lib/python2.7/site-packages/nova/filters.py:104 2016-12-21 06:45:57.538 17759 DEBUG nova.scheduler.filters.ram_filter [req-a2319864-8629-4b7f-8b94-d41911ad02d0 4f103e0230074c2488b7359bc079d323 f21dbfb3b2c840059ec2a0bba03b7385 - - -] (undercloud, c6f8f4ba-9c7c-4c87-b95a-67a5861b7bec) ram: 0MB disk: 0MB io_ops: 0 instances: 0 does not have 8192 MB usable ram before overcommit, it only has 0 MB. host_passes /usr/lib/python2.7/site-packages/nova/scheduler/filters/ram_filter.py:45
That's about 4 minutes and 30 seconds between end of introspection and nova scheduller error, I'd say that should be enough, what do you think?
So, if we focus in the node where introspection run properly (c6f8f4ba- 9c7c-4c87- b95a-67a5861b7b ec), we can see following info:
1. Introspection finishes successfully with right information according to inspector logs:
2016-12-21 06:41:24.030 24006 INFO ironic_ inspector. plugins. standard [-] [node: c6f8f4ba- 9c7c-4c87- b95a-67a5861b7b ec state processing MAC 00:fc:e0:2e:a1:ec] Discovered data: CPUs: 2 x86_64, memory 8192 MiB, disk 49 GiB inspector. process [-] [node: c6f8f4ba- 9c7c-4c87- b95a-67a5861b7b ec state None MAC 00:fc:e0:2e:a1:ec] Introspection finished successfully
2016-12-21 06:41:26.408 24006 INFO ironic_
2. Nova scheduler discards it by RamFilter:
2016-12-21 06:45:57.537 17759 DEBUG nova.filters [req-a2319864- 8629-4b7f- 8b94-d41911ad02 d0 4f103e0230074c2 488b7359bc079d3 23 f21dbfb3b2c8400 59ec2a0bba03b73 85 - - -] Filter AvailabilityZon eFilter returned 1 host(s) get_filtered_ objects /usr/lib/ python2. 7/site- packages/ nova/filters. py:104 filters. ram_filter [req-a2319864- 8629-4b7f- 8b94-d41911ad02 d0 4f103e0230074c2 488b7359bc079d3 23 f21dbfb3b2c8400 59ec2a0bba03b73 85 - - -] (undercloud, c6f8f4ba- 9c7c-4c87- b95a-67a5861b7b ec) ram: 0MB disk: 0MB io_ops: 0 instances: 0 does not have 8192 MB usable ram before overcommit, it only has 0 MB. host_passes /usr/lib/ python2. 7/site- packages/ nova/scheduler/ filters/ ram_filter. py:45
2016-12-21 06:45:57.538 17759 DEBUG nova.scheduler.
That's about 4 minutes and 30 seconds between end of introspection and nova scheduller error, I'd say that should be enough, what do you think?