2019-12-03 14:27:01.28749216.0MbinfoCronjob execution started. PID: 18923
2019-12-03 14:27:01.30894416.0MbinfoFound 27,322 BSP records
2019-12-03 14:27:01.37600216.0MbinfoResume from bsp record 0
2019-12-03 14:38:11.19782524.0MbwaitingSystem busy. Load: 3.53, expected:3.5. Sleeping 11s
2019-12-03 14:38:22.20702124.0MbwaitingSystem busy. Load: 4.05, expected:3.5. Sleeping 11s
2019-12-03 14:38:33.21644524.0MbwaitingSystem busy. Load: 3.73, expected:3.5. Sleeping 11s
2019-12-03 14:38:44.22344224.0MbwaitingSystem busy. Load: 3.69, expected:3.5. Sleeping 11s
2019-12-03 14:38:55.23160624.0MbwaitingSystem busy. Load: 3.74, expected:3.5. Sleeping 11s
2019-12-03 14:39:06.23641324.0MbwaitingSystem busy. Load: 4.41, expected:3.5. Sleeping 11s
2019-12-03 14:39:17.24595624.0MbwaitingSystem busy. Load: 5.78, expected:3.5. Sleeping 31s
2019-12-03 14:39:48.25444524.0MbwaitingSystem busy. Load: 5.68, expected:3.5. Sleeping 31s
2019-12-03 14:40:19.26340024.0MbwaitingSystem busy. Load: 5.36, expected:3.5. Sleeping 21s
2019-12-03 14:40:40.27131724.0MbwaitingSystem busy. Load: 5.14, expected:3.5. Sleeping 21s
2019-12-03 14:41:01.27950924.0MbwaitingSystem busy. Load: 4.45, expected:3.5. Sleeping 11s
2019-12-03 14:41:12.28754324.0MbwaitingSystem busy. Load: 4.57, expected:3.5. Sleeping 21s
2019-12-03 14:41:33.29505724.0MbwaitingSystem busy. Load: 4.16, expected:3.5. Sleeping 11s
2019-12-03 14:41:44.30379124.0MbwaitingSystem busy. Load: 3.91, expected:3.5. Sleeping 11s
2019-12-03 14:41:55.31217124.0MbwaitingSystem busy. Load: 3.77, expected:3.5. Sleeping 11s
2019-12-03 14:42:06.38528324.0MbwaitingSystem busy. Load: 3.62, expected:3.5. Sleeping 11s
2019-12-03 14:43:06.43359824.0MbwaitingSystem busy. Load: 3.78, expected:3.5. Sleeping 11s
2019-12-03 14:43:17.44088024.0MbwaitingSystem busy. Load: 3.74, expected:3.5. Sleeping 11s
2019-12-03 14:44:19.47850438.0MbwaitingSystem busy. Load: 3.56, expected:3.5. Sleeping 11s
2019-12-03 14:44:30.48846638.0MbwaitingSystem busy. Load: 3.56, expected:3.5. Sleeping 11s
2019-12-03 14:44:41.51023838.0MbwaitingSystem busy. Load: 3.52, expected:3.5. Sleeping 11s
2019-12-03 14:45:51.56492938.0MbwaitingSystem busy. Load: 3.52, expected:3.5. Sleeping 11s
2019-12-03 14:46:02.57328238.0MbwaitingSystem busy. Load: 3.6, expected:3.5. Sleeping 11s
2019-12-03 14:46:13.58222138.0MbwaitingSystem busy. Load: 3.66, expected:3.5. Sleeping 11s
2019-12-03 14:47:11.58623438.0MbwaitingSystem busy. Load: 3.55, expected:3.5. Sleeping 11s
2019-12-03 14:48:21.68540038.0MbwaitingSystem busy. Load: 3.59, expected:3.5. Sleeping 11s
2019-12-03 14:48:32.81900538.0MbwaitingSystem busy. Load: 3.65, expected:3.5. Sleeping 11s
2019-12-03 14:53:16.92163140.0MbwaitingSystem busy. Load: 3.74, expected:3.5. Sleeping 11s
2019-12-03 14:53:27.92940940.0MbwaitingSystem busy. Load: 4.45, expected:3.5. Sleeping 11s
2019-12-03 14:53:38.93944940.0MbwaitingSystem busy. Load: 4.22, expected:3.5. Sleeping 11s
2019-12-03 14:53:49.94780740.0MbwaitingSystem busy. Load: 3.65, expected:3.5. Sleeping 11s
2019-12-03 14:54:40.47641840.0MbsuccessScanned all BSP records. It took 1659.2s
2019-12-03 14:54:40.68359840.0MbinfoFound 0 ImportedOrders services
2019-12-03 14:54:40.68373240.0MbwarningNo pre-parsed importedServices records found. The reported progress will not be accurate
2019-12-03 14:54:40.83677140.0Mbinfo > scan systems: amadeus, galileo, sabre, worldspan, manual
2019-12-03 14:54:40.83698740.0Mbinfo > scan services: k, x
2019-12-03 14:54:40.84104940.0MbinfoResume from imported service 0
2019-12-03 14:54:40.84125940.0Mbinfo > ignoring 0 suppliers
2019-12-03 14:54:40.89588440.0MbinfoScanned 0 imported services, and ignored 0 services
2019-12-03 14:54:40.89714540.0MbsuccessScanned all imported services. It took 0.4s
2019-12-03 14:54:40.93650640.0MbinfoFound 0 BSP settlement records unmatched. Compare them now
2019-12-03 14:54:42.57446040.0MbsuccessScanned all ImportedOrder services. It took 1.7s
2019-12-03 14:54:42.57460840.0MbinfoAll done
2019-12-03 14:54:42.57470640.0MbinfoInserted 107,944 rows
2019-12-03 14:54:42.57478940.0MbinfoUpdated 107,944 rows
2019-12-03 14:54:42.57487140.0MbinfoRe-checked 23,862 rows
2019-12-03 14:54:42.57495440.0MbinfoDone 29 queue flushes, processed 144.3 rps (estimated)
2019-12-03 14:54:42.57848440.0MbinfoCronjob execution ended.