Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0-beta.om_Modelica.Electrical.QuasiStatic.Machines.Examples.TransformerTestbench.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001251/0.001251, allocations: 110.3 kB / 20.22 MB, free: 1.812 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.00137/0.00137, allocations: 218.2 kB / 23.48 MB, free: 5.254 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo): time 1.398/1.398, allocations: 230.4 MB / 257 MB, free: 8.285 MB / 206.1 MB
"
[Timeout remaining time 178]
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo)
Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
Running command: "" <> buildModelFMU(Modelica.Electrical.QuasiStatic.Machines.Examples.TransformerTestbench,fileNamePrefix="Modelica_4_1_0-beta_om_Modelica_Electrical_QuasiStatic_Machines_Examples_TransformerTestbench",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Modelica.Electrical.QuasiStatic.Machines.Examples.TransformerTestbench,fileNamePrefix="Modelica_4_1_0-beta_om_Modelica_Electrical_QuasiStatic_Machines_Examples_TransformerTestbench",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Warning: Requested package Modelica of version trunk, but this package was already loaded with version 4.1.0. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Warning: Requested package Complex of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Warning: Requested package ModelicaServices of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Notification: Performance of FrontEnd - loaded program: time 0.001919/0.001919, allocations: 67.89 kB / 377.1 MB, free: 3.594 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3022/0.3041, allocations: 53.31 MB / 430.4 MB, free: 7.238 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.QuasiStatic.Machines.Examples.TransformerTestbench): time 0.03262/0.3367, allocations: 28.77 MB / 459.2 MB, free: 10.34 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01743/0.3542, allocations: 7.222 MB / 466.4 MB, free: 3.102 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00654/0.3607, allocations: 263.2 kB / 466.6 MB, free: 2.844 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.03018/0.3909, allocations: 13.23 MB / 479.9 MB, free: 5.586 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01141/0.4023, allocations: 2.566 MB / 482.4 MB, free: 3.012 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.006971/0.4093, allocations: 2.037 MB / 484.5 MB, free: 0.9766 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02591/0.4352, allocations: 18.35 MB / 0.491 GB, free: 14.57 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.06399/0.4992, allocations: 26.94 MB / 0.5173 GB, free: 3.414 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.01752/0.5167, allocations: 9.968 MB / 0.5271 GB, free: 9.434 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.4751/0.9917, allocations: 11.13 MB / 0.5379 GB, free: 30.34 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.009991/1.002, allocations: 2.919 MB / 0.5408 GB, free: 30.34 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0121/1.014, allocations: 3.196 MB / 0.5439 GB, free: 30.34 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.01002/1.024, allocations: 5.49 MB / 0.5493 GB, free: 30.34 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0251/1.049, allocations: 10.82 MB / 0.5598 GB, free: 28.19 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.04253/1.091, allocations: 39.1 MB / 0.598 GB, free: 5.57 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 7.654e-06/1.091, allocations: 0 / 0.598 GB, free: 5.57 MB / 446.1 MB
Notification: Performance of FrontEnd: time 2.565e-06/1.091, allocations: 0 / 0.598 GB, free: 5.57 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.001305/1.093, allocations: 0 / 0.598 GB, free: 5.57 MB / 446.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 4885
 * Number of variables: 4885
Notification: Performance of Generate backend data structure: time 0.07443/1.167, allocations: 22.31 MB / 0.6198 GB, free: 3.352 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 7.015e-05/1.167, allocations: 12.03 kB / 0.6198 GB, free: 3.34 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.01461/1.182, allocations: 2.955 MB / 0.6227 GB, free: 392 kB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04697/1.229, allocations: 8.246 MB / 0.6308 GB, free: 8.207 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02994/1.259, allocations: 11.68 MB / 0.6422 GB, free: 12.96 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001698/1.261, allocations: 1.342 MB / 0.6435 GB, free: 11.63 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.008056/1.269, allocations: 1.76 MB / 0.6452 GB, free: 9.883 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.08116/1.35, allocations: 28.78 MB / 0.6733 GB, free: 13.07 MB / 0.5138 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0009556/1.351, allocations: 11.95 kB / 0.6733 GB, free: 13.05 MB / 0.5138 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.007389/1.358, allocations: 0.7328 MB / 0.674 GB, free: 12.34 MB / 0.5138 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0009698/1.359, allocations: 0.8454 MB / 0.6749 GB, free: 11.49 MB / 0.5138 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.05868/1.418, allocations: 24.84 MB / 0.6991 GB, free: 2.629 MB / 0.5294 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.4429/1.861, allocations: 87.52 MB / 0.7846 GB, free: 147.2 MB / 0.5763 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02213/1.883, allocations: 34.59 MB / 0.8184 GB, free: 119.7 MB / 0.5763 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007841/1.891, allocations: 5.054 MB / 0.8233 GB, free: 119.6 MB / 0.5763 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005531/1.891, allocations: 330.3 kB / 0.8236 GB, free: 119.4 MB / 0.5763 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.928e-05/1.891, allocations: 91.3 kB / 0.8237 GB, free: 119.4 MB / 0.5763 GB
Notification: Performance of pre-optimization done (n=577): time 7.775e-06/1.891, allocations: 0 / 0.8237 GB, free: 119.4 MB / 0.5763 GB
Notification: Performance of matching and sorting (n=577): time 0.02956/1.921, allocations: 14.77 MB / 0.8381 GB, free: 115.1 MB / 0.5763 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.477e-05/1.921, allocations: 138.4 kB / 0.8383 GB, free: 115 MB / 0.5763 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008954/1.93, allocations: 6.427 MB / 0.8445 GB, free: 111.7 MB / 0.5763 GB
Notification: Performance of collectPreVariables (initialization): time 0.001102/1.931, allocations: 116.6 kB / 0.8446 GB, free: 111.6 MB / 0.5763 GB
Notification: Performance of collectInitialEqns (initialization): time 0.005729/1.937, allocations: 7.901 MB / 0.8524 GB, free: 104.7 MB / 0.5763 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003056/1.94, allocations: 2.111 MB / 0.8544 GB, free: 102.9 MB / 0.5763 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003422/1.943, allocations: 2.121 MB / 0.8565 GB, free: 101.7 MB / 0.5763 GB
Notification: Performance of setup shared object (initialization): time 5.046e-05/1.943, allocations: 305.1 kB / 0.8568 GB, free: 101.4 MB / 0.5763 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005595/1.949, allocations: 4.745 MB / 0.8614 GB, free: 98.88 MB / 0.5763 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.008271/1.957, allocations: 9.476 MB / 0.8707 GB, free: 89.93 MB / 0.5763 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01547/1.972, allocations: 15.33 MB / 0.8856 GB, free: 72.88 MB / 0.5763 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002172/1.973, allocations: 48 kB / 0.8857 GB, free: 72.83 MB / 0.5763 GB
Notification: Performance of matching and sorting (n=1330) (initialization): time 0.02613/1.999, allocations: 15.41 MB / 0.9007 GB, free: 57.34 MB / 0.5763 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002075/1.999, allocations: 76.09 kB / 0.9008 GB, free: 57.27 MB / 0.5763 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002968/1.999, allocations: 164 kB / 0.901 GB, free: 57.11 MB / 0.5763 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005148/2.004, allocations: 1.653 MB / 0.9026 GB, free: 55.45 MB / 0.5763 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007572/2.012, allocations: 2.363 MB / 0.9049 GB, free: 53.08 MB / 0.5763 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01299/2.025, allocations: 11.62 MB / 0.9162 GB, free: 41.33 MB / 0.5763 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007973/2.033, allocations: 1.37 MB / 0.9176 GB, free: 39.95 MB / 0.5763 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001957/2.035, allocations: 0.5351 MB / 0.9181 GB, free: 39.42 MB / 0.5763 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 728
 * 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 (1243):
 * Single equations (assignments): 1240
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 2
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * 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,80,77.8%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.01282/2.048, allocations: 7.149 MB / 0.9251 GB, free: 32.25 MB / 0.5763 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001371/2.049, allocations: 0.6481 MB / 0.9257 GB, free: 31.6 MB / 0.5763 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01277/2.062, allocations: 10.06 MB / 0.9355 GB, free: 21.42 MB / 0.5763 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.00906/2.071, allocations: 7.765 MB / 0.9431 GB, free: 13.57 MB / 0.5763 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.835e-05/2.071, allocations: 12 kB / 0.9431 GB, free: 13.56 MB / 0.5763 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.074e-05/2.071, allocations: 31.88 kB / 0.9432 GB, free: 13.53 MB / 0.5763 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04794/2.119, allocations: 25.7 MB / 0.9683 GB, free: 3.578 MB / 0.5919 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.475e-05/2.119, allocations: 9 kB / 0.9683 GB, free: 3.57 MB / 0.5919 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003936/2.123, allocations: 1.018 MB / 0.9693 GB, free: 2.551 MB / 0.5919 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.005115/2.128, allocations: 1.469 MB / 0.9707 GB, free: 1.074 MB / 0.5919 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0009128/2.129, allocations: 132 kB / 0.9708 GB, free: 0.9453 MB / 0.5919 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.2807/2.41, allocations: 11.15 MB / 0.9817 GB, free: 186.3 MB / 0.5919 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.353e-05/2.41, allocations: 13 kB / 0.9817 GB, free: 186.3 MB / 0.5919 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01762/2.427, allocations: 14.14 MB / 0.9955 GB, free: 180.4 MB / 0.5919 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003646/2.431, allocations: 1.475 MB / 0.997 GB, free: 179.6 MB / 0.5919 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001217/2.432, allocations: 112.8 kB / 0.9971 GB, free: 179.5 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003108/2.435, allocations: 316.5 kB / 0.9974 GB, free: 179.4 MB / 0.5919 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008541/2.436, allocations: 411 kB / 0.9978 GB, free: 179.4 MB / 0.5919 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003767/2.437, allocations: 174.5 kB / 0.9979 GB, free: 179.3 MB / 0.5919 GB
Notification: Performance of sorting global known variables: time 0.007136/2.444, allocations: 4.307 MB / 1.002 GB, free: 177.8 MB / 0.5919 GB
Notification: Performance of sort global known variables: time 2.11e-07/2.444, allocations: 0 / 1.002 GB, free: 177.8 MB / 0.5919 GB
Notification: Performance of remove unused functions: time 0.01365/2.457, allocations: 4.086 MB / 1.006 GB, free: 176.5 MB / 0.5919 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 36
 * Number of states: 1 (source.plug_p.reference.gamma)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (528):
 * Single equations (assignments): 525
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 2
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * 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,80,77.8%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.02019/2.478, allocations: 14.93 MB / 1.021 GB, free: 169.5 MB / 0.5919 GB
Notification: Performance of simCode: created initialization part: time 0.01834/2.496, allocations: 10.95 MB / 1.031 GB, free: 164.9 MB / 0.5919 GB
Notification: Performance of simCode: created event and clocks part: time 1.521e-05/2.496, allocations: 10.41 kB / 1.031 GB, free: 164.9 MB / 0.5919 GB
Notification: Performance of simCode: created simulation system equations: time 0.007567/2.503, allocations: 4.341 MB / 1.036 GB, free: 161.2 MB / 0.5919 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.007829/2.511, allocations: 1.566 MB / 1.037 GB, free: 159.9 MB / 0.5919 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1397/2.651, allocations: 94.74 MB / 1.13 GB, free: 62.19 MB / 0.5919 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01851/2.67, allocations: 11.26 MB / 1.141 GB, free: 50.88 MB / 0.5919 GB
Notification: Performance of simCode: alias equations: time 0.008384/2.678, allocations: 3.77 MB / 1.144 GB, free: 47.1 MB / 0.5919 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.009186/2.687, allocations: 5.947 MB / 1.15 GB, free: 41.16 MB / 0.5919 GB
Notification: Performance of SimCode: time 1.683e-06/2.687, allocations: 0 / 1.15 GB, free: 41.16 MB / 0.5919 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.5546/3.242, allocations: 204.2 MB / 1.35 GB, free: 91.17 MB / 0.6075 GB
Notification: Performance of buildModelFMU: Generate platform static: time 27/30.25, allocations: 15.34 kB / 1.35 GB, free: 91.16 MB / 0.6075 GB
"
[Timeout remaining time 630]
(rm -f Modelica_4.1.0-beta.om_Modelica.Electrical.QuasiStatic.Machines.Examples.TransformerTestbench.pipe ; mkfifo Modelica_4.1.0-beta.om_Modelica.Electrical.QuasiStatic.Machines.Examples.TransformerTestbench.pipe ; head -c 1048576 < Modelica_4.1.0-beta.om_Modelica.Electrical.QuasiStatic.Machines.Examples.TransformerTestbench.pipe >> ../files/Modelica_4.1.0-beta.om_Modelica.Electrical.QuasiStatic.Machines.Examples.TransformerTestbench.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Modelica_4.1.0-beta.om_Modelica.Electrical.QuasiStatic.Machines.Examples.TransformerTestbench_res.mat --tempDir=temp_Modelica_4_1_0-beta_om_Modelica_Electrical_QuasiStatic_Machines_Examples_TransformerTestbench_fmu --startTime=0 --stopTime=0.1 --stepSize=0.001 --timeout=50 --tolerance=1e-06 Modelica_4_1_0-beta_om_Modelica_Electrical_QuasiStatic_Machines_Examples_TransformerTestbench.fmu > Modelica_4.1.0-beta.om_Modelica.Electrical.QuasiStatic.Machines.Examples.TransformerTestbench.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 33.22742992290296]
Failed to read output from testmodel.py, exit status != 0:
['time', 'source.plug_p.reference.gamma']
30.42518253903836 30.440691008 29.345500346
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f7b80216020>