Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY.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.0008739/0.0008739, allocations: 80.44 kB / 19.91 MB, free: 4 kB / 13.93 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.001042/0.001042, allocations: 169.2 kB / 23.19 MB, free: 1.371 MB / 18.57 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 0.9081/0.9081, allocations: 177.2 MB / 203.6 MB, free: 5.75 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-531:686:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-531:694:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.order:0:0-0:0:readonly] Warning: Found duplicate names in package.order file: A, B, C.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.mo:2:1-70:10:writable] Warning: C was referenced in the package.order file, but was not found in package.mo, C/package.mo or C.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo): time 0.7076/0.7076, allocations: 154.7 MB / 414.7 MB, free: 0.75 MB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 179]
Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo)
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(IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY")
translateModel(IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY") [Timeout 660]
"Notification: IDEAS requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.0009723/0.0009723, allocations: 75.89 kB / 0.6197 GB, free: 12.95 MB / 0.5886 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function.
Notification: Performance of FrontEnd - Absyn->SCode: time 0.524/0.525, allocations: 101.9 MB / 0.7192 GB, free: 23.62 MB / 0.6355 GB
Notification: Performance of NFInst.instantiate(IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY): time 0.01631/0.5413, allocations: 19.02 MB / 0.7378 GB, free: 12.04 MB / 0.6355 GB
Notification: Performance of NFInst.instExpressions: time 0.009877/0.5512, allocations: 7.033 MB / 0.7447 GB, free: 7.516 MB / 0.6355 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001811/0.553, allocations: 71.75 kB / 0.7447 GB, free: 7.496 MB / 0.6355 GB
Notification: Performance of NFTyping.typeComponents: time 0.00286/0.5558, allocations: 1.905 MB / 0.7466 GB, free: 6.453 MB / 0.6355 GB
Notification: Performance of NFTyping.typeBindings: time 0.003588/0.5594, allocations: 1.551 MB / 0.7481 GB, free: 5.57 MB / 0.6355 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003012/0.5624, allocations: 1.315 MB / 0.7494 GB, free: 4.859 MB / 0.6355 GB
Notification: Performance of NFFlatten.flatten: time 0.007374/0.5698, allocations: 5.884 MB / 0.7551 GB, free: 2.359 MB / 0.6355 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.005662/0.5755, allocations: 3.921 MB / 0.759 GB, free: 352 kB / 0.6355 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005909/0.5814, allocations: 3.713 MB / 0.7626 GB, free: 13.92 MB / 0.6511 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.005024/0.5864, allocations: 3.259 MB / 0.7658 GB, free: 11.99 MB / 0.6511 GB
Notification: Performance of NFPackage.collectConstants: time 0.001525/0.5879, allocations: 0.5744 MB / 0.7663 GB, free: 11.99 MB / 0.6511 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002835/0.5908, allocations: 1.246 MB / 0.7676 GB, free: 11.61 MB / 0.6511 GB
Notification: Performance of NFScalarize.scalarize: time 0.0028/0.5936, allocations: 2.522 MB / 0.77 GB, free: 10.01 MB / 0.6511 GB
Notification: Performance of NFVerifyModel.verify: time 0.003659/0.5972, allocations: 1.952 MB / 0.7719 GB, free: 8.754 MB / 0.6511 GB
Notification: Performance of NFConvertDAE.convert: time 0.009393/0.6066, allocations: 9.043 MB / 0.7808 GB, free: 2.191 MB / 0.6511 GB
Notification: Performance of FrontEnd - DAE generated: time 6.131e-06/0.6066, allocations: 0 / 0.7808 GB, free: 2.191 MB / 0.6511 GB
Notification: Performance of FrontEnd: time 2.314e-06/0.6066, allocations: 0 / 0.7808 GB, free: 2.191 MB / 0.6511 GB
Notification: Performance of Transformations before backend: time 0.0002795/0.6069, allocations: 0 / 0.7808 GB, free: 2.191 MB / 0.6511 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1094
 * Number of variables: 1094
Notification: Performance of Generate backend data structure: time 0.011/0.6179, allocations: 4.112 MB / 0.7848 GB, free: 14.64 MB / 0.6667 GB
Notification: Performance of prepare preOptimizeDAE: time 5.538e-05/0.618, allocations: 12.03 kB / 0.7848 GB, free: 14.63 MB / 0.6667 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.007052/0.625, allocations: 2.448 MB / 0.7872 GB, free: 12.18 MB / 0.6667 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006064/0.6311, allocations: 2.311 MB / 0.7894 GB, free: 9.84 MB / 0.6667 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001734/0.6312, allocations: 304.3 kB / 0.7897 GB, free: 9.543 MB / 0.6667 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001014/0.6323, allocations: 391.8 kB / 0.7901 GB, free: 9.16 MB / 0.6667 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01084/0.6431, allocations: 7.77 MB / 0.7977 GB, free: 1.254 MB / 0.6667 GB
Notification: Performance of preOpt findStateOrder (simulation): time 6.332e-05/0.6432, allocations: 3.938 kB / 0.7977 GB, free: 1.25 MB / 0.6667 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003399/0.6435, allocations: 164 kB / 0.7978 GB, free: 1.09 MB / 0.6667 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003544/0.6438, allocations: 342.8 kB / 0.7982 GB, free: 0.7539 MB / 0.6667 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01107/0.6549, allocations: 7.301 MB / 0.8053 GB, free: 9.441 MB / 0.6823 GB
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.02522/0.6801, allocations: 19.18 MB / 0.824 GB, free: 5.594 MB / 0.698 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004167/0.6843, allocations: 3.129 MB / 0.8271 GB, free: 2.418 MB / 0.698 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002615/0.6869, allocations: 1.952 MB / 0.829 GB, free: 456 kB / 0.698 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001364/0.6871, allocations: 45.22 kB / 0.829 GB, free: 412 kB / 0.698 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.684e-05/0.6871, allocations: 63.73 kB / 0.8291 GB, free: 336 kB / 0.698 GB
Notification: Performance of pre-optimization done (n=176): time 4.568e-06/0.6871, allocations: 0 / 0.8291 GB, free: 336 kB / 0.698 GB
Notification: Performance of matching and sorting (n=221): time 0.04373/0.7308, allocations: 25.01 MB / 0.8535 GB, free: 7.285 MB / 0.7292 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.217e-05/0.7309, allocations: 235.6 kB / 0.8537 GB, free: 7.004 MB / 0.7292 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001823/0.7327, allocations: 1.635 MB / 0.8553 GB, free: 5.352 MB / 0.7292 GB
Notification: Performance of collectPreVariables (initialization): time 0.0004194/0.7332, allocations: 61.7 kB / 0.8554 GB, free: 5.285 MB / 0.7292 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0008111/0.734, allocations: 1.817 MB / 0.8572 GB, free: 3.449 MB / 0.7292 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006187/0.7346, allocations: 0.798 MB / 0.8579 GB, free: 2.645 MB / 0.7292 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007637/0.7354, allocations: 0.6434 MB / 0.8586 GB, free: 1.992 MB / 0.7292 GB
Notification: Performance of setup shared object (initialization): time 8.497e-05/0.7354, allocations: 301.1 kB / 0.8589 GB, free: 1.695 MB / 0.7292 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003572/0.739, allocations: 2.494 MB / 0.8613 GB, free: 15.19 MB / 0.7448 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003865/0.7429, allocations: 3.668 MB / 0.8649 GB, free: 11.01 MB / 0.7448 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.007244/0.7501, allocations: 6.19 MB / 0.8709 GB, free: 4.328 MB / 0.7448 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.437e-05/0.7502, allocations: 8 kB / 0.8709 GB, free: 4.32 MB / 0.7448 GB
Notification: Performance of matching and sorting (n=400) (initialization): time 0.01503/0.7652, allocations: 9.909 MB / 0.8806 GB, free: 10.38 MB / 0.7605 GB
Notification: Performance of prepare postOptimizeDAE: time 4.64e-05/0.7652, allocations: 20 kB / 0.8806 GB, free: 10.36 MB / 0.7605 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.618e-05/0.7653, allocations: 47.91 kB / 0.8807 GB, free: 10.32 MB / 0.7605 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009957/0.7753, allocations: 5.134 MB / 0.8857 GB, free: 5.172 MB / 0.7605 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002825/0.7781, allocations: 1.041 MB / 0.8867 GB, free: 4.129 MB / 0.7605 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02228/0.8004, allocations: 20.5 MB / 0.9067 GB, free: 15.45 MB / 0.7917 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002918/0.8033, allocations: 0.6082 MB / 0.9073 GB, free: 14.84 MB / 0.7917 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000295/0.8036, allocations: 159.9 kB / 0.9075 GB, free: 14.68 MB / 0.7917 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.005533/0.8091, allocations: 3.905 MB / 0.9113 GB, free: 10.77 MB / 0.7917 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003785/0.8129, allocations: 3.629 MB / 0.9148 GB, free: 6.617 MB / 0.7917 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.00716/0.82, allocations: 6.12 MB / 0.9208 GB, free: 4 kB / 0.7917 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 4.008e-05/0.8201, allocations: 8.047 kB / 0.9208 GB, free: 15.99 MB / 0.8073 GB
Notification: Performance of matching and sorting (n=400) (initialization_lambda0): time 0.01303/0.8331, allocations: 8.427 MB / 0.929 GB, free: 7.551 MB / 0.8073 GB
Notification: Performance of prepare postOptimizeDAE: time 3.171e-05/0.8331, allocations: 23.94 kB / 0.9291 GB, free: 7.527 MB / 0.8073 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.378e-05/0.8332, allocations: 35.98 kB / 0.9291 GB, free: 7.492 MB / 0.8073 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006594/0.8398, allocations: 3.2 MB / 0.9322 GB, free: 4.285 MB / 0.8073 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003146/0.8429, allocations: 1.162 MB / 0.9334 GB, free: 3.121 MB / 0.8073 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01228/0.8552, allocations: 11.16 MB / 0.9443 GB, free: 7.852 MB / 0.823 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003094/0.8583, allocations: 0.5577 MB / 0.9448 GB, free: 7.293 MB / 0.823 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002503/0.8586, allocations: 175.9 kB / 0.945 GB, free: 7.121 MB / 0.823 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 159
 * 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 (330):
 * Single equations (assignments): 328
 * 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,30,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(6,30)}
Notification: Performance of prepare postOptimizeDAE: time 0.0006892/0.8593, allocations: 383.1 kB / 0.9453 GB, free: 6.738 MB / 0.823 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003618/0.8596, allocations: 194.8 kB / 0.9455 GB, free: 6.547 MB / 0.823 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01302/0.8726, allocations: 8.64 MB / 0.954 GB, free: 13.88 MB / 0.8386 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.467e-05/0.8727, allocations: 15.95 kB / 0.954 GB, free: 13.86 MB / 0.8386 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.639e-06/0.8727, allocations: 0 / 0.954 GB, free: 13.86 MB / 0.8386 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001106/0.8738, allocations: 0.5804 MB / 0.9545 GB, free: 13.28 MB / 0.8386 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000226/0.874, allocations: 43.94 kB / 0.9546 GB, free: 13.23 MB / 0.8386 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001691/0.8757, allocations: 499.2 kB / 0.9551 GB, free: 12.75 MB / 0.8386 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003746/0.8761, allocations: 241.4 kB / 0.9553 GB, free: 12.51 MB / 0.8386 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0118/0.8879, allocations: 8.207 MB / 0.9633 GB, free: 4.277 MB / 0.8386 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.009662/0.8975, allocations: 7.773 MB / 0.9709 GB, free: 12.47 MB / 0.8542 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003096/0.9006, allocations: 2.228 MB / 0.9731 GB, free: 10.24 MB / 0.8542 GB
Notification: Performance of sorting global known variables: time 0.001375/0.902, allocations: 1.057 MB / 0.9741 GB, free: 9.184 MB / 0.8542 GB
Notification: Performance of Backend: time 6.81e-07/0.902, allocations: 4 kB / 0.9741 GB, free: 9.18 MB / 0.8542 GB
Notification: Performance of simCode: created initialization part: time 0.01469/0.9167, allocations: 8.857 MB / 0.9827 GB, free: 292 kB / 0.8542 GB
Notification: Performance of SimCode: time 0.01035/0.9271, allocations: 7.303 MB / 0.9899 GB, free: 8.949 MB / 0.8698 GB
Notification: Performance of Templates: time 0.47/1.397, allocations: 86.48 MB / 1.074 GB, free: 232.3 MB / 0.8855 GB
"
[Timeout remaining time 659]
make -j1 -f IDEAS_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY.makefile [Timeout 660]
(rm -f IDEAS_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY.pipe ; mkfifo IDEAS_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY.pipe ; head -c 1048576 < IDEAS_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY.pipe >> ../files/IDEAS_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY.sim & ./IDEAS_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 6.310759614920244]
Failed to read output from testmodel.py, exit status != 0:
1.4036862060893327 1.40959554 0.801423444
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7460ebeb53d0>