Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.Graetz.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.001672/0.001672, allocations: 99.59 kB / 21.2 MB, free: 0.6406 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.001743/0.001743, allocations: 217.1 kB / 24.5 MB, free: 3.926 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.47/1.47, allocations: 230.6 MB / 258.3 MB, free: 7.766 MB / 206.1 MB
"
[Timeout remaining time 178]
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(Modelica.Electrical.Spice3.Examples.Graetz,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|D1.pin|D3.n.v",fileNamePrefix="Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.Graetz")
translateModel(Modelica.Electrical.Spice3.Examples.Graetz,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|D1.pin|D3.n.v",fileNamePrefix="Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.Graetz") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.614e-05/2.614e-05, allocations: 2.281 kB / 378.9 MB, free: 2.465 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.Spice3.Examples.Graetz): time 0.1788/0.1788, allocations: 4.569 MB / 383.5 MB, free: 12.35 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008178/0.187, allocations: 5.864 MB / 389.4 MB, free: 9.086 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0006629/0.1876, allocations: 64.81 kB / 389.4 MB, free: 9.043 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001133/0.1888, allocations: 476.4 kB / 389.9 MB, free: 8.812 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002492/0.1913, allocations: 0.833 MB / 390.7 MB, free: 8.305 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001284/0.1926, allocations: 442.3 kB / 391.1 MB, free: 8.102 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.003028/0.1956, allocations: 2.303 MB / 393.4 MB, free: 7.184 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0001619/0.1957, allocations: 63.38 kB / 393.5 MB, free: 7.152 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0007108/0.1965, allocations: 0.5114 MB / 394 MB, free: 6.953 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.000417/0.1969, allocations: 402.4 kB / 394.4 MB, free: 6.746 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 8.361e-05/0.197, allocations: 32.75 kB / 394.4 MB, free: 6.746 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.00308/0.2, allocations: 0.9791 MB / 395.4 MB, free: 6.473 MB / 302.1 MB
Notification: Performance of combineBinaries: time 0.001235/0.2013, allocations: 2.036 MB / 397.5 MB, free: 5.074 MB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0006473/0.2019, allocations: 1.558 MB / 399 MB, free: 3.945 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0001358/0.2021, allocations: 78.5 kB / 399.1 MB, free: 3.945 MB / 302.1 MB
Notification: Performance of FrontEnd: time 5.03e-05/0.2021, allocations: 8.344 kB / 399.1 MB, free: 3.945 MB / 302.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 61 (54)
 * Number of variables: 63 (63)
Notification: Performance of [SIM] Bindings: time 0.00503/0.2071, allocations: 7.228 MB / 406.3 MB, free: 14.98 MB / 318.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0007376/0.2079, allocations: 490.2 kB / 406.8 MB, free: 14.67 MB / 318.1 MB
Notification: Performance of [SIM] Early Inline: time 0.001127/0.209, allocations: 0.9777 MB / 407.8 MB, free: 14.09 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0004245/0.2094, allocations: 284.5 kB / 408.1 MB, free: 13.91 MB / 318.1 MB
Notification: Performance of [SIM] Alias: time 0.001841/0.2113, allocations: 1.272 MB / 409.3 MB, free: 13.07 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0003613/0.2116, allocations: 249.5 kB / 409.6 MB, free: 12.91 MB / 318.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001268/0.2118, allocations: 115.8 kB / 409.7 MB, free: 12.81 MB / 318.1 MB
Notification: Performance of [SIM] Detect States: time 0.0003779/0.2121, allocations: 300.6 kB / 410 MB, free: 12.57 MB / 318.1 MB
Notification: Performance of [SIM] Events: time 0.0001161/0.2122, allocations: 60.91 kB / 410 MB, free: 12.53 MB / 318.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0004854/0.2127, allocations: 397.3 kB / 410.4 MB, free: 12.34 MB / 318.1 MB
Error: Internal error NBResolveSingularities.indexReduction failed because there was not enough state candidates to balance out the constraint equations.
Constraint Equations (38/57)
******************************
(1)       [TUPL] (2) ($FUN_1, D1.m_dCap) = Modelica.Electrical.Spice3.Internal.Diode.diodeNoBypassCode(D1.param, Modelica.Electrical.Spice3.Internal.Diode.DiodeParams(1.0, false, 0.0, 0.0, false), D1.c2, Modelica.Electrical.Spice3.Internal.Model.Model(300.15), false, {D1.pin, D1.n.v}) ($RES_AUX_58)
(2)       [ALGO] (16) ($RES_SIM_0)
(2)       [----] rout.vp := Modelica.Electrical.Spice3.Internal.Rsemiconductor.resistorInitEquations(rout.rp, rout.lp);
(2)       [----] (rout.vp.m_dConduct, rout.vp.m_dCond_dTemp) := Modelica.Electrical.Spice3.Internal.Functions.resDepTemp(rout.vp.m_dResist, rout.rp.m_dTemp, rout.lp.m_dTnom, rout.lp.m_dTC1, rout.lp.m_dTC2);
(2)       [----] rout.i := rout.vp.m_dConduct * rout.v;

(3)       [TUPL] (2) ($FUN_3, D3.m_dCap) = Modelica.Electrical.Spice3.Internal.Diode.diodeNoBypassCode(D3.param, Modelica.Electrical.Spice3.Internal.Diode.DiodeParams(1.0, false, 0.0, 0.0, false), D3.c2, Modelica.Electrical.Spice3.Internal.Model.Model(300.15), false, {D3.pin, D3.n.v}) ($RES_AUX_57)
(4)       [TUPL] (2) ($FUN_5, D4.m_dCap) = Modelica.Electrical.Spice3.Internal.Diode.diodeNoBypassCode(D4.param, Modelica.Electrical.Spice3.Internal.Diode.DiodeParams(1.0, false, 0.0, 0.0, false), D4.c2, Modelica.Electrical.Spice3.Internal.Model.Model(300.15), false, {D4.pin, D3.n.v}) ($RES_AUX_56)
(5)       [SCAL] (1) vsin.v = D1.n.v ($RES_SIM_2)
(6)       [TUPL] (2) ($FUN_7, D2.m_dCap) = Modelica.Electrical.Spice3.Internal.Diode.diodeNoBypassCode(D2.param, Modelica.Electrical.Spice3.Internal.Diode.DiodeParams(1.0, false, 0.0, 0.0, false), D2.c2, Modelica.Electrical.Spice3.Internal.Model.Model(300.15), false, {D2.pin, 0.0}) ($RES_AUX_55)
(7)       [SCAL] (1) vsin.v = vsin.VO + (if $TEV_0 then 0.0 else exp(-vsin.THETA * (time - vsin.TD)) * vsin.VA * sin(6.283185307179586 * (time - vsin.TD) * vsin.FREQ)) ($RES_SIM_5)
(8)       [SCAL] (1) rout.v = D3.n.v - D2.p.v ($RES_SIM_7)
(9)       [RECD] (1) D2.cc = $FUN_7 ($RES_SIM_65)
(10)      [RECD] (1) D4.cc = $FUN_5 ($RES_SIM_63)
(11)      [RECD] (1) D3.cc = $FUN_3 ($RES_SIM_61)
(12)      [SCAL] (1) D3.ir + vsin.i + D1.n.i = 0.0 ($RES_SIM_45)
(13)      [SCAL] (1) D4.n.i + rout.i + D3.n.i = 0.0 ($RES_SIM_44)
(14)      [SCAL] (1) (D2.n.i + ground.p.i + D4.ir) - vsin.i = 0.0 ($RES_SIM_43)
(15)      [SCAL] (1) D3.n.i = -(D3.igmin + D3.cc.m_dCurrent + D3.icap) ($RES_SIM_28)
(16)      [SCAL] (1) (D2.ir + D1.ir) - rout.i = 0.0 ($RES_SIM_42)
(17)      [SCAL] (1) 0.0 = (D3.igmin + D3.cc.m_dCurrent + D3.icap) - D3.ir ($RES_SIM_27)
(18)      [SCAL] (1) D1.icap = D1.m_dCap * ($DER.D1.pin - $DER.D1.n.v) ($RES_SIM_40)
(19)      [SCAL] (1) D4.icap = D4.m_dCap * ($DER.D4.pin - $DER.D3.n.v) ($RES_SIM_24)
(20)      [SCAL] (1) D4.ir * D4.param.m_resist = -D4.pin ($RES_SIM_23)
(21)      [SCAL] (1) D4.igmin = 1e-12 * (D4.pin - D3.n.v) ($RES_SIM_22)
(22)      [SCAL] (1) D4.n.i = -(D4.igmin + D4.cc.m_dCurrent + D4.icap) ($RES_SIM_20)
(23)      [RECD] (1) D1.cc = $FUN_1 ($RES_SIM_59)
(24)      [SCAL] (1) D1.ir * D1.param.m_resist = D2.p.v - D1.pin ($RES_SIM_39)
(25)      [SCAL] (1) D1.igmin = 1e-12 * (D1.pin - D1.n.v) ($RES_SIM_38)
(26)      [SCAL] (1) D1.n.i = -(D1.igmin + D1.cc.m_dCurrent + D1.icap) ($RES_SIM_36)
(27)      [SCAL] (1) 0.0 = (D1.igmin + D1.cc.m_dCurrent + D1.icap) - D1.ir ($RES_SIM_35)
(28)      [SCAL] (1) 0.0 = (D4.igmin + D4.cc.m_dCurrent + D4.icap) - D4.ir ($RES_SIM_19)
(29)      [SCAL] (1) D1.v = D2.p.v - D1.n.v ($RES_SIM_34)
(30)      [SCAL] (1) D3.icap = D3.m_dCap * ($DER.D3.pin - $DER.D3.n.v) ($RES_SIM_32)
(31)      [SCAL] (1) D2.icap = D2.m_dCap * $DER.D2.pin ($RES_SIM_16)
(32)      [SCAL] (1) D3.ir * D3.param.m_resist = D1.n.v - D3.pin ($RES_SIM_31)
(33)      [SCAL] (1) D2.ir * D2.param.m_resist = D2.p.v - D2.pin ($RES_SIM_15)
(34)      [SCAL] (1) D3.igmin = 1e-12 * (D3.pin - D3.n.v) ($RES_SIM_30)
(35)      [SCAL] (1) D2.igmin = 1e-12 * D2.pin ($RES_SIM_14)
(36)      [SCAL] (1) D2.n.i = -(D2.igmin + D2.cc.m_dCurrent + D2.icap) ($RES_SIM_12)
(37)      [SCAL] (1) 0.0 = (D2.igmin + D2.cc.m_dCurrent + D2.icap) - D2.ir ($RES_SIM_11)
(38)      [SCAL] (1) D2.v = D2.p.v ($RES_SIM_10)


State Candidate Variables (53/53)
***********************************
(1)       [ALGB] (1) Real D3.icap
(2)       [DER-] (1) Real $DER.D3.n.v
(3)       [ALGB] (1) Real $FUN_5.m_dCurrent
(4)       [ALGB] (1) Real D2.icap
(5)       [ALGB] (1) Real D1.icap
(6)       [ALGB] (1) Real $FUN_3.m_dCurrent
(7)       [ALGB] (1) Real $FUN_1.m_dCurrent
(8)       [ALGB] (1) Real D2.v
(9)       [ALGB] (1) Real D1.v
(10)      [ALGB] (1) Real rout.i
(11)      [ALGB] (1) Real D4.ir
(12)      [DER-] (1) Real $DER.D2.pin
(13)      [ALGB] (1) Real D3.ir
(14)      [ALGB] (1) Real D2.p.v
(15)      [ALGB] (1) Real D2.ir
(16)      [ALGB] (1) Real D1.ir
(17)      [ALGB] (1) Real D1.cc.m_dCurrent
(18)      [ALGB] (1) Real D2.cc.m_dCurrent
(19)      [ALGB] (1) Real D3.cc.m_dCurrent
(20)      [ALGB] (1) Real D4.cc.m_dCurrent
(21)      [ALGB] (1) Real rout.vp.m_dLength
(22)      [ALGB] (1) Real D4.igmin
(23)      [ALGB] (1) Real D4.m_dCap
(24)      [ALGB] (1) Real D3.igmin
(25)      [ALGB] (1) Real D3.m_dCap
(26)      [ALGB] (1) Real D2.igmin
(27)      [STAT] (1) Real D4.pin
(28)      [ALGB] (1) Real D2.m_dCap
(29)      [ALGB] (1) Real D1.igmin
(30)      [STAT] (1) Real D3.pin
(31)      [ALGB] (1) Real D1.m_dCap
(32)      [STAT] (1) Real D2.pin
(33)      [DER-] (1) Real $DER.D1.pin
(34)      [STAT] (1) Real D1.pin (fixed = true, start = 0.0)
(35)      [ALGB] (1) Real rout.vp.m_dWidth
(36)      [DER-] (1) Real $DER.D4.pin
(37)      [ALGB] (1) Real vsin.v
(38)      [ALGB] (1) Real rout.vp.m_dCond_dTemp
(39)      [ALGB] (1) flow Real D4.n.i
(40)      [ALGB] (1) Real rout.vp.m_dResist
(41)      [ALGB] (1) flow Real D3.n.i
(42)      [ALGB] (1) flow Real D2.n.i
(43)      [ALGB] (1) flow Real ground.p.i
(44)      [ALGB] (1) flow Real D1.n.i
(45)      [DER-] (1) Real $DER.D1.n.v
(46)      [ALGB] (1) Real vsin.i
(47)      [DER-] (1) Real $DER.D3.pin
(48)      [ALGB] (1) Real rout.vp.m_dConduct
(49)      [STAT] (1) Real D3.n.v (fixed = true, start = 0.0)
(50)      [ALGB] (1) Real rout.v
(51)      [STAT] (1) Real D1.n.v
(52)      [ALGB] (1) Real $FUN_7.m_dCurrent
(53)      [ALGB] (1) Real D4.icap
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 2.5475547057576478]
Failed to read output from testmodel.py, exit status != 0:
['time', 'D1.pin', 'D3.n.v']
0.2221130272373557 0.237308135 0.032002636
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f8ef7d7ef20>