Waf: Entering directory `/locale/ns-3-bug555/build' Waf: Leaving directory `/locale/ns-3-bug555/build' 'build' finished successfully (0.452s) 0s 0 DcfManager:StartBackoffNow(): +0.0ns 0x25684c0 start backoff=0 slots 0s 0 DcfManager:StartBackoffNow(): +0.0ns 0x2569140 start backoff=13 slots 0s 0 DcfManager:StartBackoffNow(): +0.0ns 0x25695e0 start backoff=15 slots 0s 0 DcfManager:StartBackoffNow(): +0.0ns 0x2568c90 start backoff=7 slots 0s 0 DcfManager:StartBackoffNow(): +0.0ns 0x25687f0 start backoff=2 slots 0s 1 DcfManager:StartBackoffNow(): +0.0ns 0x256a9c0 start backoff=10 slots 0s 1 DcfManager:StartBackoffNow(): +0.0ns 0x256b640 start backoff=11 slots 0s 1 DcfManager:StartBackoffNow(): +0.0ns 0x256bae0 start backoff=0 slots 0s 1 DcfManager:StartBackoffNow(): +0.0ns 0x256b190 start backoff=1 slots 0s 1 DcfManager:StartBackoffNow(): +0.0ns 0x256acf0 start backoff=2 slots 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x2568290 dcf 0 dec backoff slots=0 1s -1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x25684c0 update slots=0 slots, backoff=0 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x2568290 dcf 1 dec backoff slots=2 1s -1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x25687f0 update slots=2 slots, backoff=0 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x2568290 dcf 2 dec backoff slots=7 1s -1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x2568c90 update slots=7 slots, backoff=0 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x2568290 dcf 3 dec backoff slots=13 1s -1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x2569140 update slots=13 slots, backoff=0 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x2568290 dcf 4 dec backoff slots=15 1s -1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x25695e0 update slots=15 slots, backoff=0 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:DoGrantAccess(): +1000000000.0ns 0x2568290 dcf 0 needs access. backoff expired. access granted. slots=0 1s -1 DcfManager:NotifyTxStartNow(): +1000000000.0ns 0x2568290 tx start for +1408000.0ns 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x2568290 dcf 0 dec backoff slots=0 1s -1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x25684c0 update slots=0 slots, backoff=0 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x2568290 dcf 1 dec backoff slots=0 1s -1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x25687f0 update slots=0 slots, backoff=0 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x2568290 dcf 2 dec backoff slots=0 1s -1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x2568c90 update slots=0 slots, backoff=0 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x2568290 dcf 3 dec backoff slots=0 1s -1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x2569140 update slots=0 slots, backoff=0 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x2568290 dcf 4 dec backoff slots=0 1s -1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x25695e0 update slots=0 slots, backoff=0 1s -1 DcfManager:StartBackoffNow(): +1000000000.0ns 0x25684c0 start backoff=0 slots 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:RequestAccess(): +1000000000.0ns 0x2568290 medium is busy: collision 1s -1 DcfManager:StartBackoffNow(): +1000000000.0ns 0x25684c0 start backoff=0 slots 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1s -1 DcfManager:DoRestartAccessTimeoutIfNeeded(): +1000000000.0ns 0x2568290 expected backoff end=+1001442000.0ns 1s 1 DcfManager:NotifyRxStartNow(): +1000000000.0ns 0x256a790 rx start for=+1408000.0ns 1s 1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s 1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x256a790 dcf 0 dec backoff slots=10 1s 1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x256a9c0 update slots=10 slots, backoff=0 1s 1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s 1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x256a790 dcf 1 dec backoff slots=2 1s 1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x256acf0 update slots=2 slots, backoff=0 1s 1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s 1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x256a790 dcf 2 dec backoff slots=1 1s 1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x256b190 update slots=1 slots, backoff=0 1s 1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s 1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x256a790 dcf 3 dec backoff slots=11 1s 1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x256b640 update slots=11 slots, backoff=0 1s 1 DcfManager:GetAccessGrantStart(): access grant start=+16000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1s 1 DcfManager:UpdateBackoff(): +1000000000.0ns 0x256a790 dcf 4 dec backoff slots=0 1s 1 DcfManager:UpdateBackoffSlotsNow(): +1000000000.0ns 0x256bae0 update slots=0 slots, backoff=0 1.00141s 1 DcfManager:NotifyRxEndOkNow(): +1001408000.0ns 0x256a790 rx end ok 1.00144s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1.00144s -1 DcfManager:UpdateBackoff(): +1001442000.0ns 0x2568290 dcf 0 dec backoff slots=0 1.00144s -1 DcfManager:UpdateBackoffSlotsNow(): +1001442000.0ns 0x25684c0 update slots=0 slots, backoff=0 1.00144s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1.00144s -1 DcfManager:UpdateBackoff(): +1001442000.0ns 0x2568290 dcf 1 dec backoff slots=0 1.00144s -1 DcfManager:UpdateBackoffSlotsNow(): +1001442000.0ns 0x25687f0 update slots=0 slots, backoff=0 1.00144s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1.00144s -1 DcfManager:UpdateBackoff(): +1001442000.0ns 0x2568290 dcf 2 dec backoff slots=0 1.00144s -1 DcfManager:UpdateBackoffSlotsNow(): +1001442000.0ns 0x2568c90 update slots=0 slots, backoff=0 1.00144s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1.00144s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1.00144s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1.00144s -1 DcfManager:DoGrantAccess(): +1001442000.0ns 0x2568290 dcf 0 needs access. backoff expired. access granted. slots=0 1.00144s -1 DcfManager:NotifyTxStartNow(): +1001442000.0ns 0x2568290 tx start for +1408000.0ns 1.00144s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1.00144s -1 DcfManager:UpdateBackoff(): +1001442000.0ns 0x2568290 dcf 0 dec backoff slots=0 1.00144s -1 DcfManager:UpdateBackoffSlotsNow(): +1001442000.0ns 0x25684c0 update slots=0 slots, backoff=0 1.00144s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1.00144s -1 DcfManager:UpdateBackoff(): +1001442000.0ns 0x2568290 dcf 1 dec backoff slots=0 1.00144s -1 DcfManager:UpdateBackoffSlotsNow(): +1001442000.0ns 0x25687f0 update slots=0 slots, backoff=0 1.00144s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1.00144s -1 DcfManager:UpdateBackoff(): +1001442000.0ns 0x2568290 dcf 2 dec backoff slots=0 1.00144s -1 DcfManager:UpdateBackoffSlotsNow(): +1001442000.0ns 0x2568c90 update slots=0 slots, backoff=0 1.00144s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1.00144s -1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+16000.0ns, busy access start=+16000.0ns, tx access start=+1001424000.0ns, nav access start=+16000.0ns 1.00144s -1 DcfManager:StartBackoffNow(): +1001442000.0ns 0x25684c0 start backoff=0 slots 1.00144s 1 DcfManager:NotifyRxStartNow(): +1001442000.0ns 0x256a790 rx start for=+1408000.0ns 1.00144s 1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+1001424000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1.00144s 1 DcfManager:UpdateBackoff(): +1001442000.0ns 0x256a790 dcf 0 dec backoff slots=0 1.00144s 1 DcfManager:UpdateBackoffSlotsNow(): +1001442000.0ns 0x256a9c0 update slots=0 slots, backoff=0 1.00144s 1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+1001424000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1.00144s 1 DcfManager:UpdateBackoff(): +1001442000.0ns 0x256a790 dcf 1 dec backoff slots=0 1.00144s 1 DcfManager:UpdateBackoffSlotsNow(): +1001442000.0ns 0x256acf0 update slots=0 slots, backoff=0 1.00144s 1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+1001424000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1.00144s 1 DcfManager:UpdateBackoff(): +1001442000.0ns 0x256a790 dcf 2 dec backoff slots=0 1.00144s 1 DcfManager:UpdateBackoffSlotsNow(): +1001442000.0ns 0x256b190 update slots=0 slots, backoff=0 1.00144s 1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+1001424000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1.00144s 1 DcfManager:GetAccessGrantStart(): access grant start=+1001424000.0ns, rx access start=+1001424000.0ns, busy access start=+16000.0ns, tx access start=+16000.0ns, nav access start=+16000.0ns 1.00285s 1 DcfManager:NotifyRxEndOkNow(): +1002850000.0ns 0x256a790 rx end ok sent=2 received=2 ok