Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IndustrialControlSystems_IndustrialControlSystems.Applications.ControlProblems.ATProcessControl.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.001505/0.001505, allocations: 104.3 kB / 19.77 MB, free: 2.051 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.001525/0.001525, allocations: 217.9 kB / 23.07 MB, free: 4.844 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.504/1.504, allocations: 230.6 MB / 256.9 MB, free: 7.781 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IndustrialControlSystems 2.0.0/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IndustrialControlSystems 2.0.0/package.mo): time 0.06182/0.06182, allocations: 11.35 MB / 324.6 MB, free: 8.102 MB / 254.1 MB " [Timeout remaining time 180] Using package IndustrialControlSystems with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IndustrialControlSystems 2.0.0/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(IndustrialControlSystems.Applications.ControlProblems.ATProcessControl,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IndustrialControlSystems_IndustrialControlSystems.Applications.ControlProblems.ATProcessControl") translateModel(IndustrialControlSystems.Applications.ControlProblems.ATProcessControl,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IndustrialControlSystems_IndustrialControlSystems.Applications.ControlProblems.ATProcessControl") [Timeout 660] "Notification: Performance of FrontEnd - Absyn->SCode: time 2.245e-05/2.245e-05, allocations: 2.281 kB / 452.3 MB, free: 9.863 MB / 318.1 MB Notification: Performance of NFInst.instantiate(IndustrialControlSystems.Applications.ControlProblems.ATProcessControl): time 0.007208/0.00723, allocations: 4.915 MB / 457.2 MB, free: 4.918 MB / 318.1 MB Notification: Performance of NFInst.instExpressions: time 0.005105/0.01234, allocations: 2.511 MB / 459.7 MB, free: 2.398 MB / 318.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.001215/0.01355, allocations: 67.25 kB / 459.8 MB, free: 2.332 MB / 318.1 MB Notification: Performance of NFTyping.typeComponents: time 0.001129/0.01468, allocations: 380.8 kB / 460.1 MB, free: 1.957 MB / 318.1 MB Notification: Performance of NFTyping.typeBindings: time 0.001166/0.01585, allocations: 357.4 kB / 460.5 MB, free: 1.605 MB / 318.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.002631/0.01848, allocations: 1.126 MB / 461.6 MB, free: 492 kB / 318.1 MB Notification: Performance of NFFlatten.flatten: time 0.003948/0.02242, allocations: 2.971 MB / 464.6 MB, free: 13.5 MB / 334.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.0008203/0.02325, allocations: 351.9 kB / 464.9 MB, free: 13.13 MB / 334.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.001924/0.02517, allocations: 1.164 MB / 466.1 MB, free: 11.96 MB / 334.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.001638/0.02681, allocations: 0.882 MB / 467 MB, free: 11.08 MB / 334.1 MB Notification: Performance of NFPackage.collectConstants: time 0.000417/0.02723, allocations: 240 kB / 467.2 MB, free: 10.84 MB / 334.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0007171/0.02794, allocations: 355.9 kB / 467.6 MB, free: 10.5 MB / 334.1 MB Notification: Performance of combineBinaries: time 0.003743/0.03169, allocations: 4.092 MB / 471.7 MB, free: 6.363 MB / 334.1 MB Notification: Performance of replaceArrayConstructors: time 0.001999/0.03368, allocations: 2.397 MB / 474.1 MB, free: 3.934 MB / 334.1 MB Notification: Performance of NFVerifyModel.verify: time 0.0007865/0.03447, allocations: 241.8 kB / 474.3 MB, free: 3.699 MB / 334.1 MB Notification: Performance of FrontEnd: time 0.0004485/0.03492, allocations: 115.3 kB / 474.4 MB, free: 3.586 MB / 334.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 386 (270) * Number of variables: 386 (386) Notification: Performance of [SIM] Bindings: time 0.0105/0.04542, allocations: 9.076 MB / 483.5 MB, free: 10.21 MB / 350.1 MB Notification: Performance of [SIM] FunctionAlias: time 0.001699/0.04712, allocations: 0.9977 MB / 484.5 MB, free: 9.18 MB / 350.1 MB Notification: Performance of [SIM] Early Inline: time 0.007762/0.05488, allocations: 6.172 MB / 490.6 MB, free: 2.953 MB / 350.1 MB Notification: Performance of [SIM] Simplify 1: time 0.003462/0.05834, allocations: 1.708 MB / 492.4 MB, free: 1.137 MB / 350.1 MB Notification: Performance of [SIM] Alias: time 0.01303/0.07137, allocations: 8.219 MB / 0.4888 GB, free: 8.152 MB / 366.1 MB Notification: Performance of [SIM] Simplify 2: time 0.002414/0.07378, allocations: 1.468 MB / 0.4903 GB, free: 6.609 MB / 366.1 MB Notification: Performance of [SIM] Remove Stream: time 0.001728/0.07551, allocations: 1.136 MB / 0.4914 GB, free: 5.445 MB / 366.1 MB Notification: Performance of [SIM] Detect States: time 0.002782/0.07829, allocations: 2.04 MB / 0.4934 GB, free: 3.324 MB / 366.1 MB Notification: Performance of [SIM] Events: time 0.002791/0.08109, allocations: 1.507 MB / 0.4948 GB, free: 1.789 MB / 366.1 MB Notification: Performance of [SIM] Partitioning: time 0.005498/0.08658, allocations: 3.428 MB / 0.4982 GB, free: 14.24 MB / 382.1 MB Notification: Performance of [SIM] Causalize: time 0.00963/0.09621, allocations: 7.123 MB / 0.5052 GB, free: 6.98 MB / 382.1 MB Notification: Performance of [SIM] After Index Reduction Inline: time 0.009576/0.1058, allocations: 6.132 MB / 0.5111 GB, free: 0.8438 MB / 382.1 MB Notification: Performance of [INI] Simplify: time 0.2546/0.3604, allocations: 2.997 MB / 0.5141 GB, free: 58.74 MB / 382.1 MB Notification: Performance of [INI] Inline: time 0.00933/0.3697, allocations: 7.909 MB / 0.5218 GB, free: 58.7 MB / 382.1 MB Notification: Performance of [INI] Partitioning: time 0.0003923/0.3701, allocations: 106.2 kB / 0.5219 GB, free: 58.65 MB / 382.1 MB Notification: Performance of [INI] Cleanup: time 0.004664/0.3747, allocations: 2.983 MB / 0.5248 GB, free: 58.55 MB / 382.1 MB Error: Internal error NBResolveSingularities.balanceInitialization failed because following non-fixable variables could not be solved: [DISC] (1) Real $TEV_1 [DISC] (1) Real $TEV_2 [DISC] (1) Real $TEV_3 [DISC] (1) Boolean $TEV_4 [DISC] (1) Boolean $TEV_6 [DISC] (1) Real $TEV_7 [DISC] (1) Real $TEV_8 [DISC] (1) Boolean $TEV_12 [DISC] (1) Real $TEV_13 [DISC] (1) Real $TEV_20 [DISC] (1) Real $TEV_21 [DISC] (1) Boolean $TEV_22 [DISC] (1) Boolean $TEV_23 [DISC] (1) Boolean $TEV_24 [DISC] (1) Real $TEV_25 [DISC] (1) Real $TEV_26 [DISC] (1) Real $TEV_27 [DISC] (1) Boolean $TEV_28 [DISC] (1) Boolean $TEV_30 [DISC] (1) Real $TEV_31 [DISC] (1) Real $TEV_32 [DISC] (1) Real $TEV_33 [DISC] (1) Boolean $TEV_34 [DISC] (1) Boolean $TEV_35 [DISC] (1) Boolean $TEV_36 [DISC] (1) Boolean $TEV_41 [DISC] (1) Boolean $TEV_42 [DISC] (1) Real $TEV_43 [DISC] (1) Real $TEV_44 [DISC] (1) Real $TEV_45 [DISC] (1) Boolean $TEV_46 [DISC] (1) Boolean $SEV_12 [DISC] (1) Boolean $SEV_13 [DISC] (1) Boolean $SEV_14 [DISC] (1) Boolean $SEV_25 [DISC] (1) Boolean $SEV_26 [DISC] (1) Boolean $SEV_27 [DISC] (1) Boolean $SEV_28 [DISC] (1) Boolean $SEV_29 [DISC] (1) Boolean $SEV_39 [DISC] (1) Boolean $SEV_80 [DISC] (1) Boolean $SEV_90 [DISC] (1) Boolean $SEV_91 [DER-] (1) Real $DER.R_US.PV [DER-] (1) Real $DER.Controlled.leadLag.dy [DER-] (1) Real $DER.R_i.PV [DISC] (1) protected Integer R_FS.nOx [DISC] (1) protected discrete Real R_FS.Pox [DISC] (1) protected discrete Real R_FS.wox [DISC] (1) protected discrete Real R_FS.lastToggleUp [ALGB] (1) protected Real R_FS.CSpi [DISC] (1) protected discrete Real R_Osc.Pox [DISC] (1) protected discrete Real R_Osc.wox [DISC] (1) protected discrete Real R_Osc.lastToggleUp [DISC] (1) protected discrete Real R_Osc.rCSmax [DISC] (1) protected discrete Real R_Osc.rPVmax [DISC] (1) protected Boolean R_Osc.UP [DER-] (1) Real $DER.R_Osc.linFBout [DISC] (1) Integer R_Osc.iMode [ALGB] (1) Real R_Osc.CS [DISC] (1) protected discrete Real R_US.Pox [DISC] (1) protected discrete Real R_US.wox [DISC] (1) protected discrete Real R_US.lastToggleUp [DISC] (1) protected discrete Real R_US.rCSmin [DISC] (1) Integer R_US.iMode [DISC] (1) protected Integer R_OS.nOx [DISC] (1) protected discrete Real R_OS.Pox [DISC] (1) protected discrete Real R_OS.wox [DISC] (1) protected discrete Real R_OS.lastToggleUp [DISC] (1) protected discrete Real R_OS.rPVmax [DISC] (1) protected Boolean R_OS.UP [DISC] (1) protected discrete Real R_iD.Pox [DISC] (1) protected discrete Real R_iD.wox [DISC] (1) protected discrete Real R_iD.lastToggleUp [DISC] (1) protected discrete Real R_iD.rCSmax [DISC] (1) protected discrete Real R_iD.rPVmin [DISC] (1) protected Boolean R_iD.UP [DISC] (1) protected Boolean R_iD.AT [DER-] (1) Real $DER.R_iD.linFBout [DISC] (1) protected discrete Real R_i.Pox [DISC] (1) protected discrete Real R_i.wox [DISC] (1) protected discrete Real R_i.period [DISC] (1) protected discrete Real R_i.lastToggleUp [DISC] (1) protected discrete Real R_i.rCSmax [DISC] (1) protected discrete Real R_i.rPVmin [DISC] (1) Real R_i.TI [DISC] (1) Integer R_i.iMode [DISC] (1) protected discrete Real R_FOD.Pox [DISC] (1) protected discrete Real R_FOD.wox [DISC] (1) protected discrete Real R_FOD.lastToggleUp [ALGB] (1) Real R_FOD.CS [DISC] (1) protected Integer R_FO.nOx [DISC] (1) protected discrete Real R_FO.Pox [DISC] (1) protected discrete Real R_FO.wox [DISC] (1) protected discrete Real R_FO.lastToggleUp [DISC] (1) protected discrete Real R_FO.rPVmax Following equations were created by fixing variables: [SCAL] (1) $PRE.ATpulse.combiTimeTable.nextTimeEventScaled = $START.ATpulse.combiTimeTable.nextTimeEventScaled ($RES_SRT_472) [SCAL] (1) $PRE.ATpulse.combiTimeTable.nextTimeEvent = $START.ATpulse.combiTimeTable.nextTimeEvent ($RES_SRT_471) [SCAL] (1) $PRE.R_FO.CSpi = $START.R_FO.CSpi ($RES_SRT_470) [SCAL] (1) $PRE.R_FO.PV = $START.R_FO.PV ($RES_SRT_469) [SCAL] (1) $PRE.R_FO.UP = $START.R_FO.UP ($RES_SRT_468) [SCAL] (1) $PRE.R_FO.period = $START.R_FO.period ($RES_SRT_467) [SCAL] (1) $PRE.R_FOD.CSpi = $START.R_FOD.CSpi ($RES_SRT_466) [SCAL] (1) $PRE.R_FOD.PV = $START.R_FOD.PV ($RES_SRT_465) [SCAL] (1) $PRE.R_FOD.UP = $START.R_FOD.UP ($RES_SRT_464) [SCAL] (1) $PRE.R_FOD.period = $START.R_FOD.period ($RES_SRT_463) [SCAL] (1) $PRE.R_i.CSpi = $START.R_i.CSpi ($RES_SRT_462) [SCAL] (1) $PRE.R_i.PV = $START.R_i.PV ($RES_SRT_461) [SCAL] (1) $PRE.R_i.UP = $START.R_i.UP ($RES_SRT_460) [SCAL] (1) $PRE.R_i.period = $START.R_i.period ($RES_SRT_459) [SCAL] (1) $PRE.R_iD.CSpi = $START.R_iD.CSpi ($RES_SRT_458) [SCAL] (1) $PRE.R_iD.PV = $START.R_iD.PV ($RES_SRT_457) [SCAL] (1) $PRE.R_iD.UP = $START.R_iD.UP ($RES_SRT_456) [SCAL] (1) $PRE.R_iD.period = $START.R_iD.period ($RES_SRT_455) [SCAL] (1) $PRE.R_OS.CSpi = $START.R_OS.CSpi ($RES_SRT_454) [SCAL] (1) $PRE.R_OS.PV = $START.R_OS.PV ($RES_SRT_453) [SCAL] (1) $PRE.R_OS.UP = $START.R_OS.UP ($RES_SRT_452) [SCAL] (1) $PRE.R_OS.period = $START.R_OS.period ($RES_SRT_451) [SCAL] (1) $PRE.R_US.CSpi = $START.R_US.CSpi ($RES_SRT_450) [SCAL] (1) $PRE.R_US.PV = $START.R_US.PV ($RES_SRT_449) [SCAL] (1) $PRE.R_US.UP = $START.R_US.UP ($RES_SRT_448) [SCAL] (1) $PRE.R_US.period = $START.R_US.period ($RES_SRT_447) [SCAL] (1) $PRE.R_Osc.CSpi = $START.R_Osc.CSpi ($RES_SRT_446) [SCAL] (1) $PRE.R_Osc.PV = $START.R_Osc.PV ($RES_SRT_445) [SCAL] (1) $PRE.R_Osc.UP = $START.R_Osc.UP ($RES_SRT_444) [SCAL] (1) $PRE.R_Osc.period = $START.R_Osc.period ($RES_SRT_443) [SCAL] (1) $PRE.R_FS.CSpi = $START.R_FS.CSpi ($RES_SRT_442) [SCAL] (1) $PRE.R_FS.PV = $START.R_FS.PV ($RES_SRT_441) [SCAL] (1) $PRE.R_FS.UP = $START.R_FS.UP ($RES_SRT_440) [SCAL] (1) $PRE.R_FS.period = $START.R_FS.period ($RES_SRT_439) [SCAL] (1) R_FS.linFBout = $START.R_FS.linFBout ($RES_SRT_438) [SCAL] (1) R_Osc.linFBout = $START.R_Osc.linFBout ($RES_SRT_437) [SCAL] (1) R_US.linFBout = $START.R_US.linFBout ($RES_SRT_436) [SCAL] (1) R_OS.linFBout = $START.R_OS.linFBout ($RES_SRT_435) [SCAL] (1) R_iD.linFBout = $START.R_iD.linFBout ($RES_SRT_434) [SCAL] (1) R_i.linFBout = $START.R_i.linFBout ($RES_SRT_433) [SCAL] (1) R_FOD.linFBout = $START.R_FOD.linFBout ($RES_SRT_432) [SCAL] (1) R_FO.linFBout = $START.R_FO.linFBout ($RES_SRT_431) Use -d=bltdump for more verbose debug output. Error: Internal error NBInitialization.main failed to apply modules! " [Timeout remaining time 660] [Calling sys.exit(0), Time elapsed: 2.880332286003977] Failed to read output from testmodel.py, exit status != 0: 0.3921129209920764 0.401950152 0.364870199 Calling exit ...