Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.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.BalancedStepUp.DY,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.DY")
translateModel(IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.DY,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.DY")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.000832/0.0008322, allocations: 88 kB / 16.66 MB, free: 5.867 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.0009668/0.0009668, allocations: 178.8 kB / 17.58 MB, free: 5.543 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.367/1.367, allocations: 223.3 MB / 241.6 MB, free: 196 kB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.7264/0.7264, allocations: 115.5 MB / 408.2 MB, free: 7.105 MB / 318.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003666/0.0003666, allocations: 8 kB / 496.5 MB, free: 15.62 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4269/0.4273, allocations: 77.52 MB / 0.5606 GB, free: 36.27 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.DY): time 0.01826/0.4456, allocations: 17.45 MB / 0.5777 GB, free: 24.53 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01125/0.4569, allocations: 6.715 MB / 0.5842 GB, free: 19.98 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002365/0.4593, allocations: 43.69 kB / 0.5843 GB, free: 19.97 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001325/0.4606, allocations: 388.6 kB / 0.5846 GB, free: 19.77 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004173/0.4648, allocations: 1.339 MB / 0.5859 GB, free: 19.05 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003016/0.4679, allocations: 1.005 MB / 0.5869 GB, free: 18.49 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.009899/0.4778, allocations: 8.765 MB / 0.5955 GB, free: 14.6 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01231/0.4901, allocations: 8.867 MB / 0.6041 GB, free: 6.691 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.00327/0.4934, allocations: 1.701 MB / 0.6058 GB, free: 4.984 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004316/0.4978, allocations: 2.73 MB / 0.6085 GB, free: 2.246 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001111/0.4989, allocations: 244 kB / 0.6087 GB, free: 2.008 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003266/0.5022, allocations: 1.15 MB / 0.6098 GB, free: 0.8555 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003374/0.5056, allocations: 1.945 MB / 0.6117 GB, free: 14.9 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.004811/0.5104, allocations: 1.844 MB / 0.6135 GB, free: 13.04 MB / 462.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.008355/0.5188, allocations: 6.734 MB / 0.6201 GB, free: 6.293 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.833e-06/0.5188, allocations: 0 / 0.6201 GB, free: 6.293 MB / 462.1 MB
Notification: Performance of FrontEnd: time 3.747e-06/0.5188, allocations: 0 / 0.6201 GB, free: 6.293 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 0.0002373/0.5191, allocations: 0 / 0.6201 GB, free: 6.293 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: 1023
 * Number of variables: 1023
Notification: Performance of Generate backend data structure: time 0.008472/0.5276, allocations: 3.149 MB / 0.6232 GB, free: 3.082 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.026e-05/0.5276, allocations: 15.97 kB / 0.6232 GB, free: 3.066 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01311/0.5408, allocations: 2.352 MB / 0.6255 GB, free: 0.707 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005016/0.5458, allocations: 2.21 MB / 0.6276 GB, free: 14.45 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002572/0.5461, allocations: 275.7 kB / 0.6279 GB, free: 14.18 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001305/0.5474, allocations: 359.9 kB / 0.6282 GB, free: 13.83 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01678/0.5642, allocations: 7.33 MB / 0.6354 GB, free: 6.375 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 8.214e-05/0.5643, allocations: 7.938 kB / 0.6354 GB, free: 6.367 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000512/0.5649, allocations: 156 kB / 0.6356 GB, free: 6.215 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0004988/0.5654, allocations: 334.3 kB / 0.6359 GB, free: 5.887 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0181/0.5835, allocations: 7.13 MB / 0.6428 GB, free: 14.74 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.04019/0.6237, allocations: 28.2 MB / 0.6704 GB, free: 152 kB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.008107/0.6319, allocations: 4.175 MB / 0.6745 GB, free: 11.75 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005585/0.6375, allocations: 2.327 MB / 0.6767 GB, free: 9.414 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003229/0.6378, allocations: 139 kB / 0.6769 GB, free: 9.262 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.243e-05/0.6379, allocations: 56.56 kB / 0.6769 GB, free: 9.195 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=198): time 3.687e-06/0.6379, allocations: 0 / 0.6769 GB, free: 9.195 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=257): time 0.1089/0.7468, allocations: 30.43 MB / 0.7066 GB, free: 10.69 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001741/0.747, allocations: 235.6 kB / 0.7069 GB, free: 10.41 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002286/0.7493, allocations: 1.416 MB / 0.7082 GB, free: 8.988 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005398/0.7498, allocations: 65.7 kB / 0.7083 GB, free: 8.918 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001206/0.7511, allocations: 1.555 MB / 0.7098 GB, free: 7.34 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0009734/0.752, allocations: 0.8703 MB / 0.7107 GB, free: 6.457 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004317/0.7525, allocations: 248.2 kB / 0.7109 GB, free: 6.207 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 0.0001215/0.7526, allocations: 309.1 kB / 0.7112 GB, free: 5.902 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005839/0.7584, allocations: 2.543 MB / 0.7137 GB, free: 3.355 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006522/0.765, allocations: 3.439 MB / 0.717 GB, free: 15.53 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01293/0.7779, allocations: 6.133 MB / 0.723 GB, free: 8.992 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.683e-05/0.778, allocations: 12 kB / 0.723 GB, free: 8.98 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=398) (initialization): time 0.02913/0.8072, allocations: 10.27 MB / 0.7331 GB, free: 14.64 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0009044/0.8081, allocations: 0.8719 MB / 0.7339 GB, free: 13.39 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.646e-05/0.8082, allocations: 28 kB / 0.734 GB, free: 13.36 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02254/0.8307, allocations: 5.672 MB / 0.7395 GB, free: 7.676 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004438/0.8352, allocations: 0.9828 MB / 0.7405 GB, free: 6.691 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3681/1.203, allocations: 21.58 MB / 0.7615 GB, free: 146.9 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004688/1.208, allocations: 0.5516 MB / 0.7621 GB, free: 146.9 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004241/1.208, allocations: 148.1 kB / 0.7622 GB, free: 146.8 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 1.116e-05/1.209, allocations: 3.531 kB / 0.7622 GB, free: 146.8 MB / 0.5762 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.0224/1.231, allocations: 8.451 MB / 0.7705 GB, free: 144.6 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001058/1.231, allocations: 27.19 kB / 0.7705 GB, free: 144.6 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01427/1.245, allocations: 3.536 MB / 0.7739 GB, free: 144.2 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005595/1.251, allocations: 1.13 MB / 0.775 GB, free: 144.2 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01904/1.27, allocations: 11.79 MB / 0.7866 GB, free: 139.1 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004248/1.274, allocations: 0.5265 MB / 0.7871 GB, free: 138.9 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003636/1.275, allocations: 149.2 kB / 0.7872 GB, free: 138.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.002795/1.278, allocations: 1.617 MB / 0.7888 GB, free: 137.9 MB / 0.5762 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005765/1.278, allocations: 192.1 kB / 0.789 GB, free: 137.9 MB / 0.5762 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02366/1.302, allocations: 9.838 MB / 0.7986 GB, free: 130.7 MB / 0.5762 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.273e-05/1.302, allocations: 27.98 kB / 0.7986 GB, free: 130.7 MB / 0.5762 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.234e-05/1.302, allocations: 12 kB / 0.7986 GB, free: 130.7 MB / 0.5762 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.066e-05/1.302, allocations: 11.95 kB / 0.7986 GB, free: 130.7 MB / 0.5762 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.03067/1.333, allocations: 15.19 MB / 0.8135 GB, free: 115.4 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.943e-05/1.333, allocations: 15.94 kB / 0.8135 GB, free: 115.4 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003161/1.336, allocations: 0.7338 MB / 0.8142 GB, free: 114.6 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01873/1.355, allocations: 5.34 MB / 0.8194 GB, free: 109.3 MB / 0.5762 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0005571/1.355, allocations: 79.95 kB / 0.8195 GB, free: 109.2 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02901/1.384, allocations: 20.43 MB / 0.8394 GB, free: 88.51 MB / 0.5762 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 9.147e-06/1.384, allocations: 15.06 kB / 0.8395 GB, free: 88.5 MB / 0.5762 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02238/1.407, allocations: 9.377 MB / 0.8486 GB, free: 78.93 MB / 0.5762 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001558/1.408, allocations: 0.6928 MB / 0.8493 GB, free: 78.22 MB / 0.5762 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003351/1.409, allocations: 59.92 kB / 0.8493 GB, free: 78.16 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003238/1.412, allocations: 0.5031 MB / 0.8498 GB, free: 77.66 MB / 0.5762 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0007226/1.413, allocations: 222.3 kB / 0.85 GB, free: 77.44 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001843/1.413, allocations: 91.95 kB / 0.8501 GB, free: 77.35 MB / 0.5762 GB
Notification: Performance of sorting global known variables: time 0.001162/1.414, allocations: 0.9964 MB / 0.8511 GB, free: 76.35 MB / 0.5762 GB
Notification: Performance of sort global known variables: time 1.41e-07/1.414, allocations: 0 / 0.8511 GB, free: 76.35 MB / 0.5762 GB
Notification: Performance of remove unused functions: time 0.005305/1.419, allocations: 1.754 MB / 0.8528 GB, free: 74.6 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.0003719/1.42, allocations: 181.5 kB / 0.853 GB, free: 74.41 MB / 0.5762 GB
Notification: Performance of simCode: created initialization part: time 0.02512/1.445, allocations: 11.33 MB / 0.8641 GB, free: 63.04 MB / 0.5762 GB
Notification: Performance of simCode: created event and clocks part: time 1.391e-05/1.445, allocations: 0 / 0.8641 GB, free: 63.04 MB / 0.5762 GB
Notification: Performance of simCode: created simulation system equations: time 0.00992/1.455, allocations: 4.135 MB / 0.8681 GB, free: 58.88 MB / 0.5762 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003487/1.458, allocations: 263.7 kB / 0.8683 GB, free: 58.62 MB / 0.5762 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02828/1.487, allocations: 12.96 MB / 0.881 GB, free: 45.51 MB / 0.5762 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002572/1.489, allocations: 2.939 MB / 0.8839 GB, free: 42.54 MB / 0.5762 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001163/1.49, allocations: 207.3 kB / 0.8841 GB, free: 42.34 MB / 0.5762 GB
Notification: Performance of SimCode: time 9.92e-07/1.49, allocations: 4 kB / 0.8841 GB, free: 42.33 MB / 0.5762 GB
Notification: Performance of Templates: time 0.1082/1.599, allocations: 88.62 MB / 0.9706 GB, free: 1.73 MB / 0.6231 GB
make -j1 -f IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.DY.makefile
(rm -f IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.DY.pipe ; mkfifo IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.DY.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.DY.pipe >> ../files/IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.DY.sim & ./IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.DY  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.DY.pipe 2>&1)