Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PNlib_PNlib.Examples.DisTest.PrioTest.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.001868/0.001868, allocations: 104.3 kB / 19.76 MB, free: 1.906 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.001845/0.001845, allocations: 211.9 kB / 23.06 MB, free: 4.988 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.531/1.531, allocations: 230.7 MB / 256.9 MB, free: 8.004 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PNlib master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PNlib master/package.mo): time 0.08127/0.08127, allocations: 16.94 MB / 330.2 MB, free: 2.109 MB / 254.1 MB " [Timeout remaining time 180] Using package PNlib with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PNlib 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(PNlib.Examples.DisTest.PrioTest,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="P1.t|P2.t|P3.t|P4.t|P5.t|T1.active|T1.fire|T2.active|T2.fire|T3.active|T3.fire|T4.active|T4.fire|T5.active|T5.fire|time",fileNamePrefix="PNlib_PNlib.Examples.DisTest.PrioTest") translateModel(PNlib.Examples.DisTest.PrioTest,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="P1.t|P2.t|P3.t|P4.t|P5.t|T1.active|T1.fire|T2.active|T2.fire|T3.active|T3.fire|T4.active|T4.fire|T5.active|T5.fire|time",fileNamePrefix="PNlib_PNlib.Examples.DisTest.PrioTest") [Timeout 660] "Notification: Performance of FrontEnd - Absyn->SCode: time 2.995e-05/2.995e-05, allocations: 3.688 kB / 461 MB, free: 1.676 MB / 318.1 MB Notification: Performance of NFInst.instantiate(PNlib.Examples.DisTest.PrioTest): time 0.00708/0.00711, allocations: 5.042 MB / 466.1 MB, free: 12.62 MB / 334.1 MB Notification: Performance of NFInst.instExpressions: time 0.01017/0.01728, allocations: 4.859 MB / 470.9 MB, free: 7.75 MB / 334.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.002533/0.01982, allocations: 43.69 kB / 471 MB, free: 7.707 MB / 334.1 MB Notification: Performance of NFTyping.typeComponents: time 0.001527/0.02134, allocations: 0.5502 MB / 471.5 MB, free: 7.152 MB / 334.1 MB Notification: Performance of NFTyping.typeBindings: time 0.002349/0.02369, allocations: 0.679 MB / 472.2 MB, free: 6.469 MB / 334.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.007164/0.03086, allocations: 2.715 MB / 474.9 MB, free: 3.75 MB / 334.1 MB Notification: Performance of NFFlatten.flatten: time 0.01203/0.04289, allocations: 7.601 MB / 482.5 MB, free: 12.14 MB / 350.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.00128/0.04417, allocations: 489.5 kB / 483 MB, free: 11.59 MB / 350.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.005509/0.04968, allocations: 3.035 MB / 486 MB, free: 8.543 MB / 350.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.004355/0.05403, allocations: 2.311 MB / 488.3 MB, free: 6.223 MB / 350.1 MB Notification: Performance of NFPackage.collectConstants: time 0.001527/0.05556, allocations: 488 kB / 488.8 MB, free: 5.746 MB / 350.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.00242/0.05798, allocations: 1.276 MB / 490.1 MB, free: 4.469 MB / 350.1 MB Notification: Performance of combineBinaries: time 0.007394/0.06537, allocations: 7.148 MB / 497.2 MB, free: 13.25 MB / 366.1 MB Notification: Performance of replaceArrayConstructors: time 0.003906/0.06928, allocations: 3.992 MB / 0.4895 GB, free: 9.211 MB / 366.1 MB Notification: Performance of NFVerifyModel.verify: time 0.001908/0.07119, allocations: 424.5 kB / 0.4899 GB, free: 8.797 MB / 366.1 MB Notification: Performance of FrontEnd: time 0.0008549/0.07204, allocations: 397.2 kB / 0.4903 GB, free: 8.406 MB / 366.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 1090 (790) * Number of variables: 1090 (920) Notification: Performance of [SIM] Bindings: time 0.02515/0.0972, allocations: 18.21 MB / 0.5081 GB, free: 5.789 MB / 382.1 MB Notification: Performance of [SIM] FunctionAlias: time 0.005583/0.1028, allocations: 2.206 MB / 0.5102 GB, free: 3.559 MB / 382.1 MB Notification: Performance of [SIM] Early Inline: time 0.3135/0.4163, allocations: 19.2 MB / 0.529 GB, free: 34.91 MB / 382.1 MB Notification: Performance of [SIM] Simplify 1: time 0.007873/0.4242, allocations: 3.512 MB / 0.5324 GB, free: 33.69 MB / 382.1 MB Notification: Performance of [SIM] Alias: time 0.02694/0.4511, allocations: 17.36 MB / 0.5493 GB, free: 22.78 MB / 382.1 MB Notification: Performance of [SIM] Simplify 2: time 0.005095/0.4562, allocations: 3.175 MB / 0.5524 GB, free: 21.61 MB / 382.1 MB Notification: Performance of [SIM] Remove Stream: time 0.003366/0.4596, allocations: 2.561 MB / 0.5549 GB, free: 20.17 MB / 382.1 MB Notification: Performance of [SIM] Detect States: time 0.005339/0.4649, allocations: 4.904 MB / 0.5597 GB, free: 16.36 MB / 382.1 MB Notification: Performance of [SIM] Events: time 0.004479/0.4694, allocations: 3.425 MB / 0.5631 GB, free: 14.47 MB / 382.1 MB Notification: Performance of [SIM] Partitioning: time 0.009467/0.4789, allocations: 8.426 MB / 0.5713 GB, free: 8.754 MB / 382.1 MB Notification: Performance of [SIM] Causalize: time 0.03157/0.5104, allocations: 22.75 MB / 0.5935 GB, free: 2.672 MB / 398.1 MB Notification: Performance of [SIM] After Index Reduction Inline: time 0.02541/0.5359, allocations: 16.86 MB / 0.61 GB, free: 1.922 MB / 414.1 MB Notification: Performance of [INI] Simplify: time 0.01414/0.55, allocations: 6.519 MB / 0.6164 GB, free: 11.18 MB / 430.1 MB Notification: Performance of [INI] Inline: time 0.04323/0.5932, allocations: 26.35 MB / 0.6421 GB, free: 0.8398 MB / 446.1 MB Notification: Performance of [INI] Partitioning: time 0.001165/0.5944, allocations: 327.1 kB / 0.6424 GB, free: 480 kB / 446.1 MB Notification: Performance of [INI] Cleanup: time 0.007901/0.6023, allocations: 3.958 MB / 0.6463 GB, free: 12.51 MB / 462.1 MB Warning: NBResolveSingularities.balanceInitialization reports an overdetermined initialization! Checking for consistency is not yet supported, following equations had to be removed: [SCAL] (1) $SEV_31 = $PRE.P5.firingSumIn.firingSum > 0 or $PRE.P5.firingSumOut.firingSum > 0 ($RES_EVT_1066); [SCAL] (1) $SEV_27 = $PRE.P4.firingSumIn.firingSum > 0 or $PRE.P4.firingSumOut.firingSum > 0 ($RES_EVT_1062); [SCAL] (1) $SEV_23 = $PRE.P3.firingSumIn.firingSum > 0 or $PRE.P3.firingSumOut.firingSum > 0 ($RES_EVT_1058); [SCAL] (1) P2.tokeninout = $START.P2.tokeninout ($RES_SRT_1159); Error: Internal error NBResolveSingularities.balanceInitialization failed because following non-fixable variables could not be solved: [DISC] (4) Integer[4] $TEV_11 [DISC] (4) Integer[4] $TEV_19 [DISC] (1) Boolean $SEV_52 [DISC] (1) Boolean $SEV_53 [DISC] (1) Boolean $SEV_54 [DISC] (4) Boolean[4] $SEV_55 [DISC] (1) Boolean $SEV_130 [DISC] (1) Boolean $SEV_131 [DISC] (1) Boolean $SEV_132 [DISC] (1) Boolean $SEV_141 [DISC] (1) Boolean $SEV_142 [DISC] (1) Boolean $SEV_143 [DISC] (1) Boolean $SEV_144 [DISC] (1) Boolean $SEV_155 [DISC] (1) Boolean $SEV_156 [DISC] (1) Boolean $SEV_157 [DISC] (1) Boolean $SEV_158 [DISC] (1) protected discrete Real P5.enableIn.benefitLimit [DISC] (1) protected Boolean P5.enableIn.valid [DISC] (1) protected discrete Real P5.enableIn.benefitMax [DISC] (1) protected Integer P5.enableIn.Index [DISC] (1) protected Integer P5.enableIn.posTE [DISC] (1) protected Integer P5.enableIn.nTAin [DISC] (1) protected Integer P5.enableIn.nremTAin [DISC] (1) protected Integer P5.enableIn.arcWeightSum [DISC] (1) protected discrete Real P5.enableOut.benefitLimit [DISC] (1) protected Boolean P5.enableOut.valid [DISC] (1) protected discrete Real P5.enableOut.benefitMax [DISC] (1) protected Integer P5.enableOut.Index [DISC] (1) protected Boolean P5.enableOut.endWhile [DISC] (1) protected discrete Real P5.enableOut.sumEnablingProbTAout [DISC] (1) protected Integer P5.enableOut.posTE [DISC] (1) protected Integer P5.enableOut.k [DISC] (1) protected Integer P5.enableOut.nTAout [DISC] (1) protected Integer P5.enableOut.nremTAout [DISC] (1) protected discrete Real P4.enableIn.benefitLimit [DISC] (1) protected Boolean P4.enableIn.valid [DISC] (1) protected discrete Real P4.enableIn.benefitMax [DISC] (1) protected Integer P4.enableIn.nTAin [DISC] (1) protected Integer P4.enableIn.arcWeightSum [DISC] (1) protected discrete Real P4.enableOut.benefitLimit [DISC] (1) protected Boolean P4.enableOut.valid [DISC] (1) protected discrete Real P4.enableOut.benefitMax [DISC] (1) protected Integer P4.enableOut.Index [DISC] (1) protected Boolean P4.enableOut.endWhile [DISC] (1) protected discrete Real P4.enableOut.sumEnablingProbTAout [DISC] (1) protected Integer P4.enableOut.posTE [DISC] (1) protected Integer P4.enableOut.k [DISC] (1) protected Integer P4.enableOut.nTAout [DISC] (1) protected Integer P4.enableOut.nremTAout [DISC] (1) protected discrete Real P3.enableIn.benefitLimit [DISC] (1) protected Boolean P3.enableIn.valid [DISC] (1) protected discrete Real P3.enableIn.benefitMax [DISC] (1) protected Integer P3.enableIn.nTAin [DISC] (1) protected Integer P3.enableIn.arcWeightSum [DISC] (1) protected discrete Real P3.enableOut.benefitLimit [DISC] (1) protected Boolean P3.enableOut.valid [DISC] (1) protected discrete Real P3.enableOut.benefitMax [DISC] (1) protected Integer P3.enableOut.Index [DISC] (1) protected Boolean P3.enableOut.endWhile [DISC] (1) protected discrete Real P3.enableOut.sumEnablingProbTAout [DISC] (1) protected Integer P3.enableOut.posTE [DISC] (1) protected Integer P3.enableOut.k [DISC] (1) protected Integer P3.enableOut.nTAout [DISC] (1) protected Integer P3.enableOut.nremTAout [DISC] (1) protected discrete Real P2.enableIn.benefitLimit [DISC] (1) protected Boolean P2.enableIn.valid [DISC] (1) protected discrete Real P2.enableIn.benefitMax [DISC] (1) protected Integer P2.enableIn.Index [DISC] (1) protected Integer P2.enableIn.nTAin [DISC] (1) protected Integer P2.enableIn.arcWeightSum [DISC] (1) protected discrete Real P2.enableOut.benefitLimit [DISC] (1) protected Boolean P2.enableOut.valid [DISC] (1) protected discrete Real P2.enableOut.benefitMax [DISC] (1) protected Integer P2.enableOut.Index [DISC] (1) protected Boolean P2.enableOut.endWhile [DISC] (1) protected discrete Real P2.enableOut.sumEnablingProbTAout [DISC] (1) protected Integer P2.enableOut.posTE [DISC] (1) protected Integer P2.enableOut.k [DISC] (1) protected Integer P2.enableOut.nTAout [DISC] (1) protected Integer P2.enableOut.nremTAout [DISC] (1) Boolean[1] T5.outPlaces.enabledByInPlaces = {T5.enabledByInPlaces for $f1 in 1:1} [DISC] (1) Boolean[1] T5.inPlaces.fire = {T5.fire for $f1 in 1:1} [DISC] (1) Boolean[1] T4.outPlaces.fire = {T4.fire for $f1 in 1:1} [DISC] (1) Boolean[1] T3.outPlaces.enabledByInPlaces = {T3.enabledByInPlaces for $f1 in 1:1} [DISC] (1) Boolean[1] T3.inPlaces.fire = {T3.fire for $f1 in 1:1} [DISC] (1) Boolean[1] T2.outPlaces.fire = {T2.fire for $f1 in 1:1} [DISC] (1) Boolean T1.fire [DISC] (1) protected discrete Real P1.enableIn.benefitLimit [DISC] (1) protected Boolean P1.enableIn.valid [DISC] (1) protected discrete Real P1.enableIn.benefitMax [DISC] (1) protected Integer P1.enableIn.Index [DISC] (1) protected Integer P1.enableIn.k [DISC] (1) protected Integer P1.enableIn.nTAin [DISC] (1) protected Integer P1.enableIn.arcWeightSum [DISC] (1) protected discrete Real P1.enableOut.benefitLimit [DISC] (1) protected Boolean P1.enableOut.valid [DISC] (1) protected discrete Real P1.enableOut.benefitMax Following equations were created by fixing variables: [SCAL] (1) $PRE.T1.fireTime = $START.T1.fireTime ($RES_SRT_1319) [SCAL] (1) $PRE.T2.firingTime = $START.T2.firingTime ($RES_SRT_1318) [SCAL] (1) $PRE.T2.ani = $START.T2.ani ($RES_SRT_1317) [SCAL] (1) $PRE.T3.fireTime = $START.T3.fireTime ($RES_SRT_1316) [SCAL] (1) $PRE.T4.firingTime = $START.T4.firingTime ($RES_SRT_1315) [SCAL] (1) $PRE.T4.ani = $START.T4.ani ($RES_SRT_1314) [SCAL] (1) $PRE.T5.fireTime = $START.T5.fireTime ($RES_SRT_1313) [SCAL] (1) $PRE.P1.firingSumIn.firingSum = $START.P1.firingSumIn.firingSum ($RES_SRT_1312) [SCAL] (1) $PRE.P1.firingSumOut.firingSum = $START.P1.firingSumOut.firingSum ($RES_SRT_1311) [SCAL] (1) $PRE.P2.firingSumIn.firingSum = $START.P2.firingSumIn.firingSum ($RES_SRT_1310) [SCAL] (1) $PRE.P2.firingSumOut.firingSum = $START.P2.firingSumOut.firingSum ($RES_SRT_1309) [SCAL] (1) $PRE.P3.firingSumIn.firingSum = $START.P3.firingSumIn.firingSum ($RES_SRT_1308) [SCAL] (1) $PRE.P3.firingSumOut.firingSum = $START.P3.firingSumOut.firingSum ($RES_SRT_1307) [SCAL] (1) $PRE.P4.firingSumIn.firingSum = $START.P4.firingSumIn.firingSum ($RES_SRT_1306) [SCAL] (1) $PRE.P4.firingSumOut.firingSum = $START.P4.firingSumOut.firingSum ($RES_SRT_1305) [SCAL] (1) $PRE.P5.firingSumIn.firingSum = $START.P5.firingSumIn.firingSum ($RES_SRT_1304) [SCAL] (1) $PRE.P5.firingSumOut.firingSum = $START.P5.firingSumOut.firingSum ($RES_SRT_1303) [ARRY] (4) $PRE.P1.enableOut.state128 = $START.P1.enableOut.state128 ($RES_SRT_1302) [ARRY] (4) $PRE.P1.enableIn.state128 = $START.P1.enableIn.state128 ($RES_SRT_1301) [ARRY] (4) $PRE.P2.enableOut.state128 = $START.P2.enableOut.state128 ($RES_SRT_1300) [ARRY] (4) $PRE.P2.enableIn.state128 = $START.P2.enableIn.state128 ($RES_SRT_1299) [ARRY] (4) $PRE.P3.enableOut.state128 = $START.P3.enableOut.state128 ($RES_SRT_1298) [ARRY] (4) $PRE.P3.enableIn.state128 = $START.P3.enableIn.state128 ($RES_SRT_1297) [ARRY] (4) $PRE.P4.enableOut.state128 = $START.P4.enableOut.state128 ($RES_SRT_1296) [ARRY] (4) $PRE.P4.enableIn.state128 = $START.P4.enableIn.state128 ($RES_SRT_1295) [ARRY] (4) $PRE.P5.enableOut.state128 = $START.P5.enableOut.state128 ($RES_SRT_1294) [ARRY] (4) $PRE.P5.enableIn.state128 = $START.P5.enableIn.state128 ($RES_SRT_1293) [SCAL] (1) $PRE.T5.ani = $START.T5.ani ($RES_SRT_1292) [SCAL] (1) $PRE.T5.firingTime = $START.T5.firingTime ($RES_SRT_1291) [SCAL] (1) $PRE.T4.fireTime = $START.T4.fireTime ($RES_SRT_1290) [SCAL] (1) $PRE.T3.ani = $START.T3.ani ($RES_SRT_1289) [SCAL] (1) $PRE.T3.firingTime = $START.T3.firingTime ($RES_SRT_1288) [SCAL] (1) $PRE.T2.fireTime = $START.T2.fireTime ($RES_SRT_1287) [SCAL] (1) $PRE.T1.ani = $START.T1.ani ($RES_SRT_1286) [SCAL] (1) $PRE.T1.firingTime = $START.T1.firingTime ($RES_SRT_1285) Use -d=bltdump for more verbose debug output. Error: Internal error NBInitialization.main failed to apply modules! " [Timeout remaining time 659] [Calling sys.exit(0), Time elapsed: 3.3726486280211248] Failed to read output from testmodel.py, exit status != 0: 0.6409906269982457 0.655290988 0.5800173310000001 Calling exit ...