Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine.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.0015/0.0015, allocations: 79.52 kB / 19.18 MB, free: 2.516 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001629/0.001629, allocations: 165.6 kB / 22.46 MB, free: 5.375 MB / 14.72 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 1.365/1.365, allocations: 177.2 MB / 202.8 MB, free: 9.172 MB / 190.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo): time 0.07604/0.07604, allocations: 12.52 MB / 271.7 MB, free: 4.59 MB / 254.1 MB
"
[Timeout remaining time 180]
Using package PowerGrids with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/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(PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine")
translateModel(PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.162e-06/1.162e-06, allocations: 0 / 402.8 MB, free: 14.89 MB / 334.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.539e-05/1.655e-05, allocations: 2.281 kB / 402.8 MB, free: 14.89 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine): time 0.03677/0.03678, allocations: 35.78 MB / 438.6 MB, free: 10.96 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01635/0.05313, allocations: 9.825 MB / 448.4 MB, free: 1.102 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002791/0.05592, allocations: 111.2 kB / 448.5 MB, free: 0.9922 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.1514/0.2073, allocations: 1.4 MB / 449.9 MB, free: 23.71 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01035/0.2177, allocations: 4.612 MB / 454.6 MB, free: 23.4 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004023/0.2217, allocations: 1.906 MB / 456.5 MB, free: 23.38 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.009486/0.2312, allocations: 9.25 MB / 465.7 MB, free: 20.36 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003011/0.2342, allocations: 1.955 MB / 467.7 MB, free: 19.26 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.007269/0.2415, allocations: 4.27 MB / 471.9 MB, free: 17.21 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004195/0.2457, allocations: 2.473 MB / 474.4 MB, free: 16.4 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0008706/0.2466, allocations: 380.2 kB / 474.8 MB, free: 16.4 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002992/0.2495, allocations: 0.9933 MB / 475.8 MB, free: 16.32 MB / 366.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0005824/0.2501, allocations: 0.8909 MB / 476.7 MB, free: 15.71 MB / 366.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002107/0.2522, allocations: 1.91 MB / 478.6 MB, free: 14.84 MB / 366.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.004779/0.257, allocations: 6.72 MB / 485.3 MB, free: 10.71 MB / 366.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.769e-06/0.257, allocations: 3.469 kB / 485.3 MB, free: 10.71 MB / 366.1 MB
Notification: Performance of FrontEnd: time 1.002e-06/0.257, allocations: 4 kB / 485.3 MB, free: 10.71 MB / 366.1 MB
Notification: Performance of Transformations before backend: time 4.823e-05/0.2571, allocations: 0 / 485.3 MB, free: 10.71 MB / 366.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 804
 * Number of variables: 804
Notification: Performance of Generate backend data structure: time 0.01864/0.2757, allocations: 6.719 MB / 492 MB, free: 5.453 MB / 366.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.651e-05/0.2757, allocations: 8.031 kB / 492 MB, free: 5.445 MB / 366.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005602/0.2814, allocations: 1.125 MB / 493.2 MB, free: 4.328 MB / 366.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003967/0.2853, allocations: 2.851 MB / 496 MB, free: 1.539 MB / 366.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001096/0.2854, allocations: 246.7 kB / 496.2 MB, free: 1.297 MB / 366.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002994/0.2857, allocations: 328.4 kB / 496.6 MB, free: 0.9766 MB / 366.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005522/0.2912, allocations: 4.302 MB / 0.4891 GB, free: 12.16 MB / 382.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 6.14e-05/0.2913, allocations: 24.5 kB / 0.4892 GB, free: 12.15 MB / 382.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003003/0.2916, allocations: 147.9 kB / 0.4893 GB, free: 12.01 MB / 382.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.476e-05/0.2917, allocations: 87.94 kB / 0.4894 GB, free: 11.93 MB / 382.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004909/0.2966, allocations: 2.992 MB / 0.4923 GB, free: 9.039 MB / 382.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.02876/0.3254, allocations: 19.16 MB / 0.511 GB, free: 5.629 MB / 398.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.00284/0.3282, allocations: 1.527 MB / 0.5125 GB, free: 4.098 MB / 398.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00132/0.3295, allocations: 0.8113 MB / 0.5133 GB, free: 3.273 MB / 398.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001266/0.3296, allocations: 42.28 kB / 0.5133 GB, free: 3.234 MB / 398.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.025e-05/0.3297, allocations: 68.88 kB / 0.5134 GB, free: 3.156 MB / 398.1 MB
Notification: Performance of pre-optimization done (n=254): time 5.11e-06/0.3297, allocations: 0 / 0.5134 GB, free: 3.156 MB / 398.1 MB
Notification: Performance of matching and sorting (n=254): time 0.0046/0.3343, allocations: 2.58 MB / 0.5159 GB, free: 0.582 MB / 398.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001005/0.3344, allocations: 158.3 kB / 0.5161 GB, free: 412 kB / 398.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004827/0.3392, allocations: 3.535 MB / 0.5195 GB, free: 12.87 MB / 414.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001852/0.3394, allocations: 77.7 kB / 0.5196 GB, free: 12.79 MB / 414.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001649/0.3411, allocations: 1.892 MB / 0.5215 GB, free: 10.92 MB / 414.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001072/0.3421, allocations: 1.041 MB / 0.5225 GB, free: 9.891 MB / 414.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002473/0.3446, allocations: 1.651 MB / 0.5241 GB, free: 8.227 MB / 414.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001302/0.3448, allocations: 305.1 kB / 0.5244 GB, free: 7.926 MB / 414.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004044/0.3488, allocations: 2.427 MB / 0.5267 GB, free: 5.492 MB / 414.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004831/0.3536, allocations: 3.165 MB / 0.5298 GB, free: 2.246 MB / 414.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.006881/0.3605, allocations: 4.432 MB / 0.5342 GB, free: 13.65 MB / 430.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.007e-05/0.3605, allocations: 4 kB / 0.5342 GB, free: 13.64 MB / 430.1 MB
Notification: Performance of matching and sorting (n=1174) (initialization): time 0.01197/0.3725, allocations: 7.337 MB / 0.5413 GB, free: 6.18 MB / 430.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.321e-05/0.3725, allocations: 8 kB / 0.5413 GB, free: 6.172 MB / 430.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.981e-05/0.3726, allocations: 8 kB / 0.5413 GB, free: 6.164 MB / 430.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 5.742e-05/0.3726, allocations: 40 kB / 0.5414 GB, free: 6.125 MB / 430.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007454/0.3801, allocations: 1.842 MB / 0.5432 GB, free: 4.391 MB / 430.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 7.492e-05/0.3802, allocations: 53.03 kB / 0.5432 GB, free: 4.344 MB / 430.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005953/0.3861, allocations: 0.5728 MB / 0.5438 GB, free: 3.77 MB / 430.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005112/0.3866, allocations: 283.9 kB / 0.5441 GB, free: 3.492 MB / 430.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 24
 * Number of states: 0 ()
 * Number of discrete variables: 4 (bus4.componentPF.isSlackBus,bus3.componentPF.isSlackBus,bus2.componentPF.isSlackBus,bus1.componentPF.isSlackBus)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1174):
 * Single equations (assignments): 1174
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.001556/0.3882, allocations: 0.8449 MB / 0.5449 GB, free: 2.75 MB / 430.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0007157/0.3889, allocations: 405.5 kB / 0.5453 GB, free: 2.352 MB / 430.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003648/0.3925, allocations: 2.183 MB / 0.5474 GB, free: 140 kB / 430.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.412e-05/0.3926, allocations: 11.95 kB / 0.5474 GB, free: 128 kB / 430.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.013e-06/0.3926, allocations: 4 kB / 0.5474 GB, free: 124 kB / 430.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.001627/0.3942, allocations: 0.7355 MB / 0.5481 GB, free: 15.38 MB / 446.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005376/0.3947, allocations: 47.89 kB / 0.5482 GB, free: 15.33 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002641/0.3974, allocations: 131.8 kB / 0.5483 GB, free: 15.2 MB / 446.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002512/0.3976, allocations: 143.8 kB / 0.5484 GB, free: 15.07 MB / 446.1 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.003622/0.4012, allocations: 2.12 MB / 0.5505 GB, free: 12.98 MB / 446.1 MB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.003359/0.4046, allocations: 2.383 MB / 0.5528 GB, free: 10.59 MB / 446.1 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001104/0.4057, allocations: 0.7208 MB / 0.5535 GB, free: 9.867 MB / 446.1 MB
Notification: Performance of sorting global known variables: time 0.003152/0.4089, allocations: 2.144 MB / 0.5556 GB, free: 7.738 MB / 446.1 MB
Notification: Performance of Backend: time 4.01e-07/0.4089, allocations: 0 / 0.5556 GB, free: 7.738 MB / 446.1 MB
Notification: Performance of simCode: created initialization part: time 0.007855/0.4167, allocations: 4.007 MB / 0.5596 GB, free: 3.875 MB / 446.1 MB
Notification: Performance of SimCode: time 0.008502/0.4252, allocations: 5.675 MB / 0.5651 GB, free: 14.15 MB / 462.1 MB
Notification: Performance of Templates: time 0.0996/0.5248, allocations: 56.91 MB / 0.6207 GB, free: 5.879 MB / 0.4981 GB
"
[Timeout remaining time 659]
make -j1 -f PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine.makefile [Timeout 660]
(rm -f PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine.pipe ; mkfifo PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine.pipe ; head -c 1048576 < PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine.pipe >> ../files/PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine.sim & ./PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine  -abortSlowSimulation -alarm=480 -lv LOG_STATS > PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestTransmissionLine.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 7.03754335641861]
Failed to read output from testmodel.py, exit status != 0:
0.5329410471022129 0.6858061169999999 0.426117995
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f5c1964cfd0>