Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr SystemDynamics_SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001524/0.001524, allocations: 104.3 kB / 19.76 MB, free: 1.898 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001732/0.001732, allocations: 217.5 kB / 23.07 MB, free: 4.992 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.596/1.596, allocations: 230.7 MB / 256.9 MB, free: 7.773 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/SystemDynamics master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/SystemDynamics master/package.mo): time 0.2314/0.2314, allocations: 29.23 MB / 342.5 MB, free: 5.656 MB / 270.1 MB
"
[Timeout remaining time 179]
Using package SystemDynamics with version 2.1.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SystemDynamics master/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="SystemDynamics_SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep")
translateModel(SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="SystemDynamics_SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep") [Timeout 660]
"Warning: Ignoring unknown experiment annotation option: Algorithm = \"dassl\"
Notification: Performance of FrontEnd - Absyn->SCode: time 3.181e-05/3.181e-05, allocations: 5.094 kB / 479.6 MB, free: 15.95 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep): time 0.01363/0.01367, allocations: 9.601 MB / 489.2 MB, free: 6.289 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008744/0.02241, allocations: 3.226 MB / 492.4 MB, free: 3.055 MB / 350.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004174/0.02658, allocations: 79.38 kB / 492.5 MB, free: 2.977 MB / 350.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002783/0.02937, allocations: 0.5217 MB / 493 MB, free: 2.449 MB / 350.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003871/0.03324, allocations: 368 kB / 493.4 MB, free: 2.086 MB / 350.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004789/0.03803, allocations: 1.721 MB / 495.1 MB, free: 372 kB / 350.1 MB
Notification: Performance of NFFlatten.flatten: time 0.009422/0.04745, allocations: 5.106 MB / 0.4885 GB, free: 11.25 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.004975/0.05242, allocations: 1.858 MB / 0.4903 GB, free: 9.309 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002684/0.05511, allocations: 1.553 MB / 0.4918 GB, free: 7.75 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002441/0.05755, allocations: 1.347 MB / 0.4931 GB, free: 6.395 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0018/0.05935, allocations: 0.5391 MB / 0.4937 GB, free: 5.855 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001148/0.0605, allocations: 0.5391 MB / 0.4942 GB, free: 5.316 MB / 366.1 MB
Notification: Performance of combineBinaries: time 0.007704/0.0682, allocations: 7.364 MB / 0.5014 GB, free: 13.87 MB / 382.1 MB
Notification: Performance of replaceArrayConstructors: time 0.004906/0.07311, allocations: 4.776 MB / 0.5061 GB, free: 9.031 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001312/0.07442, allocations: 216 kB / 0.5063 GB, free: 8.82 MB / 382.1 MB
Notification: Performance of FrontEnd: time 0.001527/0.07594, allocations: 376.6 kB / 0.5066 GB, free: 8.449 MB / 382.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 946 (946)
 * Number of variables: 946 (946)
Notification: Performance of [SIM] Bindings: time 0.3308/0.4068, allocations: 16.97 MB / 0.5232 GB, free: 37.12 MB / 382.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.004144/0.4109, allocations: 1.906 MB / 0.5251 GB, free: 35.82 MB / 382.1 MB
Notification: Performance of [SIM] Early Inline: time 0.01843/0.4294, allocations: 9.278 MB / 0.5341 GB, free: 29.5 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.005491/0.4349, allocations: 1.218 MB / 0.5353 GB, free: 28.95 MB / 382.1 MB
Notification: Performance of [SIM] Alias: time 0.03395/0.4688, allocations: 22.82 MB / 0.5576 GB, free: 12.77 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0008493/0.4696, allocations: 424.1 kB / 0.558 GB, free: 12.28 MB / 382.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0002734/0.4699, allocations: 343.8 kB / 0.5583 GB, free: 11.91 MB / 382.1 MB
Notification: Performance of [SIM] Detect States: time 0.0008877/0.4708, allocations: 0.9741 MB / 0.5593 GB, free: 10.91 MB / 382.1 MB
Notification: Performance of [SIM] Events: time 0.0002956/0.4711, allocations: 107.8 kB / 0.5594 GB, free: 10.8 MB / 382.1 MB
Notification: Performance of [SIM] Partitioning: time 0.001063/0.4722, allocations: 1.203 MB / 0.5605 GB, free: 9.578 MB / 382.1 MB
Notification: Performance of [SIM] Causalize: time 0.002303/0.4745, allocations: 2.021 MB / 0.5625 GB, free: 7.535 MB / 382.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.002106/0.4766, allocations: 2.46 MB / 0.5649 GB, free: 5.074 MB / 382.1 MB
Notification: Performance of [INI] Simplify: time 0.004447/0.481, allocations: 2.587 MB / 0.5674 GB, free: 2.379 MB / 382.1 MB
Notification: Performance of [INI] Inline: time 0.004857/0.4859, allocations: 4.281 MB / 0.5716 GB, free: 14.09 MB / 398.1 MB
Notification: Performance of [INI] Partitioning: time 0.0005748/0.4865, allocations: 106.3 kB / 0.5717 GB, free: 13.95 MB / 398.1 MB
Notification: Performance of [INI] Cleanup: time 0.001529/0.488, allocations: 0.7689 MB / 0.5725 GB, free: 13.18 MB / 398.1 MB
Notification: Performance of [INI] Causalize: time 0.01135/0.4993, allocations: 5.46 MB / 0.5778 GB, free: 7.625 MB / 398.1 MB
Notification: Performance of [INI] Tearing: time 0.0001429/0.4995, allocations: 160 kB / 0.578 GB, free: 7.469 MB / 398.1 MB
Notification: Performance of [SIM] Initialization: time 5.581e-06/0.4995, allocations: 4 kB / 0.578 GB, free: 7.465 MB / 398.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.001558/0.501, allocations: 0.8475 MB / 0.5788 GB, free: 6.625 MB / 398.1 MB
Notification: Performance of [SIM] Remove Dummies: time 1.557e-05/0.5011, allocations: 1.312 kB / 0.5788 GB, free: 6.625 MB / 398.1 MB
Notification: Performance of [SIM] Tearing: time 0.0001107/0.5012, allocations: 136 kB / 0.5789 GB, free: 6.492 MB / 398.1 MB
Notification: Performance of [SIM] Categorize: time 2.051e-05/0.5012, allocations: 4 kB / 0.5789 GB, free: 6.488 MB / 398.1 MB
Notification: Performance of [SIM] Solve: time 0.007563/0.5088, allocations: 4.154 MB / 0.583 GB, free: 2.336 MB / 398.1 MB
Notification: Performance of [SIM] Jacobian: time 0.003638/0.5124, allocations: 1.872 MB / 0.5848 GB, free: 408 kB / 398.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 0
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 0
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 33 (33) {factoryStock, Factory1.uof, Factory1.SRF_delayed.state, Factory1.Order_Delay.Level1.level, Factory1.Order_Delay.Level2.level, Factory1.Order_Delay.Level3.level, Factory1.Production_Delay.Level1.level, Factory1.Production_Delay.Level2.level, Factory1.Production_Delay.Level3.level, distributionStock, Distribution1.uod, Distribution1.RSD_delayed.state, Distribution1.Transporation_Delay.Level1.level, Distribution1.Transporation_Delay.Level2.level, Distribution1.Transporation_Delay.Level3.level, Distribution1.Order_Delay.Level1.level, Distribution1.Order_Delay.Level2.level, Distribution1.Order_Delay.Level3.level, Distribution1.Mail_Delay.Level1.level, Distribution1.Mail_Delay.Level2.level, Distribution1.Mail_Delay.Level3.level, retailStock, Retail1.PDR1.UOD, Retail1.RSR_delayed.state, Retail1.Transporation_Delay.Level1.level, Retail1.Transporation_Delay.Level2.level, Retail1.Transporation_Delay.Level3.level, Retail1.Order_Delay.Level1.level, Retail1.Order_Delay.Level2.level, Retail1.Order_Delay.Level3.level, Retail1.Mail_Delay.Level1.level, Retail1.Mail_Delay.Level2.level, Retail1.Mail_Delay.Level3.level}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 5 (5) {$SEV_3, $SEV_2, $SEV_1, $SEV_0, $TEV_0}
 * Number of clocks: ............................. 0 (0) {}
 * Number of top-level inputs: ................... 0 (0) {}
Notification: [Simulation] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 135 (scalar:135, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 322 (scalar:322, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 0.0001872/0.5126, allocations: 130.2 kB / 0.5849 GB, free: 284 kB / 398.1 MB
Notification: Performance of SimCode: time 0.02038/0.533, allocations: 11.04 MB / 0.5957 GB, free: 5.082 MB / 414.1 MB
Notification: Performance of Templates: time 0.03753/0.5705, allocations: 15.92 MB / 0.6113 GB, free: 5.285 MB / 430.1 MB
"
[Timeout remaining time 659]
make -j1 -f SystemDynamics_SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep.makefile [Timeout 660]
(rm -f SystemDynamics_SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep.pipe ; mkfifo SystemDynamics_SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep.pipe ; head -c 1048576 < SystemDynamics_SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep.pipe >> ../files/SystemDynamics_SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep.sim & ./SystemDynamics_SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep  -abortSlowSimulation -alarm=480 -lv LOG_STATS > SystemDynamics_SystemDynamics.IndustrialDynamics.Inventory.InventoryForresterStep.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 6.234216956014279]
Failed to read output from testmodel.py, exit status != 0:
0.5819502169615589 0.600858353 0.5206863810000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f73a9d0afb0>