Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerGrids_dev_PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF.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.0009847/0.0009847, allocations: 77.09 kB / 20.66 MB, free: 3.844 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.001179/0.001179, allocations: 169 kB / 24.06 MB, free: 456 kB / 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.9824/0.9824, allocations: 177.1 MB / 204.5 MB, free: 5.5 MB / 186.7 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.05925/0.05925, allocations: 12.99 MB / 274 MB, free: 308 kB / 250.7 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.Electrical.Test.TestTransmissionLineWithBreakersPF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=50,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF")
translateModel(PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=50,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.623e-06/1.623e-06, allocations: 0 / 348.4 MB, free: 2.285 MB / 314.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.537e-05/1.699e-05, allocations: 2.312 kB / 348.4 MB, free: 2.281 MB / 314.7 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF): time 0.229/0.229, allocations: 139.9 MB / 488.2 MB, free: 68.35 MB / 410.7 MB
Notification: Performance of NFInst.instExpressions: time 0.04779/0.2768, allocations: 36.59 MB / 0.5125 GB, free: 45.93 MB / 410.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.02011/0.2969, allocations: 483.3 kB / 0.513 GB, free: 45.71 MB / 410.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.01863/0.3156, allocations: 5.421 MB / 0.5183 GB, free: 43.12 MB / 410.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.04122/0.3568, allocations: 17.4 MB / 0.5353 GB, free: 30.4 MB / 410.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0136/0.3704, allocations: 6.078 MB / 0.5412 GB, free: 26.08 MB / 410.7 MB
Notification: Performance of NFFlatten.flatten: time 0.0395/0.4099, allocations: 40.34 MB / 0.5806 GB, free: 7.027 MB / 426.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01489/0.4248, allocations: 8.374 MB / 0.5888 GB, free: 14.54 MB / 442.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.3518/0.7766, allocations: 19.34 MB / 0.6077 GB, free: 24.44 MB / 442.8 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01675/0.7933, allocations: 10.31 MB / 0.6177 GB, free: 24.44 MB / 442.8 MB
Notification: Performance of NFPackage.collectConstants: time 0.004949/0.7983, allocations: 1.52 MB / 0.6192 GB, free: 24.44 MB / 442.8 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.01237/0.8106, allocations: 2.908 MB / 0.622 GB, free: 24.44 MB / 442.8 MB
Notification: Performance of NFScalarize.scalarize: time 0.003954/0.8146, allocations: 3.677 MB / 0.6256 GB, free: 24.44 MB / 442.8 MB
Notification: Performance of NFVerifyModel.verify: time 0.0137/0.8283, allocations: 7.784 MB / 0.6332 GB, free: 24.44 MB / 442.8 MB
Notification: Performance of NFConvertDAE.convert: time 0.01882/0.8471, allocations: 15.69 MB / 0.6486 GB, free: 23 MB / 442.8 MB
Notification: Performance of FrontEnd - DAE generated: time 5.24e-06/0.8471, allocations: 3.094 kB / 0.6486 GB, free: 23 MB / 442.8 MB
Notification: Performance of FrontEnd: time 1.052e-06/0.8471, allocations: 0 / 0.6486 GB, free: 23 MB / 442.8 MB
Notification: Performance of Transformations before backend: time 0.0002634/0.8474, allocations: 0 / 0.6486 GB, free: 23 MB / 442.8 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3473
 * Number of variables: 3473
Notification: Performance of Generate backend data structure: time 0.05631/0.9037, allocations: 28.95 MB / 0.6768 GB, free: 14.42 MB / 442.8 MB
Notification: Performance of prepare preOptimizeDAE: time 3.517e-05/0.9037, allocations: 9.312 kB / 0.6768 GB, free: 14.42 MB / 442.8 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01351/0.9172, allocations: 6.082 MB / 0.6828 GB, free: 12.79 MB / 442.8 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01589/0.9331, allocations: 10.89 MB / 0.6934 GB, free: 7.02 MB / 442.8 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007189/0.9338, allocations: 2.016 MB / 0.6954 GB, free: 5.328 MB / 442.8 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001707/0.9355, allocations: 1.449 MB / 0.6968 GB, free: 4.344 MB / 442.8 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01298/0.9485, allocations: 16.36 MB / 0.7128 GB, free: 5.07 MB / 458.8 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002337/0.9488, allocations: 89.06 kB / 0.7129 GB, free: 5.035 MB / 458.8 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0008802/0.9496, allocations: 0.6102 MB / 0.7135 GB, free: 4.852 MB / 458.8 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001996/0.9498, allocations: 360 kB / 0.7138 GB, free: 4.5 MB / 458.8 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.008943/0.9588, allocations: 7.284 MB / 0.7209 GB, free: 13.92 MB / 474.8 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.295/1.254, allocations: 107.1 MB / 0.8255 GB, free: 35.07 MB / 0.5105 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0112/1.265, allocations: 7.791 MB / 0.8331 GB, free: 34.31 MB / 0.5105 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003155/1.268, allocations: 2.575 MB / 0.8356 GB, free: 33.92 MB / 0.5105 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.000476/1.269, allocations: 261.6 kB / 0.8358 GB, free: 33.91 MB / 0.5105 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.007572/1.276, allocations: 9.487 MB / 0.8451 GB, free: 27.2 MB / 0.5105 GB
Notification: Performance of pre-optimization done (n=1284): time 5.421e-05/1.276, allocations: 3 kB / 0.8451 GB, free: 27.2 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=1284): time 0.03873/1.315, allocations: 14.9 MB / 0.8596 GB, free: 25.83 MB / 0.5105 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0006228/1.316, allocations: 4.518 MB / 0.8641 GB, free: 20.54 MB / 0.5105 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01258/1.328, allocations: 12.04 MB / 0.8758 GB, free: 35.67 MB / 0.5262 GB
Notification: Performance of collectPreVariables (initialization): time 0.001293/1.329, allocations: 321 kB / 0.8761 GB, free: 35.64 MB / 0.5262 GB
Notification: Performance of collectInitialEqns (initialization): time 0.00478/1.334, allocations: 8.339 MB / 0.8843 GB, free: 32.7 MB / 0.5262 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003604/1.338, allocations: 5.307 MB / 0.8894 GB, free: 29.7 MB / 0.5262 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.007003/1.345, allocations: 7.469 MB / 0.8967 GB, free: 29.14 MB / 0.5262 GB
Notification: Performance of setup shared object (initialization): time 0.0001366/1.345, allocations: 484.1 kB / 0.8972 GB, free: 28.68 MB / 0.5262 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.007411/1.352, allocations: 6.469 MB / 0.9035 GB, free: 27.37 MB / 0.5262 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01/1.362, allocations: 12.24 MB / 0.9155 GB, free: 15.66 MB / 0.5262 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01656/1.379, allocations: 16.36 MB / 0.9314 GB, free: 1.871 MB / 0.5262 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.099e-05/1.379, allocations: 12 kB / 0.9315 GB, free: 1.859 MB / 0.5262 GB
Notification: Performance of matching and sorting (n=5001) (initialization): time 0.03377/1.413, allocations: 27.38 MB / 0.9582 GB, free: 9.094 MB / 0.5574 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002567/1.413, allocations: 0.5069 MB / 0.9587 GB, free: 8.488 MB / 0.5574 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.203e-05/1.413, allocations: 43.38 kB / 0.9587 GB, free: 8.449 MB / 0.5574 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008123/1.421, allocations: 3.396 MB / 0.962 GB, free: 5.062 MB / 0.5574 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01501/1.436, allocations: 7.432 MB / 0.9693 GB, free: 14.08 MB / 0.573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.2291/1.665, allocations: 24.28 MB / 0.993 GB, free: 152.8 MB / 0.573 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02527/1.691, allocations: 3.094 MB / 0.996 GB, free: 152.8 MB / 0.573 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004598/1.695, allocations: 0.9361 MB / 0.997 GB, free: 152.8 MB / 0.573 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 193
 * Number of states: 0 ()
 * Number of discrete variables: 129 (LineAcBo.breakerStatusInternalA,LineAoBc.breakerStatusInternalB,LineAoBo.componentPF.breakerStatusInternalA,LineAoBo.componentPF.breakerStatusInternalB,LineAcBo.componentPF.breakerStatusInternalA,LineAcBo.componentPF.breakerStatusInternalB,LineAoBc.componentPF.breakerStatusInternalA,LineAoBc.componentPF.breakerStatusInternalB,LineAcBc.componentPF.breakerStatusInternalA,LineAcBc.componentPF.breakerStatusInternalB,LineAcBc.breakerStatusInternalA,LineAcBc.breakerStatusInternalB,IB1.componentPF.isSlackBus,LoadAcBc.componentPF.isSlackBus,BL1.componentPF.isSlackBus,IBL1.componentPF.isSlackBus,IBL2.componentPF.isSlackBus,LoadAoBc.componentPF.isSlackBus,IB2.componentPF.isSlackBus,BL2.componentPF.isSlackBus,IBL3.componentPF.isSlackBus,LoadAcBo.componentPF.isSlackBus,IB3.componentPF.isSlackBus,BL3.componentPF.isSlackBus,IB4.componentPF.isSlackBus,LoadAoBo.componentPF.isSlackBus,BL4.componentPF.isSlackBus,IBL4.componentPF.isSlackBus,LineAoBo.breakerStatusInternalB,LineAoBo.breakerStatusInternalA,LineAcBo.breakerStatusInternalB,LineAoBc.breakerStatusInternalA,CloseCommand.y,$PRE.LineAcBc.componentPF.breakerStatusInternalB,$PRE.LineAcBc.componentPF.breakerStatusInternalA,LineAcBc.componentPF.breakerStatus,$PRE.LineAcBc.componentPF.breakerStatus,$whenCondition16,$whenCondition15,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$PRE.LineAcBc.breakerStatusInternalB,$PRE.LineAcBc.breakerStatusInternalA,LineAcBc.breakerStatus,$PRE.LineAcBc.breakerStatus,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,$PRE.LineAoBc.breakerStatusInternalA,$PRE.LineAoBc.breakerStatusInternalB,LineAoBc.breakerStatus,$PRE.LineAoBc.breakerStatus,$whenCondition48,$whenCondition47,$whenCondition46,$whenCondition45,$whenCondition44,$whenCondition43,$whenCondition42,$whenCondition41,$PRE.LineAoBc.componentPF.breakerStatusInternalB,$PRE.LineAoBc.componentPF.breakerStatusInternalA,LineAoBc.componentPF.breakerStatus,$PRE.LineAoBc.componentPF.breakerStatus,$whenCondition24,$whenCondition23,$whenCondition22,$whenCondition21,$whenCondition20,$whenCondition19,$whenCondition18,$whenCondition17,$PRE.LineAcBo.breakerStatusInternalB,$PRE.LineAcBo.breakerStatusInternalA,LineAcBo.breakerStatus,$PRE.LineAcBo.breakerStatus,$whenCondition56,$whenCondition55,$whenCondition54,$whenCondition53,$whenCondition52,$whenCondition51,$whenCondition50,$whenCondition49,$PRE.LineAcBo.componentPF.breakerStatusInternalB,$PRE.LineAcBo.componentPF.breakerStatusInternalA,LineAcBo.componentPF.breakerStatus,$PRE.LineAcBo.componentPF.breakerStatus,$whenCondition32,$whenCondition31,$whenCondition30,$whenCondition29,$whenCondition28,$whenCondition27,$whenCondition26,$whenCondition25,$PRE.LineAoBo.breakerStatusInternalB,$PRE.LineAoBo.breakerStatusInternalA,LineAoBo.breakerStatus,$PRE.LineAoBo.breakerStatus,$whenCondition64,$whenCondition63,$whenCondition62,$whenCondition61,$whenCondition60,$whenCondition59,$whenCondition58,$whenCondition57,$PRE.LineAoBo.componentPF.breakerStatusInternalB,$PRE.LineAoBo.componentPF.breakerStatusInternalA,LineAoBo.componentPF.breakerStatus,$PRE.LineAoBo.componentPF.breakerStatus,$whenCondition40,$whenCondition39,$whenCondition38,$whenCondition37,$whenCondition36,$whenCondition35,$whenCondition34,$whenCondition33)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (4743):
 * Single equations (assignments): 4719
 * Array equations: 0
 * Algorithm blocks: 16
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 8
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 8 systems
   {(26,0), (26,0), (28,0), (26,0), (26,0), (26,0), (26,0), (26,0)}
Notification: Performance of prepare postOptimizeDAE: time 0.009601/1.705, allocations: 2.065 MB / 0.999 GB, free: 152.4 MB / 0.573 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.006221/1.711, allocations: 2.182 MB / 1.001 GB, free: 152.4 MB / 0.573 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01661/1.728, allocations: 9.095 MB / 1.01 GB, free: 151.2 MB / 0.573 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0003942/1.728, allocations: 79.77 kB / 1.01 GB, free: 151.2 MB / 0.573 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.042e-05/1.728, allocations: 12.56 kB / 1.01 GB, free: 151.2 MB / 0.573 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01121/1.739, allocations: 3.446 MB / 1.013 GB, free: 151.2 MB / 0.573 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.005775/1.745, allocations: 281.1 kB / 1.014 GB, free: 151.2 MB / 0.573 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01187/1.757, allocations: 461.8 kB / 1.014 GB, free: 151.2 MB / 0.573 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002693/1.76, allocations: 0.9691 MB / 1.015 GB, free: 151.2 MB / 0.573 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02108/1.781, allocations: 16.13 MB / 1.031 GB, free: 143.5 MB / 0.573 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.02441/1.805, allocations: 18.99 MB / 1.049 GB, free: 134.5 MB / 0.573 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.004724/1.81, allocations: 3.299 MB / 1.053 GB, free: 134.1 MB / 0.573 GB
Notification: Performance of sorting global known variables: time 0.01081/1.821, allocations: 5.826 MB / 1.058 GB, free: 133.7 MB / 0.573 GB
Notification: Performance of Backend: time 6.61e-07/1.821, allocations: 0 / 1.058 GB, free: 133.7 MB / 0.573 GB
Notification: Performance of simCode: created initialization part: time 0.05224/1.873, allocations: 23.04 MB / 1.081 GB, free: 127.8 MB / 0.573 GB
Notification: Performance of SimCode: time 0.0523/1.925, allocations: 29.37 MB / 1.109 GB, free: 100.8 MB / 0.573 GB
Notification: Performance of Templates: time 1.159/3.084, allocations: 0.5338 GB / 1.643 GB, free: 146.8 MB / 0.6043 GB
"
[Timeout remaining time 657]
make -j1 -f PowerGrids_dev_PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF.makefile [Timeout 660]
(rm -f PowerGrids_dev_PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF.pipe ; mkfifo PowerGrids_dev_PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF.pipe ; head -c 1048576 < PowerGrids_dev_PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF.pipe >> ../files/PowerGrids_dev_PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF.sim & ./PowerGrids_dev_PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF -abortSlowSimulation -alarm=240  -lv LOG_STATS > PowerGrids_dev_PowerGrids.Electrical.Test.TestTransmissionLineWithBreakersPF.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 18.599431029055268]