Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr AES_AES.ProcessComponents.Electric.Generators_PAport.Generator_order0_prescribed_P.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.001426/0.001426, 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.001586/0.001586, allocations: 210.3 kB / 23.06 MB, free: 4.984 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.477/1.477, 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/AES main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/AES main/package.mo): time 0.1025/0.1025, allocations: 24.04 MB / 337.3 MB, free: 11.36 MB / 270.1 MB
"
[Timeout remaining time 180]
Using package AES with version  (/home/hudson/saved_omc/libraries/.openmodelica/libraries/AES main/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(AES.ProcessComponents.Electric.Generators_PAport.Generator_order0_prescribed_P,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="AES_AES.ProcessComponents.Electric.Generators_PAport.Generator_order0_prescribed_P")
translateModel(AES.ProcessComponents.Electric.Generators_PAport.Generator_order0_prescribed_P,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="AES_AES.ProcessComponents.Electric.Generators_PAport.Generator_order0_prescribed_P") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.122e-05/2.122e-05, allocations: 2.281 kB / 472 MB, free: 8.234 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(AES.ProcessComponents.Electric.Generators_PAport.Generator_order0_prescribed_P): time 0.002654/0.002676, allocations: 1.475 MB / 473.5 MB, free: 6.746 MB / 334.1 MB
Notification: Performance of NFInst.instExpressions: time 0.00082/0.003496, allocations: 423.2 kB / 473.9 MB, free: 6.332 MB / 334.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 4.508e-05/0.003541, allocations: 7.938 kB / 473.9 MB, free: 6.324 MB / 334.1 MB
Notification: Performance of NFTyping.typeComponents: time 4.49e-05/0.003586, allocations: 7.922 kB / 473.9 MB, free: 6.316 MB / 334.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0001851/0.003771, allocations: 15.84 kB / 473.9 MB, free: 6.301 MB / 334.1 MB
Notification: Performance of NFTyping.typeClassSections: time 4.975e-05/0.00382, allocations: 15.97 kB / 474 MB, free: 6.285 MB / 334.1 MB
Notification: Performance of NFFlatten.flatten: time 0.000125/0.003945, allocations: 87.62 kB / 474 MB, free: 6.199 MB / 334.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 6.572e-05/0.004011, allocations: 4 kB / 474 MB, free: 6.195 MB / 334.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0001751/0.004186, allocations: 31.86 kB / 474.1 MB, free: 6.164 MB / 334.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 8.029e-05/0.004267, allocations: 39.81 kB / 474.1 MB, free: 6.125 MB / 334.1 MB
Notification: Performance of NFPackage.collectConstants: time 1.783e-05/0.004284, allocations: 4 kB / 474.1 MB, free: 6.121 MB / 334.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 1.486e-05/0.004299, allocations: 4 kB / 474.1 MB, free: 6.117 MB / 334.1 MB
Notification: Performance of combineBinaries: time 0.000148/0.004447, allocations: 138.5 kB / 474.3 MB, free: 5.98 MB / 334.1 MB
Notification: Performance of replaceArrayConstructors: time 5.731e-05/0.004505, allocations: 86.89 kB / 474.3 MB, free: 5.895 MB / 334.1 MB
Notification: Performance of NFVerifyModel.verify: time 2.173e-05/0.004526, allocations: 8 kB / 474.4 MB, free: 5.887 MB / 334.1 MB
Notification: Performance of FrontEnd: time 9.037e-06/0.004535, allocations: 4 kB / 474.4 MB, free: 5.883 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: 12 (12)
 * Number of variables: 12 (12)
Notification: Performance of [SIM] Bindings: time 0.0006015/0.005137, allocations: 459 kB / 474.8 MB, free: 5.312 MB / 334.1 MB
Notification: Performance of [SIM] FunctionAlias: time 6.471e-05/0.005202, allocations: 47.94 kB / 474.9 MB, free: 5.254 MB / 334.1 MB
Notification: Performance of [SIM] Early Inline: time 0.0001553/0.005357, allocations: 148.2 kB / 475 MB, free: 5.082 MB / 334.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0001294/0.005486, allocations: 88.66 kB / 475.1 MB, free: 4.949 MB / 334.1 MB
Notification: Performance of [SIM] Alias: time 0.000658/0.006144, allocations: 312.6 kB / 475.4 MB, free: 4.562 MB / 334.1 MB
Notification: Performance of [SIM] Simplify 2: time 9.515e-05/0.006239, allocations: 81.47 kB / 475.5 MB, free: 4.438 MB / 334.1 MB
Notification: Performance of [SIM] Remove Stream: time 5.017e-05/0.00629, allocations: 44.42 kB / 475.5 MB, free: 4.367 MB / 334.1 MB
Notification: Performance of [SIM] Detect States: time 0.0001743/0.006464, allocations: 108.8 kB / 475.6 MB, free: 4.207 MB / 334.1 MB
Notification: Performance of [SIM] Events: time 2.741e-05/0.006491, allocations: 15.84 kB / 475.6 MB, free: 4.191 MB / 334.1 MB
Notification: Performance of [SIM] Partitioning: time 9.796e-05/0.006589, allocations: 56.03 kB / 475.7 MB, free: 4.129 MB / 334.1 MB
Error: Internal error NBSorting.tarjan failed to sort system:
System Variables (9/9)
************************
(1|1)          [ALGB] (1) Real wp (start = wnom)
(2|2)          [ALGB] (1) Real taum
(3|3)          [DER-] (1) Real $DER.deltag
(4|4)          [ALGB] (1) Real Pm
(5|5)          [DDER] (1) Real $DER.thetap (StateSelect = avoid)
(6|6)          [ALGB] (1) Real taue
(7|7)          [DER-] (1) output Real $DER.fg
(8|8)          [DER-] (1) Real $DER.thetag
(9|9)          [DSTA] (1) Real thetap


System Equations (8/8)
************************
(1|1)          [SCAL] (1) taue = K * deltag + F * $DER.deltag ($RES_SIM_4)
(2|2)          [SCAL] (1) J * (6.283185307179586 * $DER.fg) = taum - taue ($RES_SIM_3)
(3|3)          [SCAL] (1) Pm = (6.283185307179586 * fg) * taum ($RES_SIM_9)
(4|4)          [SCAL] (1) deltag = thetag - thetap ($RES_SIM_8)
(5|5)          [SCAL] (1) 6.283185307179586 * fg - wnom = $DER.thetag ($RES_SIM_7)
(6|6)          [SCAL] (1) wp - wnom = $DER.thetap ($RES_SIM_6)
(7|7)          [SCAL] (1) 0.0 = wp * taue ($RES_SIM_5)
(8|8)          [SCAL] (1) $DER.deltag = $DER.thetag - $DER.thetap ($RES_SIM_15)


===================
  Scalar Matching
===================

variable to equation
**********************
	var 1 --> eqn 6
	var 2 --> eqn 3
	var 3 --> eqn 1
	var 4 --> eqn -1
	var 5 --> eqn 8
	var 6 --> eqn 7
	var 7 --> eqn 2
	var 8 --> eqn 5
	var 9 --> eqn 4

equation to variable
**********************
	eqn 1 --> var 3
	eqn 2 --> var 7
	eqn 3 --> var 2
	eqn 4 --> var 9
	eqn 5 --> var 8
	eqn 6 --> var 1
	eqn 7 --> var 6
	eqn 8 --> var 5
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 2.5543341679731384]
Failed to read output from testmodel.py, exit status != 0:
0.014961701002903283 0.030133086000000003 0.022541311
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fa4203c6fb0>