Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Dynawo_Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4.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.00142/0.00142, allocations: 76.83 kB / 21.61 MB, free: 2.848 MB / 18.57 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.001063/0.001063, allocations: 174.7 kB / 25.04 MB, free: 8.738 MB / 18.57 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 0.8679/0.8679, allocations: 158.6 MB / 186.9 MB, free: 2.746 MB / 170.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo 1.8.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo 1.8.0/package.mo): time 0.3605/0.3605, allocations: 90.83 MB / 330.1 MB, free: 7.84 MB / 314.7 MB
"
[Timeout remaining time 179]
Using package Dynawo with version 1.8.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo 1.8.0/package.mo)
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)
Ignoring simflag  -noemit -noHomotopyOnFirstTry=() since the simulation runtime does not accept it
Ignoring simflag  -noemit -noRestart=() since the simulation runtime does not accept it
Ignoring simflag  -noemit -noRootFinding=() since the simulation runtime does not accept it
Running command: translateModel(Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4,tolerance=1e-05,outputFormat="empty",numberOfIntervals=20000,variableFilter="",fileNamePrefix="Dynawo_Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4")
translateModel(Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4,tolerance=1e-05,outputFormat="empty",numberOfIntervals=20000,variableFilter="",fileNamePrefix="Dynawo_Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.323e-06/1.323e-06, allocations: 0 / 387.5 MB, free: 7.043 MB / 362.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.169e-05/2.301e-05, allocations: 5.406 kB / 387.5 MB, free: 7.039 MB / 362.7 MB
Notification: Performance of NFInst.instantiate(Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4): time 0.01223/0.01225, allocations: 15.09 MB / 402.6 MB, free: 7.922 MB / 378.7 MB
Notification: Performance of NFInst.instExpressions: time 0.009457/0.02171, allocations: 7.176 MB / 409.8 MB, free: 0.7305 MB / 378.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001441/0.02315, allocations: 107.3 kB / 409.9 MB, free: 0.625 MB / 378.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001213/0.02436, allocations: 0.7941 MB / 410.7 MB, free: 15.82 MB / 394.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.002332/0.02669, allocations: 1.58 MB / 412.3 MB, free: 14.25 MB / 394.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo 1.8.0/Electrical/Photovoltaics/WECC/PVVoltageSource_INIT.mo:106:3-106:91:writable] Warning: In relation Modelica.ComplexMath.'abs'(pvVoltageSource_INIT.s0Pu) == 0.0, == on Real operands is deprecated in non-function contexts.
Notification: Performance of NFTyping.typeClassSections: time 0.003599/0.03029, allocations: 3.046 MB / 415.3 MB, free: 11.21 MB / 394.7 MB
Notification: Performance of NFFlatten.flatten: time 0.004561/0.03485, allocations: 5.831 MB / 421.2 MB, free: 5.375 MB / 394.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.00149/0.03634, allocations: 1.445 MB / 422.6 MB, free: 3.832 MB / 394.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.00162/0.03796, allocations: 1.984 MB / 424.6 MB, free: 1.844 MB / 394.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001256/0.03922, allocations: 1.725 MB / 426.3 MB, free: 116 kB / 394.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005527/0.03977, allocations: 0.6329 MB / 426.9 MB, free: 15.48 MB / 410.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001212/0.04098, allocations: 0.9526 MB / 427.9 MB, free: 14.53 MB / 410.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0006276/0.04161, allocations: 1.255 MB / 429.2 MB, free: 13.27 MB / 410.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0009654/0.04258, allocations: 1.589 MB / 430.7 MB, free: 11.68 MB / 410.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.003338/0.04592, allocations: 4.087 MB / 434.8 MB, free: 7.574 MB / 410.7 MB
Notification: Performance of FrontEnd - DAE generated: time 2.254e-06/0.04592, allocations: 0.5312 kB / 434.8 MB, free: 7.574 MB / 410.7 MB
Notification: Performance of FrontEnd: time 8.11e-07/0.04592, allocations: 4 kB / 434.8 MB, free: 7.57 MB / 410.7 MB
Notification: Performance of Transformations before backend: time 2.463e-05/0.04594, allocations: 1.812 kB / 434.8 MB, free: 7.57 MB / 410.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 827
 * Number of variables: 827
Notification: Performance of Generate backend data structure: time 0.007198/0.05314, allocations: 6.197 MB / 441 MB, free: 1.336 MB / 410.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.138e-05/0.05317, allocations: 13.25 kB / 441 MB, free: 1.324 MB / 410.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003884/0.05706, allocations: 1.678 MB / 442.7 MB, free: 15.65 MB / 426.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001922/0.05898, allocations: 1.724 MB / 444.5 MB, free: 13.88 MB / 426.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003597/0.05934, allocations: 0.9061 MB / 445.4 MB, free: 12.82 MB / 426.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002913/0.05963, allocations: 352.1 kB / 445.7 MB, free: 12.48 MB / 426.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002833/0.06246, allocations: 3.076 MB / 448.8 MB, free: 9.121 MB / 426.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.085e-05/0.0625, allocations: 7.938 kB / 448.8 MB, free: 9.113 MB / 426.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001614/0.06267, allocations: 140 kB / 448.9 MB, free: 8.977 MB / 426.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.663e-05/0.0627, allocations: 80 kB / 449 MB, free: 8.898 MB / 426.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001395/0.0641, allocations: 1.034 MB / 450 MB, free: 7.836 MB / 426.7 MB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.008405/0.0725, allocations: 8.517 MB / 458.6 MB, free: 15.12 MB / 442.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001855/0.07436, allocations: 1.48 MB / 460 MB, free: 13.64 MB / 442.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0005172/0.07487, allocations: 447.3 kB / 460.5 MB, free: 13.2 MB / 442.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001736/0.07505, allocations: 63.2 kB / 460.5 MB, free: 13.14 MB / 442.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0009879/0.07604, allocations: 1.119 MB / 461.6 MB, free: 11.94 MB / 442.7 MB
Notification: Performance of pre-optimization done (n=274): time 3.576e-06/0.07604, allocations: 0 / 461.6 MB, free: 11.94 MB / 442.7 MB
Notification: Performance of matching and sorting (n=274): time 0.004155/0.08019, allocations: 2.245 MB / 463.9 MB, free: 9.691 MB / 442.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001541/0.08035, allocations: 0.5508 MB / 464.4 MB, free: 9.02 MB / 442.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001588/0.08194, allocations: 2.33 MB / 466.8 MB, free: 6.68 MB / 442.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001255/0.08206, allocations: 93.69 kB / 466.9 MB, free: 6.582 MB / 442.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0005689/0.08263, allocations: 1.249 MB / 468.1 MB, free: 5.316 MB / 442.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004849/0.08312, allocations: 0.9878 MB / 469.1 MB, free: 4.32 MB / 442.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009951/0.08411, allocations: 0.8266 MB / 469.9 MB, free: 3.48 MB / 442.7 MB
Notification: Performance of setup shared object (initialization): time 6.157e-05/0.08417, allocations: 301.1 kB / 470.2 MB, free: 3.184 MB / 442.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001118/0.08529, allocations: 0.8195 MB / 471 MB, free: 2.336 MB / 442.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001497/0.08679, allocations: 1.851 MB / 472.9 MB, free: 160 kB / 442.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.00367/0.09046, allocations: 3.622 MB / 476.5 MB, free: 12.27 MB / 458.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.905e-06/0.09047, allocations: 0 / 476.5 MB, free: 12.27 MB / 458.7 MB
Notification: Performance of matching and sorting (n=575) (initialization): time 0.004107/0.09457, allocations: 3.097 MB / 479.6 MB, free: 9.102 MB / 458.7 MB
Notification: Performance of prepare postOptimizeDAE: time 5.27e-05/0.09462, allocations: 77.64 kB / 479.7 MB, free: 9.02 MB / 458.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.385e-05/0.09464, allocations: 16 kB / 479.7 MB, free: 9.004 MB / 458.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0007478/0.09539, allocations: 472.2 kB / 480.2 MB, free: 8.543 MB / 458.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001947/0.09733, allocations: 0.9479 MB / 481.1 MB, free: 7.594 MB / 458.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002417/0.09975, allocations: 2.22 MB / 483.3 MB, free: 5.289 MB / 458.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00114/0.1009, allocations: 393.7 kB / 483.7 MB, free: 4.902 MB / 458.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004128/0.1013, allocations: 120 kB / 483.8 MB, free: 4.785 MB / 458.7 MB
Warning: Assuming fixed start value for the following 36 variables:
         PV.injector.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = PV.injector.State0 fixed = true )  \"Injector connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         PV.injector.running:DISCRETE(flow=false start = PV.injector.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         PV.HvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = PV.HvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         PV.LvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = PV.LvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         PV.source.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = PV.source.State0 fixed = true )  \"Line connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         line.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = line.State0 fixed = true )  \"Line connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         PV.s0Pu.re:VARIABLE(unit = \"1\" fixed = true )  \"Real part of complex number\" type: Real
         PV.s0Pu.im:VARIABLE(unit = \"1\" fixed = true )  \"Imaginary part of complex number\" type: Real
         line.running:DISCRETE(flow=false start = line.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         PV.wecc_regc.rateLimFirstOrderFreeze2.y:VARIABLE(flow=false start = PV.wecc_regc.rateLimFirstOrderFreeze2.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_regc.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = PV.wecc_regc.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_regc.firstOrder.y:VARIABLE(flow=false start = PV.wecc_regc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_regc.offDelay.delaySignal:DISCRETE(start = false fixed = true protected = true )  type: Boolean
         PV.wecc_regc.offDelay.tNext:DISCRETE(start = -1.0 unit = \"s\" fixed = true protected = true )  type: Real
         PV.wecc_regc.vSourceRef.firstOrder.y:VARIABLE(flow=false start = PV.wecc_regc.vSourceRef.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_regc.vSourceRef.firstOrder1.y:VARIABLE(flow=false start = PV.wecc_regc.vSourceRef.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.source.running:DISCRETE(flow=false start = PV.source.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         PV.LvTfo.running:DISCRETE(flow=false start = PV.LvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         PV.HvTfo.running:DISCRETE(flow=false start = PV.HvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         PV.wecc_repc.firstOrder.y:VARIABLE(flow=false start = PV.wecc_repc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_repc.firstOrder1.y:VARIABLE(flow=false start = PV.wecc_repc.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_repc.firstOrder2.y:VARIABLE(flow=false start = PV.wecc_repc.firstOrder2.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_repc.firstOrder3.y:VARIABLE(flow=false start = PV.wecc_repc.firstOrder3.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_repc.limPIDFreeze.I.y:VARIABLE(flow=false start = PV.wecc_repc.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_repc.leadLag.x_scaled[1]:VARIABLE(start = PV.wecc_repc.leadLag.X0[1] * PV.wecc_repc.leadLag.a_end fixed = true protected = true )  \"Scaled vector x\" type: Real [1]
         PV.wecc_reec.firstOrder.y:VARIABLE(flow=false start = PV.wecc_reec.UConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.firstOrder1.y:VARIABLE(flow=false start = PV.wecc_reec.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.limPIDFreeze.I.y:VARIABLE(flow=false start = PV.wecc_reec.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = PV.wecc_reec.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.rateLimFirstOrderFreeze.y:VARIABLE(flow=false start = PV.wecc_reec.rateLimFirstOrderFreeze.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.varLimPIDFreeze.I.y:VARIABLE(flow=false start = PV.wecc_reec.varLimPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.firstOrder2.y:VARIABLE(flow=false start = PV.wecc_reec.firstOrder2.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.firstOrder3.y:VARIABLE(flow=false start = PV.wecc_reec.firstOrder3.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.injector.i0Pu.re:VARIABLE(unit = \"1\" fixed = true )  \"Real part of complex number\" type: Real
         PV.injector.i0Pu.im:VARIABLE(unit = \"1\" fixed = true )  \"Imaginary part of complex number\" type: Real
         PV.wecc_regc.offDelay.u:DISCRETE(flow=false fixed = true )  \"Boolean input signal\" type: Boolean
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001655/0.103, allocations: 1.13 MB / 485 MB, free: 3.652 MB / 458.7 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001518/0.1045, allocations: 1.854 MB / 486.8 MB, free: 1.465 MB / 458.7 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.003662/0.1081, allocations: 3.58 MB / 490.4 MB, free: 13.63 MB / 474.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 8.986e-06/0.1081, allocations: 8 kB / 490.4 MB, free: 13.62 MB / 474.7 MB
Notification: Performance of matching and sorting (n=575) (initialization_lambda0): time 0.004145/0.1123, allocations: 3.097 MB / 493.5 MB, free: 10.45 MB / 474.7 MB
Notification: Performance of prepare postOptimizeDAE: time 4.652e-05/0.1123, allocations: 69.66 kB / 493.6 MB, free: 10.38 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.248e-05/0.1124, allocations: 12 kB / 493.6 MB, free: 10.36 MB / 474.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0007163/0.1131, allocations: 472.3 kB / 494 MB, free: 9.898 MB / 474.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001871/0.1149, allocations: 0.9323 MB / 495 MB, free: 8.965 MB / 474.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002368/0.1173, allocations: 2.225 MB / 497.2 MB, free: 6.66 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001144/0.1184, allocations: 366.1 kB / 497.6 MB, free: 6.301 MB / 474.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004115/0.1189, allocations: 120 kB / 497.7 MB, free: 6.184 MB / 474.7 MB
Warning: Assuming fixed start value for the following 36 variables:
         PV.injector.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = PV.injector.State0 fixed = true )  \"Injector connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         PV.injector.running:DISCRETE(flow=false start = PV.injector.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         PV.HvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = PV.HvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         PV.LvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = PV.LvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         PV.source.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = PV.source.State0 fixed = true )  \"Line connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         line.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = line.State0 fixed = true )  \"Line connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         PV.s0Pu.re:VARIABLE(unit = \"1\" fixed = true )  \"Real part of complex number\" type: Real
         PV.s0Pu.im:VARIABLE(unit = \"1\" fixed = true )  \"Imaginary part of complex number\" type: Real
         line.running:DISCRETE(flow=false start = line.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         PV.wecc_regc.rateLimFirstOrderFreeze2.y:VARIABLE(flow=false start = PV.wecc_regc.rateLimFirstOrderFreeze2.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_regc.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = PV.wecc_regc.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_regc.firstOrder.y:VARIABLE(flow=false start = PV.wecc_regc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_regc.offDelay.delaySignal:DISCRETE(start = false fixed = true protected = true )  type: Boolean
         PV.wecc_regc.offDelay.tNext:DISCRETE(start = -1.0 unit = \"s\" fixed = true protected = true )  type: Real
         PV.wecc_regc.vSourceRef.firstOrder.y:VARIABLE(flow=false start = PV.wecc_regc.vSourceRef.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_regc.vSourceRef.firstOrder1.y:VARIABLE(flow=false start = PV.wecc_regc.vSourceRef.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.source.running:DISCRETE(flow=false start = PV.source.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         PV.LvTfo.running:DISCRETE(flow=false start = PV.LvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         PV.HvTfo.running:DISCRETE(flow=false start = PV.HvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         PV.wecc_repc.firstOrder.y:VARIABLE(flow=false start = PV.wecc_repc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_repc.firstOrder1.y:VARIABLE(flow=false start = PV.wecc_repc.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_repc.firstOrder2.y:VARIABLE(flow=false start = PV.wecc_repc.firstOrder2.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_repc.firstOrder3.y:VARIABLE(flow=false start = PV.wecc_repc.firstOrder3.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_repc.limPIDFreeze.I.y:VARIABLE(flow=false start = PV.wecc_repc.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_repc.leadLag.x_scaled[1]:VARIABLE(start = PV.wecc_repc.leadLag.X0[1] * PV.wecc_repc.leadLag.a_end fixed = true protected = true )  \"Scaled vector x\" type: Real [1]
         PV.wecc_reec.firstOrder.y:VARIABLE(flow=false start = PV.wecc_reec.UConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.firstOrder1.y:VARIABLE(flow=false start = PV.wecc_reec.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.limPIDFreeze.I.y:VARIABLE(flow=false start = PV.wecc_reec.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = PV.wecc_reec.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.rateLimFirstOrderFreeze.y:VARIABLE(flow=false start = PV.wecc_reec.rateLimFirstOrderFreeze.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.varLimPIDFreeze.I.y:VARIABLE(flow=false start = PV.wecc_reec.varLimPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.firstOrder2.y:VARIABLE(flow=false start = PV.wecc_reec.firstOrder2.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.wecc_reec.firstOrder3.y:VARIABLE(flow=false start = PV.wecc_reec.firstOrder3.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         PV.injector.i0Pu.re:VARIABLE(unit = \"1\" fixed = true )  \"Real part of complex number\" type: Real
         PV.injector.i0Pu.im:VARIABLE(unit = \"1\" fixed = true )  \"Imaginary part of complex number\" type: Real
         PV.wecc_regc.offDelay.u:DISCRETE(flow=false fixed = true )  \"Boolean input signal\" type: Boolean
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 50
 * Number of states: 0 ()
 * Number of discrete variables: 64 ($PRE.PV.injector.state,PV.injector.state,$PRE.PV.injector.running,PV.injector.running,$whenCondition7,$whenCondition6,$whenCondition8,$PRE.PV.HvTfo.state,PV.HvTfo.state,$PRE.PV.LvTfo.state,PV.LvTfo.state,$PRE.PV.source.state,PV.source.state,$PRE.line.state,line.state,PV.wecc_regc.pIAntiWindupVariableLimits.integrator.local_reset,PV.wecc_regc.pIAntiWindupVariableLimits1.integrator.local_reset,PV.pll.integrator.local_reset,PV.pll.limIntegrator.local_reset,PV.wecc_repc.limPID.I.local_reset,PV.wecc_repc.limPIDFreeze.I.resetLocal,PV.wecc_reec.limPIDFreeze.I.resetLocal,PV.wecc_reec.varLimPIDFreeze.I.resetLocal,PV.wecc_regc.rateLimFirstOrderFreeze2.local_freeze,PV.wecc_regc.rateLimFirstOrderFreeze1.local_freeze,line.switchOffSignal1,line.switchOffSignal2,PV.injector.switchOffSignal1,PV.injector.switchOffSignal2,PV.injector.switchOffSignal3,$PRE.line.running,$PRE.PV.wecc_regc.offDelay.delaySignal,$PRE.PV.wecc_regc.offDelay.tNext,$PRE.PV.source.running,$PRE.PV.LvTfo.running,$PRE.PV.HvTfo.running,PV.wecc_regc.QConv0PuPos.k,PV.wecc_regc.QConv0PuNeg.k,$PRE.PV.wecc_regc.offDelay.u,PV.wecc_repc.freeze,PV.HvTfo.running,PV.LvTfo.running,PV.source.running,PV.wecc_regc.and2.y,PV.wecc_regc.and1.y,PV.wecc_regc.and1.u[2],PV.wecc_regc.offDelay.tNext,PV.wecc_regc.offDelay.delaySignal,PV.wecc_regc.offDelay.u,line.running,$whenCondition2,$whenCondition1,$whenCondition3,$whenCondition5,$whenCondition4,$whenCondition10,$whenCondition9,$whenCondition11,$whenCondition13,$whenCondition12,$whenCondition14,$whenCondition16,$whenCondition15,$whenCondition17)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (528):
 * Single equations (assignments): 526
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(12,8,44.4%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0006491/0.1195, allocations: 0.5727 MB / 498.3 MB, free: 5.621 MB / 474.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003586/0.1199, allocations: 303.7 kB / 498.5 MB, free: 5.324 MB / 474.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002453/0.1223, allocations: 1.759 MB / 0.4886 GB, free: 3.543 MB / 474.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.537e-05/0.1223, allocations: 28 kB / 0.4886 GB, free: 3.516 MB / 474.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.231e-06/0.1223, allocations: 4 kB / 0.4886 GB, free: 3.512 MB / 474.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.808e-05/0.1224, allocations: 11.95 kB / 0.4886 GB, free: 3.5 MB / 474.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.00514/0.1275, allocations: 4.117 MB / 0.4926 GB, free: 15.27 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.284e-06/0.1275, allocations: 8 kB / 0.4927 GB, free: 15.26 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001686/0.1292, allocations: 0.5346 MB / 0.4932 GB, free: 14.72 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0007027/0.1299, allocations: 455.9 kB / 0.4936 GB, free: 14.28 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001183/0.13, allocations: 67.97 kB / 0.4937 GB, free: 14.21 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002327/0.1323, allocations: 2.21 MB / 0.4958 GB, free: 11.92 MB / 490.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.36e-06/0.1323, allocations: 11.88 kB / 0.4958 GB, free: 11.91 MB / 490.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003175/0.1355, allocations: 2.701 MB / 0.4985 GB, free: 9.016 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.000939/0.1365, allocations: 397 kB / 0.4989 GB, free: 8.625 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003106/0.1368, allocations: 75.98 kB / 0.4989 GB, free: 8.551 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001203/0.138, allocations: 151.8 kB / 0.4991 GB, free: 8.402 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003864/0.1384, allocations: 221.4 kB / 0.4993 GB, free: 8.188 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001501/0.1385, allocations: 75.91 kB / 0.4994 GB, free: 8.113 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.001685/0.1402, allocations: 1.196 MB / 0.5005 GB, free: 6.918 MB / 490.7 MB
Notification: Performance of sort global known variables: time 1.01e-07/0.1402, allocations: 0 / 0.5005 GB, free: 6.918 MB / 490.7 MB
Notification: Performance of remove unused functions: time 0.003574/0.1438, allocations: 1.867 MB / 0.5024 GB, free: 5.047 MB / 490.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 28
 * Number of states: 25 (PV.wecc_regc.rateLimFirstOrderFreeze2.y,PV.wecc_regc.rateLimFirstOrderFreeze1.y,PV.wecc_regc.firstOrder.y,PV.wecc_regc.vSourceRef.firstOrder.y,PV.wecc_regc.vSourceRef.firstOrder1.y,PV.wecc_regc.pIAntiWindupVariableLimits.integrator.y,PV.wecc_regc.pIAntiWindupVariableLimits1.integrator.y,PV.pll.integrator.y,PV.pll.limIntegrator.y,PV.wecc_repc.firstOrder.y,PV.wecc_repc.limPID.I.y,PV.wecc_repc.firstOrder1.y,PV.wecc_repc.firstOrder2.y,PV.wecc_repc.firstOrder3.y,PV.wecc_repc.limPIDFreeze.I.y,PV.wecc_repc.leadLag.x_scaled[1],PV.wecc_reec.firstOrder.y,PV.wecc_reec.firstOrder1.y,PV.wecc_reec.limPIDFreeze.I.y,PV.wecc_reec.rateLimFirstOrderFreeze1.y,PV.wecc_reec.slewRateLimiter.y,PV.wecc_reec.rateLimFirstOrderFreeze.y,PV.wecc_reec.varLimPIDFreeze.I.y,PV.wecc_reec.firstOrder2.y,PV.wecc_reec.firstOrder3.y)
 * Number of discrete variables: 34 ($whenCondition17,$whenCondition15,$whenCondition16,$whenCondition14,$whenCondition12,$whenCondition13,$whenCondition11,$whenCondition9,$whenCondition10,$whenCondition4,$whenCondition5,$whenCondition3,$whenCondition1,$whenCondition2,line.running,line.state,PV.wecc_regc.offDelay.u,PV.wecc_regc.offDelay.delaySignal,PV.wecc_regc.offDelay.tNext,PV.wecc_regc.and1.u[2],PV.wecc_regc.and1.y,PV.wecc_regc.and2.y,PV.source.running,PV.source.state,PV.LvTfo.running,PV.LvTfo.state,PV.HvTfo.running,PV.HvTfo.state,PV.wecc_repc.freeze,$whenCondition8,$whenCondition6,$whenCondition7,PV.injector.running,PV.injector.state)
 * Number of discrete states: 6 (PV.injector.running,PV.HvTfo.running,PV.LvTfo.running,PV.source.running,line.running,PV.wecc_regc.offDelay.u)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (267):
 * Single equations (assignments): 254
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 12
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(12,8,44.4%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0004716/0.1442, allocations: 254.3 kB / 0.5026 GB, free: 4.793 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.007852/0.1521, allocations: 4.532 MB / 0.507 GB, free: 172 kB / 490.7 MB
Notification: Performance of simCode: created event and clocks part: time 3.941e-05/0.1521, allocations: 36.5 kB / 0.5071 GB, free: 136 kB / 490.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.001774/0.1539, allocations: 1.067 MB / 0.5081 GB, free: 15.05 MB / 0.4948 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00198/0.1559, allocations: 0.6227 MB / 0.5087 GB, free: 14.46 MB / 0.4948 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00997/0.1659, allocations: 6.389 MB / 0.5149 GB, free: 7.914 MB / 0.4948 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005927/0.1664, allocations: 0.6003 MB / 0.5155 GB, free: 7.301 MB / 0.4948 GB
Notification: Performance of simCode: alias equations: time 0.001978/0.1684, allocations: 0.4984 MB / 0.516 GB, free: 6.801 MB / 0.4948 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001529/0.17, allocations: 0.6142 MB / 0.5166 GB, free: 6.184 MB / 0.4948 GB
Notification: Performance of SimCode: time 1.193e-06/0.17, allocations: 0 / 0.5166 GB, free: 6.184 MB / 0.4948 GB
Notification: Performance of Templates: time 0.2613/0.4313, allocations: 55.16 MB / 0.5705 GB, free: 131.4 MB / 0.5105 GB
"
[Timeout remaining time 660]
make -j1 -f Dynawo_Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4.makefile [Timeout 660]
(rm -f Dynawo_Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4.pipe ; mkfifo Dynawo_Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4.pipe ; head -c 1048576 < Dynawo_Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4.pipe >> ../files/Dynawo_Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4.sim & ./Dynawo_Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4  -lv=LOG_STDOUT,LOG_ASSERT,LOG_STATS -s=ida -maxIntegrationOrder=2 -nls=kinsol -initialStepSize=0.00001 -maxStepSize=10 -variableFilter=.* -abortSlowSimulation -alarm=240  -lv LOG_STATS > Dynawo_Dynawo.Examples.Photovoltaics.WECC.PVVoltageSource4.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 4.655312682501972]