Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.0.0_Modelica.Magnetic.FluxTubes.Examples.SolenoidActuator.ComparisonPullInStroke.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001644/0.001644, allocations: 107.9 kB / 18.48 MB, free: 4.543 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001829/0.001829, allocations: 198.6 kB / 21.43 MB, free: 1.609 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.437/1.437, allocations: 225.6 MB / 249.8 MB, free: 3.594 MB / 190.1 MB " [Timeout remaining time 178] 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: "" <> buildModelFMU(Modelica.Magnetic.FluxTubes.Examples.SolenoidActuator.ComparisonPullInStroke,fileNamePrefix="Modelica_4_0_0_Modelica_Magnetic_FluxTubes_Examples_SolenoidActuator_ComparisonPullInStroke",fmuType="cs",version="2.0",platforms={"static"}) "" <> buildModelFMU(Modelica.Magnetic.FluxTubes.Examples.SolenoidActuator.ComparisonPullInStroke,fileNamePrefix="Modelica_4_0_0_Modelica_Magnetic_FluxTubes_Examples_SolenoidActuator_ComparisonPullInStroke",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 0.001542/0.001542, allocations: 47.92 kB / 366.3 MB, free: 0.8242 MB / 286.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.107/0.1086, allocations: 52.15 MB / 418.5 MB, free: 12.58 MB / 350.1 MB Notification: Performance of NFInst.instantiate(Modelica.Magnetic.FluxTubes.Examples.SolenoidActuator.ComparisonPullInStroke): time 0.01403/0.1226, allocations: 11.14 MB / 429.6 MB, free: 1.402 MB / 350.1 MB Notification: Performance of NFInst.instExpressions: time 0.008048/0.1307, allocations: 3.658 MB / 433.3 MB, free: 13.73 MB / 366.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.002469/0.1331, allocations: 51.75 kB / 433.3 MB, free: 13.68 MB / 366.1 MB Notification: Performance of NFTyping.typeComponents: time 0.008287/0.1414, allocations: 8.128 MB / 441.5 MB, free: 5.535 MB / 366.1 MB Notification: Performance of NFTyping.typeBindings: time 0.006989/0.1484, allocations: 4.648 MB / 446.1 MB, free: 0.875 MB / 366.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.2098/0.3582, allocations: 0.9427 MB / 447 MB, free: 15.61 MB / 366.1 MB Notification: Performance of NFFlatten.flatten: time 0.007358/0.3656, allocations: 5.307 MB / 452.4 MB, free: 15.61 MB / 366.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.001569/0.3671, allocations: 0.6016 MB / 453 MB, free: 15.51 MB / 366.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.006875/0.374, allocations: 2.895 MB / 455.8 MB, free: 15.32 MB / 366.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.01/0.384, allocations: 5.794 MB / 461.6 MB, free: 15.02 MB / 366.1 MB Notification: Performance of NFPackage.collectConstants: time 0.001041/0.3851, allocations: 354 kB / 462 MB, free: 15.02 MB / 366.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.001321/0.3864, allocations: 384.7 kB / 462.4 MB, free: 15.02 MB / 366.1 MB Notification: Performance of NFScalarize.scalarize: time 0.001466/0.3878, allocations: 1.867 MB / 464.2 MB, free: 14.68 MB / 366.1 MB Notification: Performance of NFVerifyModel.verify: time 0.003761/0.3916, allocations: 2.791 MB / 467 MB, free: 13.16 MB / 366.1 MB Notification: Performance of NFConvertDAE.convert: time 0.008489/0.4001, allocations: 7.288 MB / 474.3 MB, free: 9.262 MB / 366.1 MB Notification: Performance of FrontEnd - DAE generated: time 6.553e-06/0.4001, allocations: 0 / 474.3 MB, free: 9.262 MB / 366.1 MB Notification: Performance of FrontEnd: time 2.404e-06/0.4001, allocations: 0 / 474.3 MB, free: 9.262 MB / 366.1 MB Notification: Performance of Transformations before backend: time 0.0004476/0.4006, allocations: 3.938 kB / 474.3 MB, free: 9.258 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: 528 * Number of variables: 528 Notification: Performance of Generate backend data structure: time 0.01516/0.4157, allocations: 7.939 MB / 482.3 MB, free: 3.445 MB / 366.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.398e-05/0.4158, allocations: 10.41 kB / 482.3 MB, free: 3.445 MB / 366.1 MB Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 6.937e-05/0.4158, allocations: 55.61 kB / 482.3 MB, free: 3.438 MB / 366.1 MB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0005117/0.4163, allocations: 290 kB / 482.6 MB, free: 3.207 MB / 366.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001314/0.4177, allocations: 0.8953 MB / 483.5 MB, free: 2.695 MB / 366.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.003611/0.4213, allocations: 3.249 MB / 486.7 MB, free: 480 kB / 366.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 9.333e-05/0.4214, allocations: 159.1 kB / 486.9 MB, free: 404 kB / 366.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002616/0.4216, allocations: 209.9 kB / 487.1 MB, free: 328 kB / 366.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.003932/0.4255, allocations: 2.122 MB / 489.2 MB, free: 15.04 MB / 382.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 5.313e-05/0.4256, allocations: 4 kB / 489.2 MB, free: 15.04 MB / 382.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002011/0.4258, allocations: 84 kB / 489.3 MB, free: 14.96 MB / 382.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.949e-05/0.4259, allocations: 48 kB / 489.4 MB, free: 14.91 MB / 382.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004284/0.4301, allocations: 1.891 MB / 491.3 MB, free: 13.02 MB / 382.1 MB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.01943/0.4496, allocations: 8.898 MB / 0.4884 GB, free: 4.078 MB / 382.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.004384/0.4539, allocations: 2.185 MB / 0.4906 GB, free: 1.84 MB / 382.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.002996/0.4569, allocations: 1.316 MB / 0.4918 GB, free: 0.5195 MB / 382.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.0001345/0.4571, allocations: 41.25 kB / 0.4919 GB, free: 500 kB / 382.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002294/0.4594, allocations: 1.157 MB / 0.493 GB, free: 15.29 MB / 398.1 MB Notification: Performance of pre-optimization done (n=242): time 3.677e-06/0.4594, allocations: 0 / 0.493 GB, free: 15.29 MB / 398.1 MB Notification: Performance of matching and sorting (n=367): time 0.07274/0.5321, allocations: 23.17 MB / 0.5156 GB, free: 8.027 MB / 414.1 MB Notification: Performance of inlineWhenForInitialization (initialization): time 0.03117/0.5633, allocations: 13.56 MB / 0.5289 GB, free: 10.19 MB / 430.1 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.02655/0.5898, allocations: 14.9 MB / 0.5434 GB, free: 11.36 MB / 446.1 MB Notification: Performance of collectPreVariables (initialization): time 0.001049/0.5909, allocations: 85.7 kB / 0.5435 GB, free: 11.27 MB / 446.1 MB Notification: Performance of collectInitialEqns (initialization): time 0.005941/0.5968, allocations: 3.745 MB / 0.5472 GB, free: 7.523 MB / 446.1 MB Notification: Performance of collectInitialBindings (initialization): time 0.001867/0.5987, allocations: 1.273 MB / 0.5484 GB, free: 6.266 MB / 446.1 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.001135/0.5998, allocations: 0.5158 MB / 0.5489 GB, free: 5.742 MB / 446.1 MB Notification: Performance of setup shared object (initialization): time 0.0001429/0.6, allocations: 305.1 kB / 0.5492 GB, free: 5.441 MB / 446.1 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.002891/0.6029, allocations: 1.255 MB / 0.5504 GB, free: 4.188 MB / 446.1 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.003319/0.6062, allocations: 1.627 MB / 0.552 GB, free: 2.48 MB / 446.1 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.006164/0.6123, allocations: 2.993 MB / 0.5549 GB, free: 15.42 MB / 462.1 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.127e-05/0.6124, allocations: 4 kB / 0.555 GB, free: 15.42 MB / 462.1 MB Notification: Performance of matching and sorting (n=431) (initialization): time 0.01405/0.6264, allocations: 5.203 MB / 0.56 GB, free: 10.18 MB / 462.1 MB Notification: Performance of prepare postOptimizeDAE: time 3.236e-05/0.6264, allocations: 8 kB / 0.56 GB, free: 10.17 MB / 462.1 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.113e-05/0.6265, allocations: 8 kB / 0.56 GB, free: 10.16 MB / 462.1 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.005929/0.6324, allocations: 1.383 MB / 0.5614 GB, free: 8.758 MB / 462.1 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003012/0.6354, allocations: 0.5422 MB / 0.5619 GB, free: 8.215 MB / 462.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03237/0.6678, allocations: 23.98 MB / 0.5853 GB, free: 15.03 MB / 494.1 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00421/0.672, allocations: 135.9 kB / 0.5855 GB, free: 14.9 MB / 494.1 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005845/0.6726, allocations: 113.5 kB / 0.5856 GB, free: 14.79 MB / 494.1 MB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 22 * Number of states: 0 () * Number of discrete variables: 9 ($PRE.comparisonWithFEA.nextTimeEvent,$whenCondition1,comparisonWithFEA.nextTimeEventScaled,$PRE.comparisonWithFEA.nextTimeEventScaled,comparisonWithFEA.nextTimeEvent,simpleSolenoid.armature.stopper_xMin.contact,simpleSolenoid.armature.stopper_xMax.contact,advancedSolenoid.armature.stopper_xMin.contact,advancedSolenoid.armature.stopper_xMax.contact) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (309): * Single equations (assignments): 300 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 9 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 7 systems {(1,3,100.0%), (1,3,100.0%), (1,4,100.0%), (1,4,100.0%), (1,3,100.0%), (1,5,100.0%), (1,4,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 2 systems {(2,43), (3,50)} Notification: Performance of prepare postOptimizeDAE: time 0.005786/0.6784, allocations: 2.184 MB / 0.5877 GB, free: 12.59 MB / 494.1 MB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001838/0.6802, allocations: 0.8653 MB / 0.5886 GB, free: 11.72 MB / 494.1 MB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01387/0.6941, allocations: 5.171 MB / 0.5936 GB, free: 6.508 MB / 494.1 MB Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.003e-05/0.6941, allocations: 39.94 kB / 0.5937 GB, free: 6.469 MB / 494.1 MB Notification: Performance of postOpt constantLinearSystem (simulation): time 2.499e-05/0.6941, allocations: 11.98 kB / 0.5937 GB, free: 6.457 MB / 494.1 MB Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.587e-05/0.6942, allocations: 23.88 kB / 0.5937 GB, free: 6.434 MB / 494.1 MB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02559/0.7198, allocations: 11.01 MB / 0.6044 GB, free: 11.38 MB / 0.4981 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.46e-05/0.7198, allocations: 4 kB / 0.6044 GB, free: 11.38 MB / 0.4981 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002738/0.7226, allocations: 455.4 kB / 0.6049 GB, free: 10.93 MB / 0.4981 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.00589/0.7284, allocations: 1.331 MB / 0.6062 GB, free: 9.574 MB / 0.4981 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0006316/0.7291, allocations: 75.97 kB / 0.6062 GB, free: 9.5 MB / 0.4981 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.029/0.7581, allocations: 21.87 MB / 0.6276 GB, free: 2.398 MB / 0.5137 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 0.2244/0.9824, allocations: 11.89 MB / 0.6392 GB, free: 161.9 MB / 0.5137 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01528/0.9977, allocations: 6.949 MB / 0.646 GB, free: 160.2 MB / 0.5137 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001708/0.9994, allocations: 0.7952 MB / 0.6468 GB, free: 160.1 MB / 0.5137 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001305/1.001, allocations: 71.94 kB / 0.6468 GB, free: 160.1 MB / 0.5137 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00291/1.004, allocations: 111.5 kB / 0.647 GB, free: 160 MB / 0.5137 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001034/1.005, allocations: 270.4 kB / 0.6472 GB, free: 160 MB / 0.5137 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002407/1.005, allocations: 96.61 kB / 0.6473 GB, free: 160 MB / 0.5137 GB Notification: Performance of sorting global known variables: time 0.02056/1.025, allocations: 11.13 MB / 0.6582 GB, free: 156.3 MB / 0.5137 GB Notification: Performance of sort global known variables: time 2.71e-07/1.025, allocations: 0 / 0.6582 GB, free: 156.3 MB / 0.5137 GB Notification: Performance of remove unused functions: time 0.005143/1.031, allocations: 1.075 MB / 0.6592 GB, free: 156.1 MB / 0.5137 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 6 * Number of states: 9 (advancedSolenoid.x,advancedSolenoid.coil1.i,advancedSolenoid.armature.stopper_xMin.v_rel,advancedSolenoid.coil2.i,advancedSolenoid.c_par1.v,advancedSolenoid.c_par2.v,$STATESET1.x,simpleSolenoid.x,simpleSolenoid.armature.stopper_xMin.v_rel) * Number of discrete variables: 7 (advancedSolenoid.armature.stopper_xMax.contact,advancedSolenoid.armature.stopper_xMin.contact,simpleSolenoid.armature.stopper_xMax.contact,simpleSolenoid.armature.stopper_xMin.contact,$whenCondition1,comparisonWithFEA.nextTimeEvent,comparisonWithFEA.nextTimeEventScaled) * Number of discrete states: 2 (comparisonWithFEA.nextTimeEventScaled,comparisonWithFEA.nextTimeEvent) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (253): * Single equations (assignments): 242 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 2 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 9 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 7 systems {(1,2,100.0%), (1,2,100.0%), (1,4,100.0%), (1,5,100.0%), (1,3,100.0%), (1,2,100.0%), (1,2,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 2 systems {(3,49), (4,42)} Notification: Performance of Backend phase and start with SimCode phase: time 0.01547/1.046, allocations: 7.94 MB / 0.667 GB, free: 152.4 MB / 0.5137 GB Notification: Performance of simCode: created initialization part: time 0.0078/1.054, allocations: 4.63 MB / 0.6715 GB, free: 149 MB / 0.5137 GB Notification: Performance of simCode: created event and clocks part: time 1.129e-05/1.054, allocations: 4 kB / 0.6715 GB, free: 149 MB / 0.5137 GB Notification: Performance of simCode: created simulation system equations: time 0.004631/1.059, allocations: 3.161 MB / 0.6746 GB, free: 146 MB / 0.5137 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004124/1.063, allocations: 0.5406 MB / 0.6751 GB, free: 145.6 MB / 0.5137 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1038/1.166, allocations: 55.84 MB / 0.7296 GB, free: 92.68 MB / 0.5137 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003451/1.17, allocations: 4.009 MB / 0.7336 GB, free: 88.62 MB / 0.5137 GB Notification: Performance of simCode: alias equations: time 0.001722/1.172, allocations: 308.9 kB / 0.7339 GB, free: 88.32 MB / 0.5137 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002706/1.174, allocations: 3.221 MB / 0.737 GB, free: 85.11 MB / 0.5137 GB Notification: Performance of SimCode: time 1.052e-06/1.174, allocations: 0 / 0.737 GB, free: 85.11 MB / 0.5137 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.4638/1.638, allocations: 143 MB / 0.8766 GB, free: 140.7 MB / 0.5294 GB Notification: Performance of buildModelFMU: Generate platform static: time 83.89/85.53, allocations: 4.688 kB / 0.8766 GB, free: 140.7 MB / 0.5294 GB " [Timeout remaining time 574] (rm -f Modelica_4.0.0_Modelica.Magnetic.FluxTubes.Examples.SolenoidActuator.ComparisonPullInStroke.pipe ; mkfifo Modelica_4.0.0_Modelica.Magnetic.FluxTubes.Examples.SolenoidActuator.ComparisonPullInStroke.pipe ; head -c 1048576 < Modelica_4.0.0_Modelica.Magnetic.FluxTubes.Examples.SolenoidActuator.ComparisonPullInStroke.pipe >> ../files/Modelica_4.0.0_Modelica.Magnetic.FluxTubes.Examples.SolenoidActuator.ComparisonPullInStroke.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Modelica_4.0.0_Modelica.Magnetic.FluxTubes.Examples.SolenoidActuator.ComparisonPullInStroke_res.mat --tempDir=temp_Modelica_4_0_0_Modelica_Magnetic_FluxTubes_Examples_SolenoidActuator_ComparisonPullInStroke_fmu --startTime=0 --stopTime=0.05 --stepSize=9.01713e-06 --timeout=50 --tolerance=1e-07 Modelica_4_0_0_Modelica_Magnetic_FluxTubes_Examples_SolenoidActuator_ComparisonPullInStroke.fmu > Modelica_4.0.0_Modelica.Magnetic.FluxTubes.Examples.SolenoidActuator.ComparisonPullInStroke.pipe 2>&1) [Timeout 52.5] [Calling os._exit(0), Time elapsed: 88.45308427000418] Failed to read output from testmodel.py, exit status != 0: ['time', 'advancedSolenoid.armature.stopper_xMax.v_rel', 'advancedSolenoid.c_par1.v', 'advancedSolenoid.c_par2.v', 'advancedSolenoid.coil1.Phi', 'advancedSolenoid.coil2.Phi', 'advancedSolenoid.x', 'simpleSolenoid.armature.stopper_xMax.v_rel', 'simpleSolenoid.coil.Phi', 'simpleSolenoid.x'] 85.8301295809797 85.840625057 85.438082003 Calling exit ...