Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_3.2.3_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.001553/0.001553, allocations: 110.3 kB / 22 MB, free: 4.559 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.001551/0.001551, allocations: 214 kB / 25.3 MB, free: 2.906 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.726/1.726, allocations: 207.7 MB / 236.2 MB, free: 9.766 MB / 190.1 MB
"
[Timeout remaining time 178]
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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_3.2.3_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_3.2.3_Modelica.Electrical.Spice3.Examples.Graetz") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 3.087e-05/3.087e-05, allocations: 2.281 kB / 348.5 MB, free: 9.379 MB / 286.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.Spice3.Examples.Graetz): time 0.008845/0.008875, allocations: 4.57 MB / 353.1 MB, free: 4.781 MB / 286.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01229/0.02116, allocations: 5.764 MB / 358.8 MB, free: 14.99 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001661/0.02282, allocations: 63.31 kB / 358.9 MB, free: 14.93 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002526/0.02535, allocations: 469 kB / 359.4 MB, free: 14.47 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004653/0.03, allocations: 0.8225 MB / 360.2 MB, free: 13.64 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002751/0.03275, allocations: 442.3 kB / 360.6 MB, free: 13.21 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.005546/0.0383, allocations: 2.262 MB / 362.9 MB, free: 10.94 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002723/0.03857, allocations: 56.89 kB / 362.9 MB, free: 10.89 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.00119/0.03976, allocations: 0.4941 MB / 363.4 MB, free: 10.39 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.000874/0.04064, allocations: 373.5 kB / 363.8 MB, free: 10.02 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001535/0.04079, allocations: 32 kB / 363.8 MB, free: 9.992 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003424/0.04421, allocations: 0.921 MB / 364.7 MB, free: 9.07 MB / 302.1 MB
Notification: Performance of combineBinaries: time 0.002672/0.04689, allocations: 2.03 MB / 366.8 MB, free: 7.016 MB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001854/0.04874, allocations: 1.564 MB / 368.3 MB, free: 5.43 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.000446/0.04918, allocations: 84 kB / 368.4 MB, free: 5.348 MB / 302.1 MB
Notification: Performance of FrontEnd: time 0.000112/0.0493, allocations: 8 kB / 368.4 MB, free: 5.34 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.009685/0.05898, allocations: 7.228 MB / 375.7 MB, free: 13.88 MB / 318.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.001286/0.06027, allocations: 0.4989 MB / 376.2 MB, free: 13.36 MB / 318.1 MB
Notification: Performance of [SIM] Early Inline: time 0.001489/0.06176, allocations: 0.9822 MB / 377.1 MB, free: 12.35 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0008166/0.06257, allocations: 270.5 kB / 377.4 MB, free: 12.03 MB / 318.1 MB
Notification: Performance of [SIM] Alias: time 0.002583/0.06516, allocations: 1.305 MB / 378.7 MB, free: 10.55 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0004885/0.06565, allocations: 242 kB / 378.9 MB, free: 10.26 MB / 318.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001949/0.06584, allocations: 108.3 kB / 379 MB, free: 10.13 MB / 318.1 MB
Notification: Performance of [SIM] Detect States: time 0.0004744/0.06631, allocations: 282.2 kB / 379.3 MB, free: 9.801 MB / 318.1 MB
Notification: Performance of [SIM] Events: time 0.0002467/0.06656, allocations: 78.2 kB / 379.4 MB, free: 9.723 MB / 318.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0007236/0.06728, allocations: 396.6 kB / 379.8 MB, free: 9.32 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 (40/59)
******************************
(1)       [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_59)
(2)       [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_58)
(3)       [ALGO] (16) ($RES_SIM_0)
(3)       [----] rout.vp := Modelica.Electrical.Spice3.Internal.Rsemiconductor.resistorInitEquations(rout.rp, rout.lp);
(3)       [----] (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);
(3)       [----] rout.i := rout.vp.m_dConduct * rout.v;

(4)       [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_57)
(5)       [SCAL] (1) $FUN_9 = exp(-(time - vsin.TD) * vsin.THETA) ($RES_AUX_56)
(6)       [SCAL] (1) $FUN_10 = sin(6.283185307179586 * vsin.FREQ * (time - vsin.TD)) ($RES_AUX_55)
(7)       [SCAL] (1) vsin.v = D1.n.v ($RES_SIM_4)
(8)       [SCAL] (1) vsin.v = vsin.VO + (if $TEV_0 then 0.0 else $FUN_9 * vsin.VA * $FUN_10) ($RES_SIM_5)
(9)       [RECD] (1) D2.cc = $FUN_7 ($RES_SIM_67)
(10)      [RECD] (1) D4.cc = $FUN_5 ($RES_SIM_65)
(11)      [SCAL] (1) rout.v = D3.n.v - D2.p.v ($RES_SIM_9)
(12)      [RECD] (1) D3.cc = $FUN_3 ($RES_SIM_63)
(13)      [RECD] (1) D1.cc = $FUN_1 ($RES_SIM_61)
(14)      [SCAL] (1) D3.ir + vsin.i + D1.n.i = 0.0 ($RES_SIM_45)
(15)      [SCAL] (1) D4.n.i + rout.i + D3.n.i = 0.0 ($RES_SIM_44)
(16)      [SCAL] (1) (D2.n.i + ground.p.i + D4.ir) - vsin.i = 0.0 ($RES_SIM_43)
(17)      [SCAL] (1) D3.n.i = -(D3.igmin + D3.cc.m_dCurrent + D3.icap) ($RES_SIM_28)
(18)      [SCAL] (1) (D2.ir + D1.ir) - rout.i = 0.0 ($RES_SIM_42)
(19)      [SCAL] (1) 0.0 = (D3.igmin + D3.cc.m_dCurrent + D3.icap) - D3.ir ($RES_SIM_27)
(20)      [SCAL] (1) D1.icap = D1.m_dCap * ($DER.D1.pin - $DER.D1.n.v) ($RES_SIM_40)
(21)      [SCAL] (1) D4.icap = D4.m_dCap * ($DER.D4.pin - $DER.D3.n.v) ($RES_SIM_24)
(22)      [SCAL] (1) D4.ir * D4.param.m_resist = -D4.pin ($RES_SIM_23)
(23)      [SCAL] (1) D4.igmin = 1e-12 * (D4.pin - D3.n.v) ($RES_SIM_22)
(24)      [SCAL] (1) D4.n.i = -(D4.igmin + D4.cc.m_dCurrent + D4.icap) ($RES_SIM_20)
(25)      [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_60)
(26)      [SCAL] (1) D1.ir * D1.param.m_resist = D2.p.v - D1.pin ($RES_SIM_39)
(27)      [SCAL] (1) D1.igmin = 1e-12 * (D1.pin - D1.n.v) ($RES_SIM_38)
(28)      [SCAL] (1) D1.n.i = -(D1.igmin + D1.cc.m_dCurrent + D1.icap) ($RES_SIM_36)
(29)      [SCAL] (1) 0.0 = (D1.igmin + D1.cc.m_dCurrent + D1.icap) - D1.ir ($RES_SIM_35)
(30)      [SCAL] (1) 0.0 = (D4.igmin + D4.cc.m_dCurrent + D4.icap) - D4.ir ($RES_SIM_19)
(31)      [SCAL] (1) D1.v = D2.p.v - D1.n.v ($RES_SIM_34)
(32)      [SCAL] (1) D3.icap = D3.m_dCap * ($DER.D3.pin - $DER.D3.n.v) ($RES_SIM_32)
(33)      [SCAL] (1) D2.icap = D2.m_dCap * $DER.D2.pin ($RES_SIM_16)
(34)      [SCAL] (1) D3.ir * D3.param.m_resist = D1.n.v - D3.pin ($RES_SIM_31)
(35)      [SCAL] (1) D2.ir * D2.param.m_resist = D2.p.v - D2.pin ($RES_SIM_15)
(36)      [SCAL] (1) D3.igmin = 1e-12 * (D3.pin - D3.n.v) ($RES_SIM_30)
(37)      [SCAL] (1) D2.igmin = 1e-12 * D2.pin ($RES_SIM_14)
(38)      [SCAL] (1) D2.n.i = -(D2.igmin + D2.cc.m_dCurrent + D2.icap) ($RES_SIM_12)
(39)      [SCAL] (1) 0.0 = (D2.igmin + D2.cc.m_dCurrent + D2.icap) - D2.ir ($RES_SIM_11)
(40)      [SCAL] (1) D2.v = D2.p.v ($RES_SIM_10)


State Candidate Variables (55/55)
***********************************
(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 $FUN_10
(22)      [ALGB] (1) Real rout.vp.m_dLength
(23)      [ALGB] (1) Real D4.igmin
(24)      [ALGB] (1) Real D4.m_dCap
(25)      [ALGB] (1) Real D3.igmin
(26)      [ALGB] (1) Real D3.m_dCap
(27)      [ALGB] (1) Real D2.igmin
(28)      [STAT] (1) Real D4.pin
(29)      [ALGB] (1) Real D2.m_dCap
(30)      [ALGB] (1) Real D1.igmin
(31)      [STAT] (1) Real D3.pin
(32)      [ALGB] (1) Real D1.m_dCap
(33)      [STAT] (1) Real D2.pin
(34)      [DER-] (1) Real $DER.D1.pin
(35)      [ALGB] (1) Real rout.vp.m_dWidth
(36)      [DER-] (1) Real $DER.D4.pin
(37)      [STAT] (1) Real D1.pin (fixed = true, start = 0.0)
(38)      [ALGB] (1) Real vsin.v
(39)      [ALGB] (1) Real rout.vp.m_dCond_dTemp
(40)      [ALGB] (1) flow Real D4.n.i
(41)      [ALGB] (1) Real rout.vp.m_dResist
(42)      [ALGB] (1) flow Real D3.n.i
(43)      [ALGB] (1) flow Real D2.n.i
(44)      [ALGB] (1) flow Real ground.p.i
(45)      [ALGB] (1) flow Real D1.n.i
(46)      [DER-] (1) Real $DER.D1.n.v
(47)      [ALGB] (1) Real vsin.i
(48)      [DER-] (1) Real $DER.D3.pin
(49)      [ALGB] (1) Real rout.vp.m_dConduct
(50)      [STAT] (1) Real D3.n.v (fixed = true, start = 0.0)
(51)      [ALGB] (1) Real rout.v
(52)      [STAT] (1) Real D1.n.v
(53)      [ALGB] (1) Real $FUN_9
(54)      [ALGB] (1) Real $FUN_7.m_dCurrent
(55)      [ALGB] (1) Real D4.icap
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 2.9054788530338556]
Failed to read output from testmodel.py, exit status != 0:
['time', 'D1.pin', 'D3.n.v']
0.07912414299789816 0.340221898 0.287703924
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fc935a46fb0>