Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PNlib_PNlib.Examples.ExtTest.TAwithPDtoTD.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.001792/0.001792, 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.001934/0.001934, allocations: 217.2 kB / 23.07 MB, free: 4.98 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.514/1.514, allocations: 230.7 MB / 256.9 MB, free: 7.758 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.08032/0.08032, allocations: 16.95 MB / 330.2 MB, free: 2.004 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.TAwithPDtoTD,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.TAwithPDtoTD")
translateModel(PNlib.Examples.ExtTest.TAwithPDtoTD,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.TAwithPDtoTD") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.439e-05/2.439e-05, allocations: 2.281 kB / 461 MB, free: 1.605 MB / 318.1 MB
Notification: Performance of NFInst.instantiate(PNlib.Examples.ExtTest.TAwithPDtoTD): time 0.005061/0.005086, allocations: 3.219 MB / 464.2 MB, free: 14.38 MB / 334.1 MB
Notification: Performance of NFInst.instExpressions: time 0.006626/0.01171, allocations: 3.223 MB / 467.4 MB, free: 11.14 MB / 334.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0007829/0.01249, allocations: 27.81 kB / 467.5 MB, free: 11.12 MB / 334.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0007508/0.01324, allocations: 301.4 kB / 467.8 MB, free: 10.82 MB / 334.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0009088/0.01415, allocations: 397.4 kB / 468.2 MB, free: 10.43 MB / 334.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004006/0.01816, allocations: 1.774 MB / 469.9 MB, free: 8.652 MB / 334.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006108/0.02427, allocations: 4.279 MB / 474.2 MB, free: 4.367 MB / 334.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006656/0.02493, allocations: 266.1 kB / 474.5 MB, free: 4.078 MB / 334.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002857/0.02779, allocations: 1.695 MB / 476.2 MB, free: 2.379 MB / 334.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001864/0.02965, allocations: 1.206 MB / 477.4 MB, free: 1.168 MB / 334.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004162/0.03007, allocations: 264 kB / 477.6 MB, free: 0.9102 MB / 334.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001935/0.03201, allocations: 1.053 MB / 478.7 MB, free: 15.86 MB / 350.1 MB
Notification: Performance of combineBinaries: time 0.003523/0.03553, allocations: 3.921 MB / 482.6 MB, free: 11.9 MB / 350.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001814/0.03734, allocations: 2.241 MB / 484.8 MB, free: 9.629 MB / 350.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0006799/0.03802, allocations: 228.5 kB / 485.1 MB, free: 9.406 MB / 350.1 MB
Notification: Performance of FrontEnd: time 0.0004209/0.03844, allocations: 230.4 kB / 485.3 MB, free: 9.18 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: 581 (429)
 * Number of variables: 581 (511)
Notification: Performance of [SIM] Bindings: time 0.0126/0.05105, allocations: 9.907 MB / 495.2 MB, free: 14.98 MB / 366.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.002078/0.05312, allocations: 1.204 MB / 496.4 MB, free: 13.74 MB / 366.1 MB
Notification: Performance of [SIM] Early Inline: time 0.01497/0.06809, allocations: 10.21 MB / 0.4947 GB, free: 3.465 MB / 366.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.004095/0.07218, allocations: 1.884 MB / 0.4966 GB, free: 1.445 MB / 366.1 MB
Notification: Performance of [SIM] Alias: time 0.01574/0.08792, allocations: 9.531 MB / 0.5059 GB, free: 6.824 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.003026/0.09095, allocations: 1.699 MB / 0.5075 GB, free: 5.027 MB / 382.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.002154/0.0931, allocations: 1.361 MB / 0.5089 GB, free: 3.609 MB / 382.1 MB
Notification: Performance of [SIM] Detect States: time 0.003839/0.09694, allocations: 2.633 MB / 0.5114 GB, free: 0.8086 MB / 382.1 MB
Notification: Performance of [SIM] Events: time 0.2668/0.3637, allocations: 1.753 MB / 0.5132 GB, free: 49.96 MB / 382.1 MB
Notification: Performance of [SIM] Partitioning: time 0.007498/0.3712, allocations: 4.452 MB / 0.5175 GB, free: 49.33 MB / 382.1 MB
Notification: Performance of [SIM] Causalize: time 0.02058/0.3918, allocations: 11.62 MB / 0.5288 GB, free: 47.97 MB / 382.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0106/0.4024, allocations: 8.894 MB / 0.5375 GB, free: 44.93 MB / 382.1 MB
Notification: Performance of [INI] Simplify: time 0.00626/0.4087, allocations: 3.572 MB / 0.541 GB, free: 43.15 MB / 382.1 MB
Notification: Performance of [INI] Inline: time 0.01376/0.4224, allocations: 13.95 MB / 0.5546 GB, free: 32.95 MB / 382.1 MB
Notification: Performance of [INI] Partitioning: time 0.0007088/0.4231, allocations: 169 kB / 0.5548 GB, free: 32.75 MB / 382.1 MB
Notification: Performance of [INI] Cleanup: time 0.002981/0.4261, allocations: 2.098 MB / 0.5569 GB, free: 30.68 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_8 = $PRE.P3.firingSumIn.firingSum > 0 or $PRE.P3.firingSumOut.firingSum > 0 ($RES_EVT_572);
	[SCAL] (1) P2.tokeninout = $START.P2.tokeninout ($RES_SRT_629);
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[1] $SEV_70
	[DISC] (1) Boolean[1] T2.outPlaces.fire = {T2.fire for $f1 in 1:1}
	[DISC] (1) Boolean[1] T1.outPlaces.enabledByInPlaces = {T1.enabledByInPlaces 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_720)
	[SCAL] (1) $PRE.T2.firingTime = $START.T2.firingTime ($RES_SRT_719)
	[SCAL] (1) $PRE.T2.ani = $START.T2.ani ($RES_SRT_718)
	[SCAL] (1) $PRE.P1.firingSumIn.firingSum = $START.P1.firingSumIn.firingSum ($RES_SRT_717)
	[SCAL] (1) $PRE.P1.firingSumOut.firingSum = $START.P1.firingSumOut.firingSum ($RES_SRT_716)
	[SCAL] (1) $PRE.P2.firingSumIn.firingSum = $START.P2.firingSumIn.firingSum ($RES_SRT_715)
	[SCAL] (1) $PRE.P2.firingSumOut.firingSum = $START.P2.firingSumOut.firingSum ($RES_SRT_714)
	[SCAL] (1) $PRE.P3.firingSumIn.firingSum = $START.P3.firingSumIn.firingSum ($RES_SRT_713)
	[SCAL] (1) $PRE.P3.firingSumOut.firingSum = $START.P3.firingSumOut.firingSum ($RES_SRT_712)
	[ARRY] (4) $PRE.P1.enableOut.state128 = $START.P1.enableOut.state128 ($RES_SRT_711)
	[ARRY] (4) $PRE.P1.enableIn.state128 = $START.P1.enableIn.state128 ($RES_SRT_710)
	[ARRY] (4) $PRE.P2.enableOut.state128 = $START.P2.enableOut.state128 ($RES_SRT_709)
	[ARRY] (4) $PRE.P2.enableIn.state128 = $START.P2.enableIn.state128 ($RES_SRT_708)
	[ARRY] (4) $PRE.P3.enableOut.state128 = $START.P3.enableOut.state128 ($RES_SRT_707)
	[ARRY] (4) $PRE.P3.enableIn.state128 = $START.P3.enableIn.state128 ($RES_SRT_706)
	[SCAL] (1) $PRE.T2.fireTime = $START.T2.fireTime ($RES_SRT_705)
	[SCAL] (1) $PRE.T1.ani = $START.T1.ani ($RES_SRT_704)
	[SCAL] (1) $PRE.T1.firingTime = $START.T1.firingTime ($RES_SRT_703)

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.153270868002437]
Failed to read output from testmodel.py, exit status != 0:
0.44580572901759297 0.45462532200000005 0.413849807
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f7b3f022fb0>