Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo", uses=false)
Using package ModelicaTest with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo)
Using package Modelica with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/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(ModelicaTest.MultiBody.FourbarVariants.JointSSR,fileNamePrefix="ModelicaTest_trunk_ModelicaTest_MultiBody_FourbarVariants_JointSSR",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.FourbarVariants.JointSSR,fileNamePrefix="ModelicaTest_trunk_ModelicaTest_MultiBody_FourbarVariants_JointSSR",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.0009736/0.0009736, allocations: 101.2 kB / 17.01 MB, free: 6.102 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001245/0.001245, allocations: 193.8 kB / 17.91 MB, free: 5.664 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.436/1.436, allocations: 227.4 MB / 246 MB, free: 11.98 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.2386/0.2386, allocations: 45.92 MB / 344.1 MB, free: 12.25 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003194/0.0003195, allocations: 11.94 kB / 418.2 MB, free: 20.79 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.164/0.1643, allocations: 63.46 MB / 481.6 MB, free: 5.316 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.FourbarVariants.JointSSR): time 0.3752/0.5396, allocations: 52.73 MB / 0.5218 GB, free: 9.867 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.03734/0.577, allocations: 11.73 MB / 0.5333 GB, free: 8.152 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01704/0.594, allocations: 252.5 kB / 0.5335 GB, free: 8.109 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01074/0.6048, allocations: 2.75 MB / 0.5362 GB, free: 7.516 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.02615/0.631, allocations: 8.252 MB / 0.5443 GB, free: 3.07 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.005152/0.6362, allocations: 1.061 MB / 0.5453 GB, free: 2.461 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.04495/0.6812, allocations: 36.2 MB / 0.5807 GB, free: 2.789 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01067/0.6919, allocations: 7.018 MB / 0.5875 GB, free: 11.7 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004421/0.6963, allocations: 2.148 MB / 0.5896 GB, free: 9.539 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.005172/0.7015, allocations: 3.49 MB / 0.593 GB, free: 6.039 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001023/0.7025, allocations: 264 kB / 0.5933 GB, free: 5.781 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003025/0.7056, allocations: 1.28 MB / 0.5945 GB, free: 4.5 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002821/0.7084, allocations: 2.895 MB / 0.5974 GB, free: 1.598 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.00159/0.71, allocations: 1.38 MB / 0.5987 GB, free: 212 kB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.009344/0.7194, allocations: 9.609 MB / 0.6081 GB, free: 6.578 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 8.165e-06/0.7194, allocations: 8 kB / 0.6081 GB, free: 6.57 MB / 446.1 MB
Notification: Performance of FrontEnd: time 3.316e-06/0.7194, allocations: 3.984 kB / 0.6081 GB, free: 6.566 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0003409/0.7198, allocations: 0 / 0.6081 GB, free: 6.566 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: 2564
 * Number of variables: 2564
Notification: Performance of Generate backend data structure: time 0.01279/0.7326, allocations: 6.635 MB / 0.6146 GB, free: 15.84 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.579e-05/0.7326, allocations: 12.03 kB / 0.6146 GB, free: 15.83 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.002632/0.7353, allocations: 1.303 MB / 0.6159 GB, free: 14.52 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02493/0.7602, allocations: 5.817 MB / 0.6215 GB, free: 8.68 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01137/0.7716, allocations: 4.745 MB / 0.6262 GB, free: 3.875 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004041/0.772, allocations: 402 kB / 0.6266 GB, free: 3.48 MB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002153/0.7742, allocations: 0.5503 MB / 0.6271 GB, free: 2.93 MB / 462.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0379/0.8121, allocations: 18.84 MB / 0.6455 GB, free: 15.04 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001926/0.8123, allocations: 79.3 kB / 0.6456 GB, free: 14.96 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0006937/0.813, allocations: 247.8 kB / 0.6458 GB, free: 14.72 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004907/0.818, allocations: 3.428 MB / 0.6492 GB, free: 11.29 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03646/0.8544, allocations: 16.48 MB / 0.6652 GB, free: 10.79 MB / 0.4981 GB
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.3869/1.241, allocations: 66.72 MB / 0.7304 GB, free: 138.2 MB / 0.5294 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.018/1.259, allocations: 6.266 MB / 0.7365 GB, free: 136.9 MB / 0.5294 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005533/1.265, allocations: 2.816 MB / 0.7393 GB, free: 136.3 MB / 0.5294 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.02293/1.288, allocations: 13.9 MB / 0.7529 GB, free: 128.8 MB / 0.5294 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.29e-05/1.288, allocations: 59.5 kB / 0.7529 GB, free: 128.7 MB / 0.5294 GB
Notification: Performance of pre-optimization done (n=284): time 6.692e-06/1.288, allocations: 0 / 0.7529 GB, free: 128.7 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=345): time 0.0764/1.364, allocations: 25.05 MB / 0.7774 GB, free: 114.8 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.996e-05/1.365, allocations: 190.8 kB / 0.7776 GB, free: 114.6 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008047/1.373, allocations: 5.868 MB / 0.7833 GB, free: 108.7 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.0006901/1.373, allocations: 71.42 kB / 0.7834 GB, free: 108.6 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002827/1.376, allocations: 4.988 MB / 0.7882 GB, free: 103.7 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001514/1.378, allocations: 1.282 MB / 0.7895 GB, free: 102.4 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003915/1.378, allocations: 189.6 kB / 0.7897 GB, free: 102.2 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 0.0003912/1.379, allocations: 0.703 MB / 0.7903 GB, free: 101.5 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004503/1.383, allocations: 3.267 MB / 0.7935 GB, free: 98.22 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006895/1.39, allocations: 8.737 MB / 0.8021 GB, free: 86.88 MB / 0.5294 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.00732/1.397, allocations: 5.232 MB / 0.8072 GB, free: 81.7 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.000119/1.398, allocations: 56 kB / 0.8072 GB, free: 81.65 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=1238) (initialization): time 0.02386/1.421, allocations: 12.74 MB / 0.8197 GB, free: 68.87 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000214/1.422, allocations: 96.2 kB / 0.8198 GB, free: 68.78 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003087/1.422, allocations: 195 kB / 0.8199 GB, free: 68.59 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003939/1.426, allocations: 1.337 MB / 0.8213 GB, free: 67.25 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006944/1.433, allocations: 2.164 MB / 0.8234 GB, free: 65.08 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01073/1.444, allocations: 7.288 MB / 0.8305 GB, free: 57.66 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007036/1.451, allocations: 0.5084 MB / 0.831 GB, free: 57.15 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001806/1.453, allocations: 0.5481 MB / 0.8315 GB, free: 56.61 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 874
 * Number of states: 0 ()
 * Number of discrete variables: 46 (jointSSR.revolute.positiveBranch,Body2.sphereColor[3],Body2.sphereColor[2],Body2.sphereColor[1],Body1.sphereColor[3],Body1.sphereColor[2],Body1.sphereColor[1],FixedFrame1.color_x[3],FixedFrame1.color_x[2],FixedFrame1.color_x[1],jointSSR.rod1Color[3],jointSSR.rod1Color[2],jointSSR.rod1Color[1],jointSSR.rod1.massColor[3],jointSSR.rod1.massColor[2],jointSSR.rod1.massColor[1],jointSSR.sphereColor[3],jointSSR.sphereColor[2],jointSSR.sphereColor[1],jointSSR.revoluteColor[3],jointSSR.revoluteColor[2],jointSSR.revoluteColor[1],b3.color[3],b3.color[2],b3.color[1],b2.color[3],b2.color[2],b2.color[1],b2.body.sphereColor[3],b2.body.sphereColor[2],b2.body.sphereColor[1],j2.boxColor[3],j2.boxColor[2],j2.boxColor[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])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1152):
 * Single equations (assignments): 1145
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(2,100.0%) 37}
 * Non-linear torn systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.007797/1.46, allocations: 3.439 MB / 0.8349 GB, free: 53.16 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001367/1.462, allocations: 0.5283 MB / 0.8354 GB, free: 52.62 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01203/1.474, allocations: 5.406 MB / 0.8407 GB, free: 47.16 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.563e-05/1.474, allocations: 37.8 kB / 0.8407 GB, free: 47.12 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.496e-05/1.474, allocations: 8.469 kB / 0.8407 GB, free: 47.11 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.473e-05/1.474, allocations: 11.95 kB / 0.8407 GB, free: 47.1 MB / 0.5294 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02875/1.503, allocations: 14.01 MB / 0.8544 GB, free: 32.88 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.632e-05/1.503, allocations: 15.94 kB / 0.8544 GB, free: 32.86 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002118/1.505, allocations: 0.4953 MB / 0.8549 GB, free: 32.36 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.002986/1.508, allocations: 0.8088 MB / 0.8557 GB, free: 31.55 MB / 0.5294 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0003971/1.508, allocations: 68.53 kB / 0.8558 GB, free: 31.48 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.00866/1.517, allocations: 5.329 MB / 0.861 GB, free: 26.03 MB / 0.5294 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.533e-05/1.517, allocations: 15.94 kB / 0.861 GB, free: 26.01 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01456/1.532, allocations: 8.275 MB / 0.8691 GB, free: 17.45 MB / 0.5294 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002192/1.534, allocations: 0.5839 MB / 0.8696 GB, free: 16.84 MB / 0.5294 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001431/1.535, allocations: 65.25 kB / 0.8697 GB, free: 16.77 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002842/1.538, allocations: 135.8 kB / 0.8698 GB, free: 16.64 MB / 0.5294 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000499/1.539, allocations: 210.5 kB / 0.87 GB, free: 16.44 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002615/1.539, allocations: 100.9 kB / 0.8701 GB, free: 16.34 MB / 0.5294 GB
Notification: Performance of sorting global known variables: time 0.007046/1.546, allocations: 3.644 MB / 0.8737 GB, free: 12.73 MB / 0.5294 GB
Notification: Performance of sort global known variables: time 5.91e-07/1.546, allocations: 0 / 0.8737 GB, free: 12.73 MB / 0.5294 GB
Notification: Performance of remove unused functions: time 0.008652/1.555, allocations: 1.628 MB / 0.8753 GB, free: 11.11 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 51
 * Number of states: 2 (j2.s,j2.v)
 * 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 (269):
 * Single equations (assignments): 262
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(2,100.0%) 30}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.01288/1.568, allocations: 9.01 MB / 0.8841 GB, free: 1.812 MB / 0.5294 GB
Notification: Performance of simCode: created initialization part: time 0.01361/1.581, allocations: 7.754 MB / 0.8916 GB, free: 10.07 MB / 0.545 GB
Notification: Performance of simCode: created event and clocks part: time 1.591e-05/1.581, allocations: 4 kB / 0.8916 GB, free: 10.07 MB / 0.545 GB
Notification: Performance of simCode: created simulation system equations: time 0.004569/1.586, allocations: 2.366 MB / 0.8939 GB, free: 7.688 MB / 0.545 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006365/1.592, allocations: 0.6941 MB / 0.8946 GB, free: 7 MB / 0.545 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.432/2.024, allocations: 57.89 MB / 0.9512 GB, free: 168.6 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.008812/2.033, allocations: 5.163 MB / 0.9562 GB, free: 166.9 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.008386/2.042, allocations: 2.959 MB / 0.9591 GB, free: 166.8 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.005315/2.047, allocations: 3.332 MB / 0.9623 GB, free: 164.1 MB / 0.5606 GB
Notification: Performance of SimCode: time 9.52e-07/2.047, allocations: 0 / 0.9623 GB, free: 164.1 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1693/2.216, allocations: 123.9 MB / 1.083 GB, free: 45.03 MB / 0.5606 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0001628/2.216, allocations: 209.2 kB / 1.084 GB, free: 44.79 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate platform static: time 5.423/7.64, allocations: 3.781 kB / 1.084 GB, free: 44.79 MB / 0.5606 GB
(rm -f ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.pipe ; mkfifo ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.pipe ; head -c 1048576 < ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.pipe >> ../files/ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.sim & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator -r=ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR_res.mat --tempDir=temp_ModelicaTest_trunk_ModelicaTest_MultiBody_FourbarVariants_JointSSR_fmu --startTime=0 --stopTime=1.1 --timeout=50 --tolerance=1e-06 ModelicaTest_trunk_ModelicaTest_MultiBody_FourbarVariants_JointSSR.fmu > ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.pipe 2>&1)
TODO: How the !@#!# did the simulation report success but simulation result ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR_res.mat does not exist to compare? outputFormat=mat