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_Start.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.00174/0.00174, allocations: 114.3 kB / 21.18 MB, free: 0.6211 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.002058/0.002058, allocations: 212.9 kB / 24.48 MB, free: 4.031 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.4/1.4, allocations: 230.4 MB / 258.1 MB, free: 8.16 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: translateModel(Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start,tolerance=1e-06,outputFormat="mat",numberOfIntervals=15000,variableFilter="time|Itr|Iqs|ims.rotor.electroMagneticConverter.singlePhaseElectroMagneticConverter.1..Phi.re|ims.rotor.electroMagneticConverter.singlePhaseElectroMagneticConverter.2..Phi.im|ims.stator.electroMagneticConverter.singlePhaseElectroMagneticConverter.1..Phi.re|ims.stator.electroMagneticConverter.singlePhaseElectroMagneticConverter.2..Phi.im|imsQS.rotor.electroMagneticConverter.port_p.reference.gamma|loadInertia.phi|loadInertia.w",fileNamePrefix="Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start")
translateModel(Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start,tolerance=1e-06,outputFormat="mat",numberOfIntervals=15000,variableFilter="time|Itr|Iqs|ims.rotor.electroMagneticConverter.singlePhaseElectroMagneticConverter.1..Phi.re|ims.rotor.electroMagneticConverter.singlePhaseElectroMagneticConverter.2..Phi.im|ims.stator.electroMagneticConverter.singlePhaseElectroMagneticConverter.1..Phi.re|ims.stator.electroMagneticConverter.singlePhaseElectroMagneticConverter.2..Phi.im|imsQS.rotor.electroMagneticConverter.port_p.reference.gamma|loadInertia.phi|loadInertia.w",fileNamePrefix="Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start") [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.00194/0.00194, allocations: 67.89 kB / 378.8 MB, free: 2.871 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2112/0.2131, allocations: 53.31 MB / 432.1 MB, free: 7.406 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start): time 0.05059/0.2637, allocations: 46.21 MB / 478.3 MB, free: 8.996 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.02293/0.2867, allocations: 12.13 MB / 490.4 MB, free: 12.83 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.005691/0.2924, allocations: 358.8 kB / 490.8 MB, free: 12.48 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.02876/0.3211, allocations: 12.58 MB / 0.4916 GB, free: 15.86 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01513/0.3362, allocations: 4.917 MB / 0.4964 GB, free: 10.92 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.008353/0.3446, allocations: 3.065 MB / 0.4994 GB, free: 7.871 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02658/0.3712, allocations: 21.12 MB / 0.52 GB, free: 2.684 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.3976/0.7688, allocations: 25.26 MB / 0.5447 GB, free: 15.73 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.01221/0.781, allocations: 11.02 MB / 0.5554 GB, free: 14.93 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01557/0.7966, allocations: 12.32 MB / 0.5675 GB, free: 13.01 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.005489/0.8021, allocations: 3.134 MB / 0.5705 GB, free: 13.01 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.006153/0.8082, allocations: 3.574 MB / 0.574 GB, free: 13.01 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.004222/0.8124, allocations: 6.273 MB / 0.5801 GB, free: 12.31 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.01089/0.8233, allocations: 11.92 MB / 0.5918 GB, free: 3.684 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.05121/0.8745, allocations: 42.17 MB / 0.633 GB, free: 13.93 MB / 494.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.418e-06/0.8745, allocations: 0 / 0.633 GB, free: 13.93 MB / 494.1 MB
Notification: Performance of FrontEnd: time 1.132e-06/0.8745, allocations: 0 / 0.633 GB, free: 13.93 MB / 494.1 MB
Notification: Performance of Transformations before backend: time 0.000859/0.8754, allocations: 0 / 0.633 GB, free: 13.93 MB / 494.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 5030
 * Number of variables: 5030
Notification: Performance of Generate backend data structure: time 0.07118/0.9466, allocations: 24.4 MB / 0.6568 GB, free: 5.57 MB / 0.4982 GB
Notification: Performance of prepare preOptimizeDAE: time 4.492e-05/0.9466, allocations: 8.031 kB / 0.6568 GB, free: 5.562 MB / 0.4982 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06799/1.015, allocations: 11.32 MB / 0.6679 GB, free: 10.36 MB / 0.5138 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.03119/1.046, allocations: 13.52 MB / 0.6811 GB, free: 13.32 MB / 0.5294 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001364/1.047, allocations: 1.357 MB / 0.6824 GB, free: 11.98 MB / 0.5294 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.005907/1.053, allocations: 1.79 MB / 0.6841 GB, free: 10.21 MB / 0.5294 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.066/1.119, allocations: 29.64 MB / 0.7131 GB, free: 12.49 MB / 0.5607 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0004682/1.12, allocations: 23.86 kB / 0.7131 GB, free: 12.47 MB / 0.5607 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.003136/1.123, allocations: 0.7422 MB / 0.7138 GB, free: 11.75 MB / 0.5607 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.00103/1.124, allocations: 0.9917 MB / 0.7148 GB, free: 10.75 MB / 0.5607 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.05469/1.178, allocations: 27.35 MB / 0.7415 GB, free: 15.38 MB / 0.5919 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.5135/1.692, allocations: 94.08 MB / 0.8334 GB, free: 149 MB / 0.592 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.03051/1.722, allocations: 51.77 MB / 0.8839 GB, free: 97.8 MB / 0.592 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00961/1.732, allocations: 6.714 MB / 0.8905 GB, free: 91.08 MB / 0.592 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.03017/1.762, allocations: 19.81 MB / 0.9098 GB, free: 71.56 MB / 0.592 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.2e-05/1.762, allocations: 116.5 kB / 0.9099 GB, free: 71.44 MB / 0.592 GB
Notification: Performance of pre-optimization done (n=677): time 8.456e-06/1.762, allocations: 0 / 0.9099 GB, free: 71.44 MB / 0.592 GB
Notification: Performance of matching and sorting (n=685): time 0.07444/1.837, allocations: 38.54 MB / 0.9476 GB, free: 33.25 MB / 0.592 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.803e-05/1.837, allocations: 151.1 kB / 0.9477 GB, free: 33.09 MB / 0.592 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007991/1.845, allocations: 7.153 MB / 0.9547 GB, free: 26.03 MB / 0.592 GB
Notification: Performance of collectPreVariables (initialization): time 0.0009361/1.846, allocations: 137.7 kB / 0.9548 GB, free: 25.89 MB / 0.592 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003065/1.849, allocations: 8.074 MB / 0.9627 GB, free: 18.52 MB / 0.592 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002264/1.851, allocations: 2.441 MB / 0.9651 GB, free: 16.11 MB / 0.592 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002548/1.854, allocations: 1.898 MB / 0.967 GB, free: 14.2 MB / 0.592 GB
Notification: Performance of setup shared object (initialization): time 3.661e-05/1.854, allocations: 305.1 kB / 0.9673 GB, free: 13.9 MB / 0.592 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006021/1.86, allocations: 4.867 MB / 0.972 GB, free: 9.027 MB / 0.592 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.2181/2.078, allocations: 8.338 MB / 0.9802 GB, free: 187.5 MB / 0.592 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01579/2.094, allocations: 14.04 MB / 0.9939 GB, free: 180.7 MB / 0.592 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.085e-05/2.094, allocations: 29.47 kB / 0.9939 GB, free: 180.7 MB / 0.592 GB
Notification: Performance of matching and sorting (n=1235) (initialization): time 0.02216/2.116, allocations: 16.08 MB / 1.01 GB, free: 174.5 MB / 0.592 GB
Notification: Performance of prepare postOptimizeDAE: time 8.279e-05/2.116, allocations: 56.59 kB / 1.01 GB, free: 174.5 MB / 0.592 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001825/2.116, allocations: 112.1 kB / 1.01 GB, free: 174.5 MB / 0.592 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005761/2.122, allocations: 2.577 MB / 1.012 GB, free: 173.8 MB / 0.592 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007212/2.129, allocations: 2.106 MB / 1.014 GB, free: 173.6 MB / 0.592 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02881/2.158, allocations: 30.79 MB / 1.044 GB, free: 153.2 MB / 0.592 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006834/2.165, allocations: 0.5221 MB / 1.045 GB, free: 153.1 MB / 0.592 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007775/2.165, allocations: 469.2 kB / 1.045 GB, free: 153 MB / 0.592 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 511
 * Number of states: 0 ()
 * Number of discrete variables: 12 (booleanStepQS[3].y,booleanStepQS[2].y,booleanStepQS[1].y,rheostatQS.booleanStep[3].y,rheostatQS.booleanStep[2].y,rheostatQS.booleanStep[1].y,rheostatM.booleanStep[3].y,rheostatM.booleanStep[2].y,rheostatM.booleanStep[1].y,idealCloser.control[3],idealCloser.control[2],idealCloser.control[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1089):
 * Single equations (assignments): 1079
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 1
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 8
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 1 system
   {(2,100.0%)}
 * Non-linear Jacobian (size): 0 systems
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 8 systems
   {(1,1,100.0%), (18,100,38.3%), (1,5,100.0%), (1,1,100.0%), (1,5,100.0%), (2,4,100.0%), (2,4,100.0%), (2,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.008347/2.174, allocations: 6.768 MB / 1.052 GB, free: 147.4 MB / 0.592 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001538/2.175, allocations: 0.8023 MB / 1.053 GB, free: 147 MB / 0.592 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01829/2.194, allocations: 13.01 MB / 1.065 GB, free: 135.9 MB / 0.592 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.382e-05/2.194, allocations: 35.86 kB / 1.065 GB, free: 135.9 MB / 0.592 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.374e-05/2.194, allocations: 0 / 1.065 GB, free: 135.9 MB / 0.592 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004238/2.198, allocations: 1.808 MB / 1.067 GB, free: 134.5 MB / 0.592 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001345/2.199, allocations: 124 kB / 1.067 GB, free: 134.4 MB / 0.592 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003913/2.203, allocations: 391.6 kB / 1.068 GB, free: 134 MB / 0.592 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0009228/2.204, allocations: 0.5911 MB / 1.068 GB, free: 133.4 MB / 0.592 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01917/2.223, allocations: 12.5 MB / 1.081 GB, free: 121 MB / 0.592 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01642/2.24, allocations: 14.58 MB / 1.095 GB, free: 106.3 MB / 0.592 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.004664/2.244, allocations: 4.429 MB / 1.099 GB, free: 101.9 MB / 0.592 GB
Notification: Performance of sorting global known variables: time 0.005832/2.25, allocations: 4.814 MB / 1.104 GB, free: 97.1 MB / 0.592 GB
Notification: Performance of Backend: time 2.8e-07/2.25, allocations: 0 / 1.104 GB, free: 97.1 MB / 0.592 GB
Notification: Performance of simCode: created initialization part: time 0.01483/2.265, allocations: 9.319 MB / 1.113 GB, free: 87.72 MB / 0.592 GB
Notification: Performance of SimCode: time 0.04548/2.311, allocations: 32.22 MB / 1.144 GB, free: 55.48 MB / 0.592 GB
Notification: Performance of Templates: time 0.4173/2.728, allocations: 151.7 MB / 1.293 GB, free: 140.2 MB / 0.6077 GB
"
[Timeout remaining time 657]
make -j1 -f Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start.makefile [Timeout 660]
(rm -f Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start.pipe ; mkfifo Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start.pipe ; head -c 1048576 < Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start.pipe >> ../files/Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start.sim & ./Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_4.1.0-beta.om_Modelica.Magnetic.QuasiStatic.FundamentalWave.Examples.BasicMachines.InductionMachines.IMS_Start.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 11.106004463974386]
Failed to read output from testmodel.py, exit status != 0:
['time', 'Itr', 'Iqs', 'ims.rotor.electroMagneticConverter.singlePhaseElectroMagneticConverter[1].Phi.re', 'ims.rotor.electroMagneticConverter.singlePhaseElectroMagneticConverter[2].Phi.im', 'ims.stator.electroMagneticConverter.singlePhaseElectroMagneticConverter[1].Phi.re', 'ims.stator.electroMagneticConverter.singlePhaseElectroMagneticConverter[2].Phi.im', 'imsQS.rotor.electroMagneticConverter.port_p.reference.gamma', 'loadInertia.phi', 'loadInertia.w']
2.735544417053461 2.744189026 1.867294357
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f74a10ee050>