Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PNlib_PNlib.Examples.ExtTest.DoubleArcwithPTtoTD2.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.002333/0.002333, 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.002486/0.002486, allocations: 219.4 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.534/1.534, allocations: 230.7 MB / 256.9 MB, free: 7.734 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.08168/0.08168, allocations: 16.94 MB / 330.2 MB, free: 2.023 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.ExtTest.DoubleArcwithPTtoTD2,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="P1.t|P2.t|P3.t|T1.active|T1.fire|T2.active|T2.fire|time",fileNamePrefix="PNlib_PNlib.Examples.ExtTest.DoubleArcwithPTtoTD2")
translateModel(PNlib.Examples.ExtTest.DoubleArcwithPTtoTD2,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="P1.t|P2.t|P3.t|T1.active|T1.fire|T2.active|T2.fire|time",fileNamePrefix="PNlib_PNlib.Examples.ExtTest.DoubleArcwithPTtoTD2") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.559e-05/2.559e-05, allocations: 2.281 kB / 461 MB, free: 1.625 MB / 318.1 MB
Notification: Performance of NFInst.instantiate(PNlib.Examples.ExtTest.DoubleArcwithPTtoTD2): time 0.005228/0.005253, allocations: 3.381 MB / 464.4 MB, free: 14.23 MB / 334.1 MB
Notification: Performance of NFInst.instExpressions: time 0.00671/0.01196, allocations: 3.268 MB / 467.7 MB, free: 10.95 MB / 334.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0006825/0.01265, allocations: 31.81 kB / 467.7 MB, free: 10.92 MB / 334.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0007662/0.01341, allocations: 325.2 kB / 468 MB, free: 10.6 MB / 334.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0009233/0.01434, allocations: 421.2 kB / 468.4 MB, free: 10.19 MB / 334.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003472/0.01781, allocations: 1.785 MB / 470.2 MB, free: 8.402 MB / 334.1 MB
Notification: Performance of NFFlatten.flatten: time 0.005823/0.02363, allocations: 4.372 MB / 474.6 MB, free: 4.023 MB / 334.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006646/0.02429, allocations: 304.6 kB / 474.9 MB, free: 3.699 MB / 334.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002662/0.02696, allocations: 1.741 MB / 476.6 MB, free: 1.953 MB / 334.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001763/0.02872, allocations: 1.26 MB / 477.9 MB, free: 0.6875 MB / 334.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005366/0.02926, allocations: 276 kB / 478.1 MB, free: 428 kB / 334.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001977/0.03123, allocations: 1.069 MB / 479.2 MB, free: 15.35 MB / 350.1 MB
Notification: Performance of combineBinaries: time 0.00381/0.03504, allocations: 4.184 MB / 483.4 MB, free: 11.12 MB / 350.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001928/0.03697, allocations: 2.418 MB / 485.8 MB, free: 8.676 MB / 350.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.000589/0.03756, allocations: 232.5 kB / 486 MB, free: 8.449 MB / 350.1 MB
Notification: Performance of FrontEnd: time 0.0004328/0.03799, allocations: 282.1 kB / 486.3 MB, free: 8.172 MB / 350.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 630 (476)
 * Number of variables: 630 (558)
Notification: Performance of [SIM] Bindings: time 0.01234/0.05033, allocations: 10.55 MB / 496.9 MB, free: 13.3 MB / 366.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.001754/0.05209, allocations: 1.241 MB / 498.1 MB, free: 12.04 MB / 366.1 MB
Notification: Performance of [SIM] Early Inline: time 0.01344/0.06553, allocations: 10.82 MB / 0.497 GB, free: 1.121 MB / 366.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.003794/0.06932, allocations: 1.954 MB / 0.4989 GB, free: 15.04 MB / 382.1 MB
Notification: Performance of [SIM] Alias: time 0.01567/0.08499, allocations: 10.33 MB / 0.509 GB, free: 3.473 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.003175/0.08817, allocations: 1.74 MB / 0.5107 GB, free: 1.621 MB / 382.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.002006/0.09018, allocations: 1.385 MB / 0.512 GB, free: 184 kB / 382.1 MB
Notification: Performance of [SIM] Detect States: time 0.2644/0.3546, allocations: 2.696 MB / 0.5147 GB, free: 49.66 MB / 382.1 MB
Notification: Performance of [SIM] Events: time 0.002738/0.3573, allocations: 1.8 MB / 0.5164 GB, free: 49.36 MB / 382.1 MB
Notification: Performance of [SIM] Partitioning: time 0.006121/0.3634, allocations: 4.662 MB / 0.521 GB, free: 48.6 MB / 382.1 MB
Notification: Performance of [SIM] Causalize: time 0.01809/0.3815, allocations: 12.11 MB / 0.5328 GB, free: 47.09 MB / 382.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.01062/0.3921, allocations: 9.206 MB / 0.5418 GB, free: 42.27 MB / 382.1 MB
Notification: Performance of [INI] Simplify: time 0.006119/0.3983, allocations: 3.746 MB / 0.5455 GB, free: 39.83 MB / 382.1 MB
Notification: Performance of [INI] Inline: time 0.01267/0.4109, allocations: 14.46 MB / 0.5596 GB, free: 27.48 MB / 382.1 MB
Notification: Performance of [INI] Partitioning: time 0.0005763/0.4115, allocations: 186.1 kB / 0.5598 GB, free: 27.26 MB / 382.1 MB
Notification: Performance of [INI] Cleanup: time 0.00247/0.414, allocations: 2.173 MB / 0.5619 GB, free: 25.12 MB / 382.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_9 = $PRE.P3.firingSumIn.firingSum > 0 or $PRE.P3.firingSumOut.firingSum > 0 ($RES_EVT_627);
	[SCAL] (1) $SEV_5 = $PRE.P2.firingSumIn.firingSum > 0 or $PRE.P2.firingSumOut.firingSum > 0 ($RES_EVT_623);
Error: Internal error NBResolveSingularities.balanceInitialization failed because following non-fixable variables could not be solved:
	[DISC] (4) Integer[4] $TEV_9
	[DISC] (4) Integer[4] $TEV_11
	[DISC] (1) Boolean $SEV_30
	[DISC] (1) Boolean $SEV_31
	[DISC] (1) Boolean $SEV_32
	[DISC] (1) Boolean $SEV_33
	[DISC] (1) Boolean $SEV_34
	[DISC] (1) Boolean $SEV_70
	[DISC] (1) Boolean[1] T2.outPlaces.fire = {T2.fire for $f1 in 1:1}
	[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.Index
	[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] P2.inTransition.fire = {P2.fireIn[1]}
	[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 Boolean P1.enableIn.endWhile
	[DISC] (1) protected discrete Real P1.enableIn.sumEnablingProbTAin
	[DISC] (1) protected Integer P1.enableIn.posTE
	[DISC] (1) protected Integer P1.enableIn.k
	[DISC] (1) protected Integer P1.enableIn.nTAin
	[DISC] (1) protected Integer P1.enableIn.nremTAin
	[DISC] (1) protected discrete Real P1.enableOut.benefitLimit
	[DISC] (1) protected Boolean P1.enableOut.valid
	[DISC] (1) protected discrete Real P1.enableOut.benefitMax
	[DISC] (1) protected Integer P1.enableOut.Index
	[DISC] (1) protected Integer P1.enableOut.k

Following equations were created by fixing variables:
	[SCAL] (1) $PRE.T1.fireTime = $START.T1.fireTime ($RES_SRT_780)
	[SCAL] (1) $PRE.T2.firingTime = $START.T2.firingTime ($RES_SRT_779)
	[SCAL] (1) $PRE.T2.ani = $START.T2.ani ($RES_SRT_778)
	[SCAL] (1) $PRE.P1.firingSumIn.firingSum = $START.P1.firingSumIn.firingSum ($RES_SRT_777)
	[SCAL] (1) $PRE.P1.firingSumOut.firingSum = $START.P1.firingSumOut.firingSum ($RES_SRT_776)
	[SCAL] (1) $PRE.P2.firingSumIn.firingSum = $START.P2.firingSumIn.firingSum ($RES_SRT_775)
	[SCAL] (1) $PRE.P2.firingSumOut.firingSum = $START.P2.firingSumOut.firingSum ($RES_SRT_774)
	[SCAL] (1) $PRE.P3.firingSumIn.firingSum = $START.P3.firingSumIn.firingSum ($RES_SRT_773)
	[SCAL] (1) $PRE.P3.firingSumOut.firingSum = $START.P3.firingSumOut.firingSum ($RES_SRT_772)
	[ARRY] (4) $PRE.P1.enableOut.state128 = $START.P1.enableOut.state128 ($RES_SRT_771)
	[ARRY] (4) $PRE.P1.enableIn.state128 = $START.P1.enableIn.state128 ($RES_SRT_770)
	[ARRY] (4) $PRE.P2.enableOut.state128 = $START.P2.enableOut.state128 ($RES_SRT_769)
	[ARRY] (4) $PRE.P2.enableIn.state128 = $START.P2.enableIn.state128 ($RES_SRT_768)
	[ARRY] (4) $PRE.P3.enableOut.state128 = $START.P3.enableOut.state128 ($RES_SRT_767)
	[ARRY] (4) $PRE.P3.enableIn.state128 = $START.P3.enableIn.state128 ($RES_SRT_766)
	[SCAL] (1) $PRE.T2.fireTime = $START.T2.fireTime ($RES_SRT_765)
	[SCAL] (1) $PRE.T1.ani = $START.T1.ani ($RES_SRT_764)
	[SCAL] (1) $PRE.T1.firingTime = $START.T1.firingTime ($RES_SRT_763)

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: 3.1969648340018466]
Failed to read output from testmodel.py, exit status != 0:
0.43220194504829124 0.44063419499999995 0.400323693
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f9bf33b6fb0>