Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo", uses=false)
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY")
translateModel(IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001072/0.001072, allocations: 92.2 kB / 16.66 MB, free: 5.742 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001256/0.001256, allocations: 179 kB / 17.58 MB, free: 5.477 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.351/1.351, allocations: 223.3 MB / 241.6 MB, free: 14.46 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.8433/0.8433, allocations: 115.5 MB / 408.2 MB, free: 7.102 MB / 318.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003603/0.0003601, allocations: 12 kB / 496.6 MB, free: 15.64 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3966/0.397, allocations: 77.52 MB / 0.5606 GB, free: 35.48 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY): time 0.01824/0.4153, allocations: 17.52 MB / 0.5777 GB, free: 23.7 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01209/0.4274, allocations: 6.781 MB / 0.5843 GB, free: 19.1 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002318/0.4298, allocations: 43.66 kB / 0.5844 GB, free: 19.09 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001615/0.4314, allocations: 394.3 kB / 0.5848 GB, free: 18.88 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004473/0.4359, allocations: 1.362 MB / 0.5861 GB, free: 18.16 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003271/0.4392, allocations: 1.006 MB / 0.5871 GB, free: 17.59 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01047/0.4497, allocations: 8.807 MB / 0.5957 GB, free: 13.94 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01169/0.4614, allocations: 8.895 MB / 0.6044 GB, free: 6.48 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003302/0.4648, allocations: 1.72 MB / 0.606 GB, free: 4.754 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003748/0.4685, allocations: 2.73 MB / 0.6087 GB, free: 2.016 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005078/0.4691, allocations: 244 kB / 0.6089 GB, free: 1.777 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002255/0.4713, allocations: 1.174 MB / 0.6101 GB, free: 0.6016 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001918/0.4732, allocations: 1.953 MB / 0.612 GB, free: 14.64 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002288/0.4755, allocations: 1.864 MB / 0.6138 GB, free: 12.76 MB / 462.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.007153/0.4827, allocations: 6.766 MB / 0.6204 GB, free: 5.98 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.338e-06/0.4827, allocations: 0 / 0.6204 GB, free: 5.98 MB / 462.1 MB
Notification: Performance of FrontEnd: time 2.033e-06/0.4827, allocations: 0 / 0.6204 GB, free: 5.98 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 0.0001888/0.4829, allocations: 0 / 0.6204 GB, free: 5.98 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1034
 * Number of variables: 1034
Notification: Performance of Generate backend data structure: time 0.007194/0.4901, allocations: 3.18 MB / 0.6235 GB, free: 2.738 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.828e-05/0.4902, allocations: 15.97 kB / 0.6235 GB, free: 2.723 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01249/0.5027, allocations: 2.372 MB / 0.6259 GB, free: 352 kB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004775/0.5075, allocations: 2.214 MB / 0.628 GB, free: 14.09 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002236/0.5078, allocations: 279.8 kB / 0.6283 GB, free: 13.81 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0009595/0.5087, allocations: 367.9 kB / 0.6286 GB, free: 13.45 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01568/0.5244, allocations: 7.363 MB / 0.6358 GB, free: 5.969 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 8.127e-05/0.5245, allocations: 7.922 kB / 0.6358 GB, free: 5.961 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004663/0.525, allocations: 160 kB / 0.636 GB, free: 5.805 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0004685/0.5255, allocations: 334.4 kB / 0.6363 GB, free: 5.477 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01676/0.5423, allocations: 7.142 MB / 0.6433 GB, free: 14.32 MB / 494.1 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.039/0.5813, allocations: 28.28 MB / 0.6709 GB, free: 15.64 MB / 0.5137 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.008394/0.5897, allocations: 4.174 MB / 0.675 GB, free: 11.25 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005794/0.5956, allocations: 2.323 MB / 0.6772 GB, free: 8.914 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003581/0.596, allocations: 134.9 kB / 0.6774 GB, free: 8.77 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.831e-05/0.596, allocations: 60.56 kB / 0.6774 GB, free: 8.699 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=198): time 5.09e-06/0.596, allocations: 0 / 0.6774 GB, free: 8.699 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=257): time 0.1124/0.7085, allocations: 30.48 MB / 0.7072 GB, free: 10.14 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.000179/0.7087, allocations: 235.6 kB / 0.7074 GB, free: 9.859 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00299/0.7117, allocations: 1.458 MB / 0.7088 GB, free: 8.395 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.0006396/0.7124, allocations: 69.7 kB / 0.7089 GB, free: 8.32 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001439/0.7138, allocations: 1.567 MB / 0.7104 GB, free: 6.73 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001305/0.7151, allocations: 0.8625 MB / 0.7113 GB, free: 5.855 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007584/0.7159, allocations: 256.2 kB / 0.7115 GB, free: 5.598 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 0.0001099/0.716, allocations: 309.1 kB / 0.7118 GB, free: 5.293 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00609/0.7221, allocations: 2.539 MB / 0.7143 GB, free: 2.75 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006841/0.729, allocations: 3.431 MB / 0.7177 GB, free: 14.93 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01324/0.7423, allocations: 6.133 MB / 0.7236 GB, free: 8.391 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.746e-05/0.7424, allocations: 7.938 kB / 0.7237 GB, free: 8.383 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=398) (initialization): time 0.02969/0.7721, allocations: 10.28 MB / 0.7337 GB, free: 14.04 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0008521/0.773, allocations: 0.8758 MB / 0.7345 GB, free: 12.79 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.733e-05/0.773, allocations: 31.94 kB / 0.7346 GB, free: 12.75 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02287/0.7959, allocations: 5.668 MB / 0.7401 GB, free: 7.07 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004535/0.8005, allocations: 0.9828 MB / 0.7411 GB, free: 6.086 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3507/1.151, allocations: 21.59 MB / 0.7622 GB, free: 145.8 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004483/1.156, allocations: 0.5507 MB / 0.7627 GB, free: 145.8 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004316/1.156, allocations: 148.5 kB / 0.7628 GB, free: 145.8 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 1.169e-05/1.156, allocations: 4.281 kB / 0.7628 GB, free: 145.8 MB / 0.5762 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.02233/1.179, allocations: 8.44 MB / 0.7711 GB, free: 143.5 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.595e-05/1.179, allocations: 27.5 kB / 0.7711 GB, free: 143.5 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01372/1.192, allocations: 3.54 MB / 0.7746 GB, free: 143.2 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005188/1.198, allocations: 1.133 MB / 0.7757 GB, free: 143.1 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01879/1.216, allocations: 11.8 MB / 0.7872 GB, free: 138.1 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004057/1.221, allocations: 0.5239 MB / 0.7877 GB, free: 138 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003475/1.221, allocations: 146.3 kB / 0.7878 GB, free: 137.9 MB / 0.5762 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 121
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (304):
 * Single equations (assignments): 302
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(6,42,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(6,42)}
Notification: Performance of prepare postOptimizeDAE: time 0.002616/1.224, allocations: 1.628 MB / 0.7894 GB, free: 136.9 MB / 0.5762 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000516/1.224, allocations: 196.3 kB / 0.7896 GB, free: 136.9 MB / 0.5762 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0236/1.248, allocations: 9.833 MB / 0.7992 GB, free: 130.2 MB / 0.5762 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.295e-05/1.248, allocations: 27.66 kB / 0.7992 GB, free: 130.2 MB / 0.5762 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.939e-05/1.248, allocations: 15.98 kB / 0.7993 GB, free: 130.2 MB / 0.5762 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.755e-05/1.248, allocations: 11.95 kB / 0.7993 GB, free: 130.2 MB / 0.5762 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.03055/1.278, allocations: 15.21 MB / 0.8141 GB, free: 114.9 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.56e-05/1.278, allocations: 15.94 kB / 0.8141 GB, free: 114.8 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003161/1.282, allocations: 0.7261 MB / 0.8149 GB, free: 114.1 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01901/1.301, allocations: 5.34 MB / 0.8201 GB, free: 108.8 MB / 0.5762 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0005081/1.301, allocations: 75.95 kB / 0.8201 GB, free: 108.7 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02967/1.331, allocations: 20.45 MB / 0.8401 GB, free: 87.97 MB / 0.5762 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.258e-05/1.331, allocations: 19.88 kB / 0.8401 GB, free: 87.95 MB / 0.5762 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02247/1.353, allocations: 9.381 MB / 0.8493 GB, free: 78.38 MB / 0.5762 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001565/1.355, allocations: 0.6898 MB / 0.85 GB, free: 77.67 MB / 0.5762 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004054/1.355, allocations: 59.92 kB / 0.85 GB, free: 77.61 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003319/1.359, allocations: 0.5031 MB / 0.8505 GB, free: 77.11 MB / 0.5762 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006727/1.359, allocations: 222.3 kB / 0.8507 GB, free: 76.89 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001891/1.36, allocations: 91.94 kB / 0.8508 GB, free: 76.8 MB / 0.5762 GB
Notification: Performance of sorting global known variables: time 0.001182/1.361, allocations: 1.029 MB / 0.8518 GB, free: 75.77 MB / 0.5762 GB
Notification: Performance of sort global known variables: time 1.1e-07/1.361, allocations: 0 / 0.8518 GB, free: 75.77 MB / 0.5762 GB
Notification: Performance of remove unused functions: time 0.005242/1.366, allocations: 1.766 MB / 0.8535 GB, free: 74 MB / 0.5762 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 40
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (162):
 * Single equations (assignments): 160
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(6,42,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(6,42)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0003869/1.366, allocations: 170.6 kB / 0.8537 GB, free: 73.83 MB / 0.5762 GB
Notification: Performance of simCode: created initialization part: time 0.02537/1.392, allocations: 11.33 MB / 0.8648 GB, free: 62.45 MB / 0.5762 GB
Notification: Performance of simCode: created event and clocks part: time 1.36e-05/1.392, allocations: 0 / 0.8648 GB, free: 62.45 MB / 0.5762 GB
Notification: Performance of simCode: created simulation system equations: time 0.01002/1.402, allocations: 4.136 MB / 0.8688 GB, free: 58.29 MB / 0.5762 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004904/1.407, allocations: 279.4 kB / 0.8691 GB, free: 58.02 MB / 0.5762 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02677/1.434, allocations: 12.11 MB / 0.8809 GB, free: 45.76 MB / 0.5762 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003076/1.437, allocations: 2.956 MB / 0.8838 GB, free: 42.77 MB / 0.5762 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001119/1.438, allocations: 211.3 kB / 0.884 GB, free: 42.56 MB / 0.5762 GB
Notification: Performance of SimCode: time 1.312e-06/1.438, allocations: 0 / 0.884 GB, free: 42.56 MB / 0.5762 GB
Notification: Performance of Templates: time 0.1187/1.557, allocations: 89.34 MB / 0.9712 GB, free: 1.254 MB / 0.6231 GB
make -j1 -f IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY.makefile
(rm -f IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY.pipe ; mkfifo IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY.pipe >> ../files/IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY.sim & ./IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.DY.pipe 2>&1)