Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Characteristics.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.001353/0.001353, allocations: 122.5 kB / 18.51 MB, free: 4.539 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: 215.2 kB / 21.46 MB, free: 1.59 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.474/1.474, allocations: 230.5 MB / 254.8 MB, free: 15.11 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.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Characteristics,fileNamePrefix="Modelica_4_1_0-beta_om_Modelica_Magnetic_QuasiStatic_FundamentalWave_Examples_BasicMachines_InductionMachines_IMS_Characteristics",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Characteristics,fileNamePrefix="Modelica_4_1_0-beta_om_Modelica_Magnetic_QuasiStatic_FundamentalWave_Examples_BasicMachines_InductionMachines_IMS_Characteristics",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.001998/0.001998, allocations: 75.83 kB / 373.7 MB, free: 10.19 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1006/0.1026, allocations: 53.34 MB / 427.1 MB, free: 4.762 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Characteristics): time 0.2401/0.3427, allocations: 27.1 MB / 454.2 MB, free: 10.4 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01999/0.3627, allocations: 8.189 MB / 462.3 MB, free: 4.574 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.007386/0.3701, allocations: 237.1 kB / 462.6 MB, free: 4.5 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.02348/0.3936, allocations: 9.469 MB / 472 MB, free: 14.47 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01284/0.4064, allocations: 3.381 MB / 475.4 MB, free: 11.79 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.006418/0.4129, allocations: 1.824 MB / 477.2 MB, free: 10.37 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02038/0.4332, allocations: 12.25 MB / 489.5 MB, free: 2.535 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.03221/0.4654, allocations: 18.65 MB / 0.4962 GB, free: 15.7 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.012/0.4774, allocations: 6.589 MB / 0.5027 GB, free: 9.094 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01439/0.4918, allocations: 7.285 MB / 0.5098 GB, free: 1.785 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.005483/0.4973, allocations: 1.836 MB / 0.5116 GB, free: 15.95 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.007792/0.5051, allocations: 2.234 MB / 0.5138 GB, free: 13.71 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.006076/0.5112, allocations: 3.62 MB / 0.5173 GB, free: 10.09 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.01293/0.5241, allocations: 6.926 MB / 0.5241 GB, free: 3.133 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.03119/0.5553, allocations: 24.82 MB / 0.5483 GB, free: 10.24 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 7.334e-06/0.5553, allocations: 0 / 0.5483 GB, free: 10.24 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.864e-06/0.5553, allocations: 0 / 0.5483 GB, free: 10.24 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 0.0006007/0.5559, allocations: 0 / 0.5483 GB, free: 10.24 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3035
 * Number of variables: 3035
Notification: Performance of Generate backend data structure: time 0.0482/0.6041, allocations: 14.76 MB / 0.5627 GB, free: 11.36 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.931e-05/0.6042, allocations: 12.03 kB / 0.5627 GB, free: 11.35 MB / 478.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.006722/0.6109, allocations: 1.788 MB / 0.5645 GB, free: 9.547 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04967/0.6606, allocations: 8.436 MB / 0.5727 GB, free: 1.094 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01893/0.6795, allocations: 7.689 MB / 0.5802 GB, free: 9.32 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001045/0.6805, allocations: 0.8068 MB / 0.581 GB, free: 8.512 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.005115/0.6857, allocations: 1.058 MB / 0.582 GB, free: 7.453 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.05175/0.7374, allocations: 18.34 MB / 0.5999 GB, free: 5.055 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0005287/0.7379, allocations: 15.95 kB / 0.6 GB, free: 5.039 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.003892/0.7418, allocations: 447.9 kB / 0.6004 GB, free: 4.602 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0008905/0.7427, allocations: 0.6672 MB / 0.601 GB, free: 3.934 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04143/0.7842, allocations: 17.6 MB / 0.6182 GB, free: 2.32 MB / 0.5137 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.3092/1.093, allocations: 59.38 MB / 0.6762 GB, free: 111.4 MB / 0.5137 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0239/1.117, allocations: 36.99 MB / 0.7123 GB, free: 75.85 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005729/1.123, allocations: 3.754 MB / 0.716 GB, free: 73.87 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005308/1.124, allocations: 302.8 kB / 0.7163 GB, free: 73.64 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.536e-05/1.124, allocations: 89.34 kB / 0.7164 GB, free: 73.55 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=518): time 7.223e-06/1.124, allocations: 3.344 kB / 0.7164 GB, free: 73.55 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=524): time 0.04448/1.168, allocations: 21.6 MB / 0.7375 GB, free: 54.45 MB / 0.5137 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.84e-05/1.168, allocations: 103.5 kB / 0.7376 GB, free: 54.33 MB / 0.5137 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007311/1.175, allocations: 4.184 MB / 0.7417 GB, free: 50.12 MB / 0.5137 GB
Notification: Performance of collectPreVariables (initialization): time 0.001096/1.177, allocations: 109.7 kB / 0.7418 GB, free: 50 MB / 0.5137 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002478/1.179, allocations: 4.67 MB / 0.7463 GB, free: 45.32 MB / 0.5137 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002242/1.181, allocations: 1.864 MB / 0.7481 GB, free: 43.48 MB / 0.5137 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.006033/1.187, allocations: 1.375 MB / 0.7495 GB, free: 42.1 MB / 0.5137 GB
Notification: Performance of setup shared object (initialization): time 5.807e-05/1.187, allocations: 305.1 kB / 0.7498 GB, free: 41.8 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005523/1.193, allocations: 3.449 MB / 0.7531 GB, free: 38.35 MB / 0.5137 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006524/1.199, allocations: 5.892 MB / 0.7589 GB, free: 31.36 MB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01265/1.212, allocations: 10.04 MB / 0.7687 GB, free: 20.16 MB / 0.5137 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.445e-05/1.212, allocations: 27.94 kB / 0.7687 GB, free: 20.13 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=910) (initialization): time 0.02065/1.233, allocations: 10.95 MB / 0.7794 GB, free: 9.086 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001141/1.233, allocations: 40 kB / 0.7795 GB, free: 9.047 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001362/1.233, allocations: 76 kB / 0.7795 GB, free: 8.973 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003928/1.237, allocations: 1.446 MB / 0.7809 GB, free: 7.523 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006053/1.243, allocations: 1.622 MB / 0.7825 GB, free: 5.898 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01861/1.262, allocations: 11.49 MB / 0.7937 GB, free: 10.28 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005633/1.267, allocations: 411.4 kB / 0.7941 GB, free: 9.879 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001595/1.269, allocations: 336 kB / 0.7945 GB, free: 9.551 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 354
 * 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 (840):
 * Single equations (assignments): 838
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 1
 * 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
   {(14,56,41.3%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.009846/1.279, allocations: 4.161 MB / 0.7985 GB, free: 5.375 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001986/1.281, allocations: 0.493 MB / 0.799 GB, free: 4.879 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02081/1.301, allocations: 8.441 MB / 0.8072 GB, free: 12.33 MB / 0.545 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.016/1.317, allocations: 7.072 MB / 0.8142 GB, free: 5.164 MB / 0.545 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.112e-05/1.318, allocations: 15.94 kB / 0.8142 GB, free: 5.148 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001396/1.318, allocations: 27.89 kB / 0.8142 GB, free: 5.121 MB / 0.545 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04677/1.364, allocations: 18.77 MB / 0.8325 GB, free: 2.277 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.665e-05/1.364, allocations: 4 kB / 0.8325 GB, free: 2.273 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.004682/1.369, allocations: 0.9439 MB / 0.8335 GB, free: 1.328 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.004538/1.374, allocations: 1.305 MB / 0.8347 GB, free: 20 kB / 0.5606 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.2307/1.604, allocations: 112.2 kB / 0.8348 GB, free: 184.5 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01598/1.62, allocations: 10.18 MB / 0.8448 GB, free: 181.8 MB / 0.5606 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.144e-06/1.62, allocations: 4.938 kB / 0.8448 GB, free: 181.8 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02305/1.643, allocations: 12.29 MB / 0.8568 GB, free: 176.8 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003702/1.647, allocations: 1.184 MB / 0.8579 GB, free: 176.4 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.00198/1.649, allocations: 89.94 kB / 0.858 GB, free: 176.3 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00466/1.654, allocations: 270.6 kB / 0.8583 GB, free: 176.3 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001696/1.655, allocations: 355.6 kB / 0.8586 GB, free: 176.2 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0008789/1.656, allocations: 154.4 kB / 0.8588 GB, free: 176.2 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.006712/1.663, allocations: 2.819 MB / 0.8615 GB, free: 175.1 MB / 0.5606 GB
Notification: Performance of sort global known variables: time 3.11e-07/1.663, allocations: 0 / 0.8615 GB, free: 175.1 MB / 0.5606 GB
Notification: Performance of remove unused functions: time 0.008428/1.671, allocations: 1.934 MB / 0.8634 GB, free: 174.9 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 10
 * Number of states: 2 (imsQS.rotor.stray.port_p.reference.gamma,speed.phi)
 * 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 (499):
 * Single equations (assignments): 497
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 1
 * 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
   {(14,56,41.3%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.02126/1.693, allocations: 13.06 MB / 0.8762 GB, free: 169.2 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.01518/1.708, allocations: 8.399 MB / 0.8844 GB, free: 166 MB / 0.5606 GB
Notification: Performance of simCode: created event and clocks part: time 8.867e-06/1.708, allocations: 3.5 kB / 0.8844 GB, free: 166 MB / 0.5606 GB
Notification: Performance of simCode: created simulation system equations: time 0.009353/1.717, allocations: 3.899 MB / 0.8882 GB, free: 162.8 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01152/1.729, allocations: 1.135 MB / 0.8893 GB, free: 161.9 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1555/1.884, allocations: 63.97 MB / 0.9518 GB, free: 97.99 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.006973/1.891, allocations: 7.009 MB / 0.9586 GB, free: 90.94 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.00501/1.896, allocations: 2.32 MB / 0.9609 GB, free: 88.61 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.004439/1.901, allocations: 3.893 MB / 0.9647 GB, free: 84.72 MB / 0.5606 GB
Notification: Performance of SimCode: time 1.523e-06/1.901, allocations: 0 / 0.9647 GB, free: 84.72 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.4294/2.33, allocations: 150.8 MB / 1.112 GB, free: 153.5 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate platform static: time 22.13/24.46, allocations: 5.859 kB / 1.112 GB, free: 153.5 MB / 0.5606 GB
"
[Timeout remaining time 635]
(rm -f Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Characteristics.pipe ; mkfifo Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Characteristics.pipe ; head -c 1048576 < Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Characteristics.pipe >> ../files/Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Characteristics.sim & python3 -m fmpy simulate  --output-file Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Characteristics_res.csv --start-time 0 --stop-time 1 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.001 Modelica_4_1_0-beta_om_Modelica_Magnetic_QuasiStatic_FundamentalWave_Examples_BasicMachines_InductionMachines_IMS_Characteristics.fmu > Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Characteristics.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 27.095041748136282]
Failed to read output from testmodel.py, exit status != 0:
['time', 'resistor.plug_p.reference.gamma', 'speed.phi']
24.62493897974491 24.642076376 24.082991227
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7ff45fe997e0>