Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSystems_PowerSystems.Examples.AC3ph.Transformation.TapChanger.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.001266/0.001266, allocations: 112.2 kB / 21.22 MB, free: 0.75 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.001619/0.001619, allocations: 206.1 kB / 24.52 MB, free: 4.156 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.475/1.475, allocations: 230.6 MB / 258.3 MB, free: 8.02 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.0.0/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.0.0/package.mo): time 0.1874/0.1874, allocations: 38.52 MB / 353.2 MB, free: 12.55 MB / 286.1 MB " [Timeout remaining time 180] Using package PowerSystems with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.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: "" <> buildModelFMU(PowerSystems.Examples.AC3ph.Transformation.TapChanger,fileNamePrefix="PowerSystems_PowerSystems_Examples_AC3ph_Transformation_TapChanger",fmuType="cs",version="2.0",platforms={"static"}) "" <> buildModelFMU(PowerSystems.Examples.AC3ph.Transformation.TapChanger,fileNamePrefix="PowerSystems_PowerSystems_Examples_AC3ph_Transformation_TapChanger",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660] "Notification: PowerSystems 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.002084/0.002084, allocations: 59.91 kB / 493.3 MB, free: 4.895 MB / 350.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3124/0.3145, allocations: 62.46 MB / 0.5428 GB, free: 464 kB / 382.1 MB Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.TapChanger): time 0.01115/0.3257, allocations: 9.313 MB / 0.5519 GB, free: 9.113 MB / 398.1 MB Notification: Performance of NFInst.instExpressions: time 0.007332/0.333, allocations: 3.458 MB / 0.5552 GB, free: 6.328 MB / 398.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.0009967/0.334, allocations: 50 kB / 0.5553 GB, free: 6.301 MB / 398.1 MB Notification: Performance of NFTyping.typeComponents: time 0.00304/0.337, allocations: 1.066 MB / 0.5563 GB, free: 5.484 MB / 398.1 MB Notification: Performance of NFTyping.typeBindings: time 0.002869/0.3399, allocations: 1.117 MB / 0.5574 GB, free: 4.605 MB / 398.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.002095/0.342, allocations: 0.7127 MB / 0.5581 GB, free: 4.086 MB / 398.1 MB Notification: Performance of NFFlatten.flatten: time 0.003657/0.3456, allocations: 2.378 MB / 0.5604 GB, free: 2.406 MB / 398.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.001385/0.347, allocations: 0.6664 MB / 0.5611 GB, free: 1.715 MB / 398.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.002372/0.3494, allocations: 1.012 MB / 0.5621 GB, free: 0.6992 MB / 398.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.002792/0.3522, allocations: 1.464 MB / 0.5635 GB, free: 15.23 MB / 414.1 MB Notification: Performance of NFPackage.collectConstants: time 0.0004682/0.3527, allocations: 128 kB / 0.5636 GB, free: 15.11 MB / 414.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.001804/0.3545, allocations: 0.6553 MB / 0.5643 GB, free: 14.45 MB / 414.1 MB Notification: Performance of NFScalarize.scalarize: time 0.001508/0.356, allocations: 0.9467 MB / 0.5652 GB, free: 13.5 MB / 414.1 MB Notification: Performance of NFVerifyModel.verify: time 0.001973/0.358, allocations: 0.9018 MB / 0.5661 GB, free: 12.59 MB / 414.1 MB Notification: Performance of NFConvertDAE.convert: time 0.004076/0.362, allocations: 2.378 MB / 0.5684 GB, free: 10.21 MB / 414.1 MB Notification: Performance of FrontEnd - DAE generated: time 5.981e-06/0.362, allocations: 0 / 0.5684 GB, free: 10.21 MB / 414.1 MB Notification: Performance of FrontEnd: time 1.813e-06/0.362, allocations: 4 kB / 0.5684 GB, free: 10.2 MB / 414.1 MB Notification: Performance of Transformations before backend: time 9.4e-05/0.3621, allocations: 0 / 0.5684 GB, free: 10.2 MB / 414.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 423 * Number of variables: 423 Notification: Performance of Generate backend data structure: time 0.005577/0.3677, allocations: 1.988 MB / 0.5703 GB, free: 8.16 MB / 414.1 MB Notification: Performance of prepare preOptimizeDAE: time 6.95e-05/0.3678, allocations: 15.92 kB / 0.5703 GB, free: 8.145 MB / 414.1 MB Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 9.624e-05/0.3679, allocations: 38.94 kB / 0.5704 GB, free: 8.105 MB / 414.1 MB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0007231/0.3686, allocations: 240.8 kB / 0.5706 GB, free: 7.859 MB / 414.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003089/0.3717, allocations: 487.2 kB / 0.5711 GB, free: 7.383 MB / 414.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.002476/0.3742, allocations: 0.9956 MB / 0.5721 GB, free: 6.355 MB / 414.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001412/0.3743, allocations: 106.8 kB / 0.5722 GB, free: 6.25 MB / 414.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005508/0.3749, allocations: 160 kB / 0.5723 GB, free: 6.094 MB / 414.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.004522/0.3794, allocations: 2.328 MB / 0.5746 GB, free: 3.609 MB / 414.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 6.116e-05/0.3794, allocations: 11.89 kB / 0.5746 GB, free: 3.598 MB / 414.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001906/0.3796, allocations: 59.97 kB / 0.5746 GB, free: 3.539 MB / 414.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0005879/0.3802, allocations: 226.8 kB / 0.5749 GB, free: 3.316 MB / 414.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003506/0.3837, allocations: 1.846 MB / 0.5767 GB, free: 1.465 MB / 414.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.01319/0.3969, allocations: 9.415 MB / 0.5859 GB, free: 7.543 MB / 430.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.002437/0.3993, allocations: 1.304 MB / 0.5871 GB, free: 6.223 MB / 430.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.0009353/0.4003, allocations: 0.5473 MB / 0.5877 GB, free: 5.672 MB / 430.1 MB Notification: Performance of preOpt evalFunc (simulation): time 7.58e-05/0.4004, allocations: 20.92 kB / 0.5877 GB, free: 5.652 MB / 430.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001309/0.4017, allocations: 0.8529 MB / 0.5885 GB, free: 4.75 MB / 430.1 MB Notification: Performance of pre-optimization done (n=130): time 3.787e-06/0.4017, allocations: 0 / 0.5885 GB, free: 4.75 MB / 430.1 MB Notification: Performance of matching and sorting (n=131): time 0.007129/0.4088, allocations: 4.004 MB / 0.5924 GB, free: 0.6758 MB / 430.1 MB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001862/0.409, allocations: 282.3 kB / 0.5927 GB, free: 344 kB / 430.1 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001288/0.4103, allocations: 0.9148 MB / 0.5936 GB, free: 15.39 MB / 446.1 MB Notification: Performance of collectPreVariables (initialization): time 0.0001038/0.4104, allocations: 61.86 kB / 0.5937 GB, free: 15.33 MB / 446.1 MB Notification: Performance of collectInitialEqns (initialization): time 0.000737/0.4111, allocations: 0.781 MB / 0.5944 GB, free: 14.54 MB / 446.1 MB Notification: Performance of collectInitialBindings (initialization): time 0.000469/0.4116, allocations: 480 kB / 0.5949 GB, free: 14.07 MB / 446.1 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004875/0.4121, allocations: 327.4 kB / 0.5952 GB, free: 13.74 MB / 446.1 MB Notification: Performance of setup shared object (initialization): time 0.0001933/0.4123, allocations: 361.9 kB / 0.5955 GB, free: 13.38 MB / 446.1 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.001011/0.4133, allocations: 0.5739 MB / 0.5961 GB, free: 12.81 MB / 446.1 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.001551/0.4148, allocations: 1.174 MB / 0.5972 GB, free: 11.38 MB / 446.1 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.002774/0.4176, allocations: 2.001 MB / 0.5992 GB, free: 9.082 MB / 446.1 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.65e-05/0.4176, allocations: 8 kB / 0.5992 GB, free: 9.074 MB / 446.1 MB Notification: Performance of matching and sorting (n=227) (initialization): time 0.005058/0.4227, allocations: 2.482 MB / 0.6016 GB, free: 6.559 MB / 446.1 MB Notification: Performance of prepare postOptimizeDAE: time 8.945e-05/0.4228, allocations: 74.36 kB / 0.6017 GB, free: 6.477 MB / 446.1 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.003109/0.4259, allocations: 1.544 MB / 0.6032 GB, free: 4.918 MB / 446.1 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.001734/0.4276, allocations: 0.5413 MB / 0.6037 GB, free: 4.367 MB / 446.1 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001183/0.4288, allocations: 331.5 kB / 0.604 GB, free: 4.043 MB / 446.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01156/0.4404, allocations: 9.059 MB / 0.6129 GB, free: 10.53 MB / 462.1 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001125/0.4415, allocations: 75.92 kB / 0.613 GB, free: 10.46 MB / 462.1 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002641/0.4417, allocations: 104 kB / 0.6131 GB, free: 10.36 MB / 462.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: 21 (system.initime,tapRelay1.cnt_2,$PRE.tapRelay1.cnt_2,tapRelay1.cnt_1,$PRE.tapRelay1.cnt_1,$whenCondition4,$whenCondition3,tapRelay2.cnt_2,$PRE.tapRelay2.cnt_2,tapRelay2.cnt_1,$PRE.tapRelay2.cnt_1,$whenCondition2,$whenCondition1,tapRelay1.tap_2,$PRE.tapRelay1.tap_2,tapRelay1.tap_1,$PRE.tapRelay1.tap_1,tapRelay2.tap_2,$PRE.tapRelay2.tap_2,tapRelay2.tap_1,$PRE.tapRelay2.tap_1) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (175): * Single equations (assignments): 169 * Array equations: 0 * Algorithm blocks: 2 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 4 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 4 systems {(2,12,100.0%), (2,11,100.0%), (2,12,100.0%), (2,11,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.001097/0.4428, allocations: 0.5997 MB / 0.6136 GB, free: 9.73 MB / 462.1 MB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002624/0.4431, allocations: 131.2 kB / 0.6138 GB, free: 9.602 MB / 462.1 MB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002715/0.4458, allocations: 1.535 MB / 0.6153 GB, free: 8.047 MB / 462.1 MB Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.706e-05/0.4458, allocations: 19.94 kB / 0.6153 GB, free: 8.027 MB / 462.1 MB Notification: Performance of postOpt constantLinearSystem (simulation): time 4.929e-06/0.4458, allocations: 4 kB / 0.6153 GB, free: 8.023 MB / 462.1 MB Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.289e-05/0.4459, allocations: 3.984 kB / 0.6153 GB, free: 8.02 MB / 462.1 MB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.005384/0.4512, allocations: 3.04 MB / 0.6183 GB, free: 4.895 MB / 462.1 MB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.76e-06/0.4512, allocations: 4 kB / 0.6183 GB, free: 4.891 MB / 462.1 MB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001393/0.4526, allocations: 299.8 kB / 0.6186 GB, free: 4.598 MB / 462.1 MB Notification: Performance of postOpt tearingSystem (simulation): time 1.817e-05/0.4527, allocations: 4 kB / 0.6186 GB, free: 4.594 MB / 462.1 MB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001144/0.4528, allocations: 39.98 kB / 0.6186 GB, free: 4.555 MB / 462.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.255e-05/0.4528, allocations: 7.938 kB / 0.6186 GB, free: 4.547 MB / 462.1 MB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.43e-06/0.4528, allocations: 4 kB / 0.6186 GB, free: 4.543 MB / 462.1 MB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003543/0.4563, allocations: 2.338 MB / 0.6209 GB, free: 2.152 MB / 462.1 MB Notification: Performance of postOpt removeConstants (simulation): time 0.0007172/0.457, allocations: 219.2 kB / 0.6211 GB, free: 1.938 MB / 462.1 MB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000271/0.4573, allocations: 31.98 kB / 0.6211 GB, free: 1.906 MB / 462.1 MB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0007517/0.4581, allocations: 47.91 kB / 0.6212 GB, free: 1.859 MB / 462.1 MB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002215/0.4583, allocations: 80.8 kB / 0.6213 GB, free: 1.781 MB / 462.1 MB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001578/0.4584, allocations: 83.91 kB / 0.6213 GB, free: 1.699 MB / 462.1 MB Notification: Performance of sorting global known variables: time 0.001115/0.4596, allocations: 0.5986 MB / 0.6219 GB, free: 1.09 MB / 462.1 MB Notification: Performance of sort global known variables: time 1.1e-07/0.4596, allocations: 3.938 kB / 0.6219 GB, free: 1.086 MB / 462.1 MB Notification: Performance of remove unused functions: time 0.001908/0.4615, allocations: 420 kB / 0.6223 GB, free: 0.6758 MB / 462.1 MB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 8 * Number of states: 6 (trafo1.i1[1],trafo1.i1[2],trafo1.i1[3],trafo2.i1[1],trafo2.i1[2],trafo2.i1[3]) * Number of discrete variables: 13 ($whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,tapRelay2.tap_1,tapRelay2.tap_2,tapRelay2.cnt_1,tapRelay2.cnt_2,tapRelay1.tap_1,tapRelay1.tap_2,tapRelay1.cnt_1,tapRelay1.cnt_2,system.initime) * Number of discrete states: 12 (tapRelay1.tap_2,tapRelay1.cnt_2,$whenCondition4,tapRelay1.tap_1,tapRelay1.cnt_1,$whenCondition3,tapRelay2.tap_2,tapRelay2.cnt_2,$whenCondition2,tapRelay2.tap_1,tapRelay2.cnt_1,$whenCondition1) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (121): * Single equations (assignments): 118 * Array equations: 0 * Algorithm blocks: 2 * Record equations: 0 * When equations: 1 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Performance of Backend phase and start with SimCode phase: time 0.004137/0.4656, allocations: 2.913 MB / 0.6252 GB, free: 13.7 MB / 478.1 MB Notification: Performance of simCode: created initialization part: time 0.004051/0.4697, allocations: 2.258 MB / 0.6274 GB, free: 11.37 MB / 478.1 MB Notification: Performance of simCode: created event and clocks part: time 5.711e-06/0.4697, allocations: 4.125 kB / 0.6274 GB, free: 11.37 MB / 478.1 MB Notification: Performance of simCode: created simulation system equations: time 0.001238/0.4709, allocations: 0.7812 MB / 0.6281 GB, free: 10.56 MB / 478.1 MB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001456/0.4724, allocations: 226.5 kB / 0.6284 GB, free: 10.34 MB / 478.1 MB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02198/0.4943, allocations: 13.12 MB / 0.6412 GB, free: 12.48 MB / 494.1 MB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001522/0.4959, allocations: 1.465 MB / 0.6426 GB, free: 10.98 MB / 494.1 MB Notification: Performance of simCode: alias equations: time 0.001172/0.497, allocations: 470.3 kB / 0.6431 GB, free: 10.52 MB / 494.1 MB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0008369/0.4979, allocations: 0.5696 MB / 0.6436 GB, free: 9.949 MB / 494.1 MB Notification: Performance of SimCode: time 9.62e-07/0.4979, allocations: 0 / 0.6436 GB, free: 9.949 MB / 494.1 MB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.07401/0.5719, allocations: 28.89 MB / 0.6718 GB, free: 13.26 MB / 0.5137 GB Notification: Performance of buildModelFMU: Generate platform static: time 14.35/14.93, allocations: 4.5 kB / 0.6718 GB, free: 13.26 MB / 0.5137 GB " [Timeout remaining time 645] (rm -f PowerSystems_PowerSystems.Examples.AC3ph.Transformation.TapChanger.pipe ; mkfifo PowerSystems_PowerSystems.Examples.AC3ph.Transformation.TapChanger.pipe ; head -c 1048576 < PowerSystems_PowerSystems.Examples.AC3ph.Transformation.TapChanger.pipe >> ../files/PowerSystems_PowerSystems.Examples.AC3ph.Transformation.TapChanger.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --tempDir=temp_PowerSystems_PowerSystems_Examples_AC3ph_Transformation_TapChanger_fmu --startTime=0 --stopTime=4 --stepSize=0.0016 --timeout=50 --tolerance=1e-06 PowerSystems_PowerSystems_Examples_AC3ph_Transformation_TapChanger.fmu > PowerSystems_PowerSystems.Examples.AC3ph.Transformation.TapChanger.pipe 2>&1) [Timeout 52.5] [Calling sys.exit(0), Time elapsed: 17.920814490993507] Failed to read output from testmodel.py, exit status != 0: 15.107846402970608 15.125983592 14.760516298 Calling exit ...