Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding.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/PowerSystems 2.0.0-master/package.mo", uses=false)
Using package PowerSystems with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.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(PowerSystems.Examples.AC3ph.Transformation.ThreeWinding,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="Time|meter1.alpha_v|meter1.p.1.|meter1.p.2.|meter1.p.3.|meter1.v_norm|meter12.alpha_v|meter12.p.1.|meter12.p.2.|meter12.p.3.|meter12.v_norm|meter22.alpha_v|meter22.p.1.|meter22.p.2.|meter22.p.3.|meter22.v_norm",fileNamePrefix="PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding")
translateModel(PowerSystems.Examples.AC3ph.Transformation.ThreeWinding,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="Time|meter1.alpha_v|meter1.p.1.|meter1.p.2.|meter1.p.3.|meter1.v_norm|meter12.alpha_v|meter12.p.1.|meter12.p.2.|meter12.p.3.|meter12.v_norm|meter22.alpha_v|meter22.p.1.|meter22.p.2.|meter22.p.3.|meter22.v_norm",fileNamePrefix="PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.000744/0.0007439, allocations: 95.23 kB / 16.65 MB, free: 6.535 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.0008123/0.0008124, allocations: 175.4 kB / 17.56 MB, free: 6.18 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.227/1.227, 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/PowerSystems 2.0.0-master/package.mo): time 0.1637/0.1637, allocations: 38.09 MB / 330.8 MB, free: 8.527 MB / 270.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.000276/0.0002759, allocations: 9.906 kB / 400.7 MB, free: 34.15 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.08409/0.08439, allocations: 59.17 MB / 459.9 MB, free: 7.055 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.ThreeWinding): time 0.008389/0.09281, allocations: 9.11 MB / 469 MB, free: 13.9 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.005273/0.09811, allocations: 3.429 MB / 472.4 MB, free: 10.46 MB / 350.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0007968/0.09893, allocations: 51.62 kB / 472.5 MB, free: 10.41 MB / 350.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0007147/0.09966, allocations: 254.4 kB / 472.7 MB, free: 10.16 MB / 350.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.001717/0.1014, allocations: 0.8681 MB / 473.6 MB, free: 9.289 MB / 350.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00119/0.1026, allocations: 0.5732 MB / 474.2 MB, free: 8.715 MB / 350.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002657/0.1053, allocations: 3.334 MB / 477.5 MB, free: 5.371 MB / 350.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001446/0.1067, allocations: 1.074 MB / 478.6 MB, free: 4.258 MB / 350.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0007611/0.1075, allocations: 0.6494 MB / 479.2 MB, free: 3.605 MB / 350.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001244/0.1087, allocations: 1.153 MB / 480.4 MB, free: 2.449 MB / 350.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001113/0.1089, allocations: 56 kB / 480.4 MB, free: 2.395 MB / 350.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0007718/0.1097, allocations: 0.5226 MB / 481 MB, free: 1.871 MB / 350.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0006155/0.1103, allocations: 0.7286 MB / 481.7 MB, free: 1.141 MB / 350.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0005784/0.1109, allocations: 0.705 MB / 482.4 MB, free: 436 kB / 350.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.001464/0.1123, allocations: 1.908 MB / 484.3 MB, free: 14.51 MB / 366.1 MB
Notification: Performance of FrontEnd - DAE generated: time 3.106e-06/0.1124, allocations: 0 / 484.3 MB, free: 14.51 MB / 366.1 MB
Notification: Performance of FrontEnd: time 1.422e-06/0.1124, allocations: 0 / 484.3 MB, free: 14.51 MB / 366.1 MB
Notification: Performance of Transformations before backend: time 1.272e-05/0.1124, allocations: 0 / 484.3 MB, free: 14.51 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: 374
 * Number of variables: 374
Notification: Performance of Generate backend data structure: time 0.002213/0.1146, allocations: 1.407 MB / 485.7 MB, free: 13.05 MB / 366.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.03e-05/0.1146, allocations: 11.97 kB / 485.7 MB, free: 13.04 MB / 366.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001459/0.1161, allocations: 278.8 kB / 486 MB, free: 12.76 MB / 366.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001186/0.1173, allocations: 0.8676 MB / 486.9 MB, free: 11.85 MB / 366.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 7.06e-05/0.1174, allocations: 98.78 kB / 487 MB, free: 11.75 MB / 366.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002906/0.1177, allocations: 144.1 kB / 487.1 MB, free: 11.61 MB / 366.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002517/0.1202, allocations: 2.095 MB / 489.2 MB, free: 9.359 MB / 366.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.696e-05/0.1202, allocations: 11.94 kB / 489.2 MB, free: 9.348 MB / 366.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001148/0.1204, allocations: 51.97 kB / 489.3 MB, free: 9.297 MB / 366.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0004052/0.1208, allocations: 178.5 kB / 489.4 MB, free: 9.121 MB / 366.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00227/0.1231, allocations: 1.677 MB / 491.1 MB, free: 7.438 MB / 366.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.008806/0.1319, allocations: 9.411 MB / 0.4888 GB, free: 13.51 MB / 382.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001027/0.1329, allocations: 0.8951 MB / 0.4897 GB, free: 12.55 MB / 382.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0005357/0.1335, allocations: 444.3 kB / 0.4901 GB, free: 12.12 MB / 382.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 4.724e-05/0.1335, allocations: 8.656 kB / 0.4901 GB, free: 12.11 MB / 382.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.882e-05/0.1336, allocations: 51.09 kB / 0.4901 GB, free: 12.05 MB / 382.1 MB
Notification: Performance of pre-optimization done (n=81): time 2.154e-06/0.1336, allocations: 0 / 0.4901 GB, free: 12.05 MB / 382.1 MB
Notification: Performance of matching and sorting (n=85): time 0.004238/0.1378, allocations: 3.037 MB / 0.4931 GB, free: 8.895 MB / 382.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.256e-05/0.1379, allocations: 90.14 kB / 0.4932 GB, free: 8.785 MB / 382.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0007422/0.1386, allocations: 0.7504 MB / 0.4939 GB, free: 8.027 MB / 382.1 MB
Notification: Performance of collectPreVariables (initialization): time 5.087e-05/0.1387, allocations: 41.7 kB / 0.494 GB, free: 7.98 MB / 382.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004004/0.1391, allocations: 0.7073 MB / 0.4947 GB, free: 7.262 MB / 382.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002054/0.1393, allocations: 299.8 kB / 0.4949 GB, free: 6.965 MB / 382.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000128/0.1394, allocations: 117.6 kB / 0.4951 GB, free: 6.844 MB / 382.1 MB
Notification: Performance of setup shared object (initialization): time 0.000161/0.1396, allocations: 406.7 kB / 0.4954 GB, free: 6.438 MB / 382.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0006162/0.1402, allocations: 451.7 kB / 0.4959 GB, free: 5.996 MB / 382.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0009287/0.1412, allocations: 1.022 MB / 0.4969 GB, free: 4.715 MB / 382.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001708/0.1429, allocations: 1.598 MB / 0.4984 GB, free: 2.852 MB / 382.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.915e-06/0.1429, allocations: 8 kB / 0.4984 GB, free: 2.844 MB / 382.1 MB
Notification: Performance of matching and sorting (n=175) (initialization): time 0.003044/0.1459, allocations: 1.965 MB / 0.5004 GB, free: 0.8633 MB / 382.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.206e-05/0.146, allocations: 12 kB / 0.5004 GB, free: 0.8516 MB / 382.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.1846/0.3306, allocations: 1.207 MB / 0.5015 GB, free: 54.22 MB / 382.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001356/0.332, allocations: 449.1 kB / 0.502 GB, free: 54.2 MB / 382.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009022/0.3329, allocations: 264.8 kB / 0.5022 GB, free: 54.2 MB / 382.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.005774/0.3387, allocations: 5.401 MB / 0.5075 GB, free: 51.44 MB / 382.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0007362/0.3394, allocations: 58.72 kB / 0.5076 GB, free: 51.41 MB / 382.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.919e-05/0.3395, allocations: 73.36 kB / 0.5076 GB, free: 51.4 MB / 382.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 86
 * Number of states: 0 ()
 * Number of discrete variables: 4 (system.initime,trafo3Stray.tap_2_internal[2],trafo3Stray.tap_2_internal[1],trafo3Stray.tap_1_internal)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (141):
 * Single equations (assignments): 139
 * 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): 2 systems
   {(3,15,100.0%), (2,16,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0006217/0.3401, allocations: 468.4 kB / 0.5081 GB, free: 51.15 MB / 382.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001142/0.3403, allocations: 95.28 kB / 0.5082 GB, free: 51.15 MB / 382.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001948/0.3422, allocations: 1.389 MB / 0.5095 GB, free: 50.43 MB / 382.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.927e-06/0.3422, allocations: 7.844 kB / 0.5095 GB, free: 50.43 MB / 382.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.761e-06/0.3422, allocations: 2.188 kB / 0.5095 GB, free: 50.43 MB / 382.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.191e-05/0.3423, allocations: 7.609 kB / 0.5095 GB, free: 50.43 MB / 382.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003307/0.3456, allocations: 2.492 MB / 0.512 GB, free: 49.21 MB / 382.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.959e-06/0.3456, allocations: 4.938 kB / 0.512 GB, free: 49.21 MB / 382.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004976/0.3461, allocations: 129.9 kB / 0.5121 GB, free: 49.2 MB / 382.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0005095/0.3466, allocations: 182.3 kB / 0.5123 GB, free: 49.14 MB / 382.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.505e-05/0.3467, allocations: 26.19 kB / 0.5123 GB, free: 49.13 MB / 382.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002908/0.3496, allocations: 4.607 MB / 0.5168 GB, free: 44.97 MB / 382.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.358e-06/0.3496, allocations: 2.969 kB / 0.5168 GB, free: 44.97 MB / 382.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002384/0.352, allocations: 1.857 MB / 0.5186 GB, free: 44.02 MB / 382.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002778/0.3523, allocations: 227 kB / 0.5188 GB, free: 43.87 MB / 382.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 9.302e-05/0.3524, allocations: 25.47 kB / 0.5189 GB, free: 43.86 MB / 382.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003404/0.3527, allocations: 32.58 kB / 0.5189 GB, free: 43.84 MB / 382.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001272/0.3529, allocations: 61.27 kB / 0.5189 GB, free: 43.83 MB / 382.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.649e-05/0.3529, allocations: 26.72 kB / 0.519 GB, free: 43.82 MB / 382.1 MB
Notification: Performance of sorting global known variables: time 0.0005798/0.3535, allocations: 0.4901 MB / 0.5194 GB, free: 43.62 MB / 382.1 MB
Notification: Performance of sort global known variables: time 2.41e-07/0.3535, allocations: 0 / 0.5194 GB, free: 43.62 MB / 382.1 MB
Notification: Performance of remove unused functions: time 0.001178/0.3547, allocations: 322.2 kB / 0.5198 GB, free: 43.59 MB / 382.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 6
 * Number of states: 6 (trafo3Stray.i2a[1],trafo3Stray.i2a[2],trafo3Stray.i2a[3],trafo3Stray.i2b[1],trafo3Stray.i2b[2],trafo3Stray.i2b[3])
 * Number of discrete variables: 1 (system.initime)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (76):
 * Single equations (assignments): 72
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 1
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 3 systems
   {(1,3,100.0%), (1,3,100.0%), (1,3,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0001347/0.3548, allocations: 126.3 kB / 0.5199 GB, free: 43.48 MB / 382.1 MB
Notification: Performance of simCode: created initialization part: time 0.002272/0.3571, allocations: 1.541 MB / 0.5214 GB, free: 42.83 MB / 382.1 MB
Notification: Performance of simCode: created event and clocks part: time 4.007e-06/0.3571, allocations: 0 / 0.5214 GB, free: 42.83 MB / 382.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.001184/0.3583, allocations: 0.8402 MB / 0.5222 GB, free: 42.34 MB / 382.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0009064/0.3592, allocations: 167.5 kB / 0.5224 GB, free: 42.27 MB / 382.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003763/0.363, allocations: 3.465 MB / 0.5257 GB, free: 40.29 MB / 382.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007764/0.3638, allocations: 1.317 MB / 0.527 GB, free: 39.5 MB / 382.1 MB
Notification: Performance of simCode: alias equations: time 0.0007714/0.3646, allocations: 362 kB / 0.5274 GB, free: 39.45 MB / 382.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001479/0.3647, allocations: 75.81 kB / 0.5274 GB, free: 39.44 MB / 382.1 MB
Notification: Performance of SimCode: time 5.41e-07/0.3647, allocations: 1.375 kB / 0.5274 GB, free: 39.44 MB / 382.1 MB
Notification: Performance of Templates: time 0.01413/0.3789, allocations: 11.27 MB / 0.5385 GB, free: 31.41 MB / 382.1 MB
make -j1 -f PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding.makefile
Environment - environmentSimulation:
(rm -f PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding.pipe ; mkfifo PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding.pipe ; head -c 1048576 < PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding.pipe >> ../files/PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding.sim & ./PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding.pipe 2>&1)
Environment - environmentSimulation:
diffSimulationResults("PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/PowerSystems/PowerSystems.Examples.AC3ph.Transformation.ThreeWinding.mat","../files/PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.ThreeWinding.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches