Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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: translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic")
translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001236/0.001236, allocations: 109.9 kB / 17.06 MB, free: 5.969 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001357/0.001357, allocations: 200 kB / 18.01 MB, free: 5.055 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.494/1.494, allocations: 205.1 MB / 223.9 MB, free: 12.21 MB / 190.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.001439/0.001439, allocations: 55.92 kB / 327.9 MB, free: 3.266 MB / 270.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.09885/0.1003, allocations: 46.61 MB / 374.5 MB, free: 4.543 MB / 318.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic): time 0.2282/0.3285, allocations: 23.46 MB / 398 MB, free: 14.58 MB / 334.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01904/0.3476, allocations: 7.086 MB / 405.1 MB, free: 9.789 MB / 334.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.007393/0.355, allocations: 177.9 kB / 405.2 MB, free: 9.754 MB / 334.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01806/0.3732, allocations: 7.946 MB / 413.2 MB, free: 5.328 MB / 334.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0137/0.3869, allocations: 3.592 MB / 416.8 MB, free: 2.664 MB / 334.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.007305/0.3943, allocations: 2.008 MB / 418.8 MB, free: 1.285 MB / 334.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02314/0.4175, allocations: 12.25 MB / 431 MB, free: 6.879 MB / 350.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01539/0.4329, allocations: 10.46 MB / 441.5 MB, free: 12.36 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0101/0.443, allocations: 4.289 MB / 445.8 MB, free: 8.055 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01051/0.4536, allocations: 5.726 MB / 451.5 MB, free: 2.312 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.004013/0.4577, allocations: 1.004 MB / 452.5 MB, free: 1.309 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.006707/0.4644, allocations: 2.162 MB / 454.7 MB, free: 15.14 MB / 382.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.008733/0.4732, allocations: 5.141 MB / 459.8 MB, free: 9.992 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.01636/0.4896, allocations: 5.425 MB / 465.2 MB, free: 4.539 MB / 382.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02427/0.5139, allocations: 17.28 MB / 482.5 MB, free: 3.203 MB / 398.1 MB
Notification: Performance of FrontEnd - DAE generated: time 9.448e-06/0.5139, allocations: 0 / 482.5 MB, free: 3.203 MB / 398.1 MB
Notification: Performance of FrontEnd: time 4.208e-06/0.514, allocations: 0 / 482.5 MB, free: 3.203 MB / 398.1 MB
Notification: Performance of Transformations before backend: time 0.0006208/0.5146, allocations: 0 / 482.5 MB, free: 3.203 MB / 398.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2753
 * Number of variables: 2753
Notification: Performance of Generate backend data structure: time 0.03443/0.549, allocations: 11.22 MB / 493.7 MB, free: 7.883 MB / 414.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.624e-05/0.5491, allocations: 8.031 kB / 493.7 MB, free: 7.875 MB / 414.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04985/0.5989, allocations: 5.426 MB / 499.2 MB, free: 2.43 MB / 414.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0257/0.6247, allocations: 9.348 MB / 0.4966 GB, free: 8.988 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005854/0.6253, allocations: 0.5181 MB / 0.4971 GB, free: 8.469 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003824/0.6291, allocations: 0.7298 MB / 0.4978 GB, free: 7.738 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04408/0.6732, allocations: 19.52 MB / 0.5169 GB, free: 3.172 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0004641/0.6737, allocations: 91.25 kB / 0.517 GB, free: 3.082 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00229/0.676, allocations: 327.8 kB / 0.5173 GB, free: 2.762 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004432/0.6805, allocations: 2.814 MB / 0.52 GB, free: 15.94 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03987/0.7204, allocations: 16.25 MB / 0.5359 GB, free: 15.67 MB / 478.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.5138/1.234, allocations: 74.17 MB / 0.6083 GB, free: 93.7 MB / 478.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0263/1.261, allocations: 8.48 MB / 0.6166 GB, free: 89.64 MB / 478.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.009028/1.27, allocations: 4.769 MB / 0.6213 GB, free: 87.05 MB / 478.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.03582/1.305, allocations: 15.41 MB / 0.6363 GB, free: 74.93 MB / 478.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.934e-05/1.306, allocations: 66.69 kB / 0.6364 GB, free: 74.86 MB / 478.1 MB
Notification: Performance of pre-optimization done (n=319): time 9.568e-06/1.306, allocations: 4 kB / 0.6364 GB, free: 74.85 MB / 478.1 MB
Notification: Performance of matching and sorting (n=419): time 0.199/1.505, allocations: 50.46 MB / 0.6857 GB, free: 24.37 MB / 478.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001099/1.505, allocations: 406.7 kB / 0.6861 GB, free: 23.89 MB / 478.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01228/1.517, allocations: 7.647 MB / 0.6935 GB, free: 16.28 MB / 478.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.001251/1.518, allocations: 89.7 kB / 0.6936 GB, free: 16.18 MB / 478.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.00328/1.522, allocations: 5.617 MB / 0.6991 GB, free: 10.57 MB / 478.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.002037/1.524, allocations: 1.53 MB / 0.7006 GB, free: 9.047 MB / 478.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.007069/1.531, allocations: 2.083 MB / 0.7026 GB, free: 6.953 MB / 478.1 MB
Notification: Performance of setup shared object (initialization): time 4.909e-05/1.531, allocations: 305.1 kB / 0.7029 GB, free: 6.652 MB / 478.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006879/1.538, allocations: 5.123 MB / 0.7079 GB, free: 1.523 MB / 478.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01206/1.55, allocations: 10.2 MB / 0.7179 GB, free: 4.891 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02319/1.573, allocations: 16.15 MB / 0.7336 GB, free: 2.305 MB / 0.4982 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002008/1.573, allocations: 48 kB / 0.7337 GB, free: 2.258 MB / 0.4982 GB
Notification: Performance of matching and sorting (n=1332) (initialization): time 0.2937/1.867, allocations: 20.85 MB / 0.7541 GB, free: 166.3 MB / 0.5138 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001937/1.867, allocations: 80.75 kB / 0.7541 GB, free: 166.3 MB / 0.5138 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002687/1.868, allocations: 174.3 kB / 0.7543 GB, free: 166.3 MB / 0.5138 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02316/1.891, allocations: 6.452 MB / 0.7606 GB, free: 166.3 MB / 0.5138 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008842/1.9, allocations: 2.15 MB / 0.7627 GB, free: 166.3 MB / 0.5138 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04943/1.949, allocations: 33.58 MB / 0.7955 GB, free: 153.2 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01173/1.961, allocations: 0.6127 MB / 0.7961 GB, free: 153 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002675/1.964, allocations: 0.5399 MB / 0.7966 GB, free: 152.9 MB / 0.5138 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 784
 * Number of states: 0 ()
 * Number of discrete variables: 73 (jointRRP.jointUSP.prismatic.positiveBranch,jointRRP.jointUSP.rod1Color[3],jointRRP.jointUSP.rod1Color[2],jointRRP.jointUSP.rod1Color[1],jointRRP.jointUSP.cylinderColor[3],jointRRP.jointUSP.cylinderColor[2],jointRRP.jointUSP.cylinderColor[1],jointRRP.jointUSP.sphereColor[3],jointRRP.jointUSP.sphereColor[2],jointRRP.jointUSP.sphereColor[1],jointRRP.rodColor[3],jointRRP.rodColor[2],jointRRP.rodColor[1],jointRRP.cylinderColor[3],jointRRP.cylinderColor[2],jointRRP.cylinderColor[1],cylPosition.color[3],cylPosition.color[2],cylPosition.color[1],Mid.color[3],Mid.color[2],Mid.color[1],Crank2.color[3],Crank2.color[2],Crank2.color[1],Crank2.body.sphereColor[3],Crank2.body.sphereColor[2],Crank2.body.sphereColor[1],Crank1.color[3],Crank1.color[2],Crank1.color[1],Crank1.body.sphereColor[3],Crank1.body.sphereColor[2],Crank1.body.sphereColor[1],Crank3.color[3],Crank3.color[2],Crank3.color[1],Crank3.body.sphereColor[3],Crank3.body.sphereColor[2],Crank3.body.sphereColor[1],Crank4.color[3],Crank4.color[2],Crank4.color[1],Crank4.body.sphereColor[3],Crank4.body.sphereColor[2],Crank4.body.sphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1],world.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],Bearing.cylinderColor[3],Bearing.cylinderColor[2],Bearing.cylinderColor[1],Rod2.color[3],Rod2.color[2],Rod2.color[1],Rod2.body.sphereColor[3],Rod2.body.sphereColor[2],Rod2.body.sphereColor[1],Piston.color[3],Piston.color[2],Piston.color[1],Piston.body.sphereColor[3],Piston.body.sphereColor[2],Piston.body.sphereColor[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1180):
 * Single equations (assignments): 1175
 * Array equations: 4
 * Algorithm blocks: 0
 * Record equations: 0
 * 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
   {(3,124,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.01019/1.974, allocations: 4.103 MB / 0.8006 GB, free: 151.3 MB / 0.5138 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001948/1.976, allocations: 0.6921 MB / 0.8013 GB, free: 151.3 MB / 0.5138 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0266/2.002, allocations: 13.85 MB / 0.8148 GB, free: 139.4 MB / 0.5138 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001064/2.003, allocations: 44 kB / 0.8149 GB, free: 139.4 MB / 0.5138 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.76e-05/2.003, allocations: 23.84 kB / 0.8149 GB, free: 139.4 MB / 0.5138 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001342/2.003, allocations: 23.92 kB / 0.8149 GB, free: 139.4 MB / 0.5138 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.05888/2.062, allocations: 27 MB / 0.8413 GB, free: 112.5 MB / 0.5138 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.104e-05/2.062, allocations: 8 kB / 0.8413 GB, free: 112.5 MB / 0.5138 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002712/2.064, allocations: 0.546 MB / 0.8418 GB, free: 111.9 MB / 0.5138 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01777/2.082, allocations: 5.949 MB / 0.8476 GB, free: 106 MB / 0.5138 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001595/2.084, allocations: 86.16 kB / 0.8477 GB, free: 105.9 MB / 0.5138 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0426/2.127, allocations: 30.28 MB / 0.8773 GB, free: 75.73 MB / 0.5138 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 8.767e-06/2.127, allocations: 8 kB / 0.8773 GB, free: 75.73 MB / 0.5138 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03745/2.164, allocations: 19.99 MB / 0.8968 GB, free: 55.73 MB / 0.5138 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.005921/2.17, allocations: 1.545 MB / 0.8983 GB, free: 54.17 MB / 0.5138 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002688/2.173, allocations: 79.92 kB / 0.8984 GB, free: 54.09 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005781/2.179, allocations: 311.5 kB / 0.8987 GB, free: 53.79 MB / 0.5138 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001693/2.18, allocations: 295.1 kB / 0.899 GB, free: 53.49 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0008708/2.181, allocations: 112 kB / 0.8991 GB, free: 53.38 MB / 0.5138 GB
Notification: Performance of sorting global known variables: time 0.009428/2.191, allocations: 4.89 MB / 0.9039 GB, free: 48.53 MB / 0.5138 GB
Notification: Performance of sort global known variables: time 4.91e-07/2.191, allocations: 0 / 0.9039 GB, free: 48.53 MB / 0.5138 GB
Notification: Performance of remove unused functions: time 0.01462/2.205, allocations: 2.412 MB / 0.9062 GB, free: 46.13 MB / 0.5138 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 34
 * Number of states: 2 (Inertia.phi,Inertia.w)
 * 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 (292):
 * Single equations (assignments): 288
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * 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
   {(3,111,77.8%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.001974/2.207, allocations: 288.2 kB / 0.9065 GB, free: 45.86 MB / 0.5138 GB
Notification: Performance of simCode: created initialization part: time 0.02132/2.229, allocations: 12.44 MB / 0.9186 GB, free: 33.43 MB / 0.5138 GB
Notification: Performance of simCode: created event and clocks part: time 2.485e-05/2.229, allocations: 4 kB / 0.9186 GB, free: 33.43 MB / 0.5138 GB
Notification: Performance of simCode: created simulation system equations: time 0.009761/2.238, allocations: 5.093 MB / 0.9236 GB, free: 28.31 MB / 0.5138 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01208/2.251, allocations: 1.08 MB / 0.9247 GB, free: 27.27 MB / 0.5138 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2599/2.51, allocations: 22.44 MB / 0.9466 GB, free: 162.7 MB / 0.5138 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01009/2.521, allocations: 6.395 MB / 0.9528 GB, free: 161.8 MB / 0.5138 GB
Notification: Performance of simCode: alias equations: time 0.008384/2.529, allocations: 2.961 MB / 0.9557 GB, free: 161.7 MB / 0.5138 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001709/2.531, allocations: 428 kB / 0.9561 GB, free: 161.7 MB / 0.5138 GB
Notification: Performance of SimCode: time 8.02e-07/2.531, allocations: 3.344 kB / 0.9561 GB, free: 161.7 MB / 0.5138 GB
Notification: Performance of Templates: time 0.1617/2.692, allocations: 107.2 MB / 1.061 GB, free: 76.41 MB / 0.5138 GB
make -j1 -f Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.makefile
(rm -f Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.pipe ; mkfifo Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.pipe ; head -c 1048576 < Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.pipe >> ../files/Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.sim & ./Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.pipe 2>&1)