Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Torus.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.001786/0.001786, allocations: 110 kB / 20.42 MB, free: 1.535 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.001631/0.001631, allocations: 208.7 kB / 23.71 MB, free: 4.965 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.516/1.516, allocations: 230.2 MB / 257.1 MB, free: 8.512 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo): time 0.2471/0.2471, allocations: 46.89 MB / 360.3 MB, free: 4.617 MB / 286.1 MB
"
[Timeout remaining time 180]
Using package ModelicaTest with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/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.Visualizers.Torus,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Visualizers_Torus",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Visualizers.Torus,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Visualizers_Torus",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Warning: Requested package Modelica 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.002323/0.002323, allocations: 79.86 kB / 0.4955 GB, free: 8.32 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3528/0.3552, allocations: 66 MB / 0.56 GB, free: 4.891 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Visualizers.Torus): time 0.01362/0.3688, allocations: 11.29 MB / 0.571 GB, free: 9.543 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.007242/0.376, allocations: 2.844 MB / 0.5738 GB, free: 6.688 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002358/0.3784, allocations: 63.81 kB / 0.5738 GB, free: 6.625 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003413/0.3818, allocations: 1.351 MB / 0.5752 GB, free: 5.27 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003612/0.3854, allocations: 1.245 MB / 0.5764 GB, free: 4.02 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0005593/0.386, allocations: 75.84 kB / 0.5764 GB, free: 3.945 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004314/0.3903, allocations: 2.72 MB / 0.5791 GB, free: 1.219 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002595/0.3929, allocations: 1.465 MB / 0.5805 GB, free: 15.72 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002072/0.3949, allocations: 1.039 MB / 0.5815 GB, free: 14.68 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002708/0.3977, allocations: 1.604 MB / 0.5831 GB, free: 13.07 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0008073/0.3985, allocations: 208 kB / 0.5833 GB, free: 12.86 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001523/0.4, allocations: 0.5071 MB / 0.5838 GB, free: 12.36 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001935/0.4019, allocations: 1.429 MB / 0.5852 GB, free: 10.92 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002762/0.4047, allocations: 1.7 MB / 0.5869 GB, free: 9.211 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.007359/0.412, allocations: 5.456 MB / 0.5922 GB, free: 3.73 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.291e-06/0.412, allocations: 0 / 0.5922 GB, free: 3.73 MB / 430.1 MB
Notification: Performance of FrontEnd: time 2.224e-06/0.4121, allocations: 0 / 0.5922 GB, free: 3.73 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 0.0001756/0.4122, allocations: 0 / 0.5922 GB, free: 3.73 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1443
 * Number of variables: 1443
Notification: Performance of Generate backend data structure: time 0.007626/0.4199, allocations: 3.676 MB / 0.5958 GB, free: 15.98 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.74e-05/0.4199, allocations: 12.03 kB / 0.5958 GB, free: 15.97 MB / 446.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001614/0.4215, allocations: 0.667 MB / 0.5964 GB, free: 15.3 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01053/0.4321, allocations: 2.962 MB / 0.5993 GB, free: 12.33 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004247/0.4363, allocations: 1.538 MB / 0.6008 GB, free: 10.75 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001897/0.4365, allocations: 176.6 kB / 0.601 GB, free: 10.57 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008412/0.4373, allocations: 231.8 kB / 0.6012 GB, free: 10.34 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0168/0.4541, allocations: 9.468 MB / 0.6105 GB, free: 0.4922 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 7.355e-05/0.4542, allocations: 15.81 kB / 0.6105 GB, free: 488 kB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0005896/0.4548, allocations: 111.8 kB / 0.6106 GB, free: 376 kB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004219/0.459, allocations: 2.369 MB / 0.6129 GB, free: 13.99 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01772/0.4767, allocations: 8.675 MB / 0.6214 GB, free: 5.301 MB / 462.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.04814/0.5249, allocations: 29.15 MB / 0.6498 GB, free: 7.348 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.00549/0.5304, allocations: 2.951 MB / 0.6527 GB, free: 4.172 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001619/0.532, allocations: 0.8945 MB / 0.6536 GB, free: 3.273 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.01082/0.5428, allocations: 4.847 MB / 0.6583 GB, free: 14.17 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.497e-05/0.5429, allocations: 43.22 kB / 0.6584 GB, free: 14.12 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=126): time 4.438e-06/0.5429, allocations: 0 / 0.6584 GB, free: 14.12 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=126): time 0.006669/0.5495, allocations: 2.745 MB / 0.6611 GB, free: 11.37 MB / 0.4981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.367e-05/0.5496, allocations: 64.06 kB / 0.6611 GB, free: 11.3 MB / 0.4981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004487/0.5541, allocations: 2.566 MB / 0.6636 GB, free: 8.703 MB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001783/0.5543, allocations: 37.7 kB / 0.6637 GB, free: 8.66 MB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002317/0.5566, allocations: 2.767 MB / 0.6664 GB, free: 5.867 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006431/0.5572, allocations: 0.5001 MB / 0.6669 GB, free: 5.363 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001421/0.5586, allocations: 0.913 MB / 0.6677 GB, free: 4.438 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 0.0002258/0.5589, allocations: 357.9 kB / 0.6681 GB, free: 4.082 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002309/0.5612, allocations: 1.352 MB / 0.6694 GB, free: 2.723 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004735/0.5659, allocations: 4.118 MB / 0.6734 GB, free: 13.21 MB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.007559/0.5735, allocations: 5.968 MB / 0.6793 GB, free: 5.871 MB / 0.5137 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.786e-05/0.5736, allocations: 28 kB / 0.6793 GB, free: 5.844 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=588) (initialization): time 0.0103/0.5839, allocations: 5.288 MB / 0.6844 GB, free: 0.5508 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 8.907e-05/0.584, allocations: 55.97 kB / 0.6845 GB, free: 0.4961 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001408/0.5841, allocations: 96 kB / 0.6846 GB, free: 412 kB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001428/0.5842, allocations: 128 kB / 0.6847 GB, free: 284 kB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003161/0.5874, allocations: 0.9825 MB / 0.6857 GB, free: 15.29 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001882/0.5876, allocations: 187.3 kB / 0.6858 GB, free: 15.11 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003437/0.591, allocations: 0.6166 MB / 0.6865 GB, free: 14.49 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007412/0.5918, allocations: 280 kB / 0.6867 GB, free: 14.21 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 446
 * Number of states: 0 ()
 * Number of discrete variables: 21 (fixedFrame1.color_x[3],fixedFrame1.color_x[2],fixedFrame1.color_x[1],fixedFrame.color_x[3],fixedFrame.color_x[2],fixedFrame.color_x[1],fixedTranslation.color[3],fixedTranslation.color[2],fixedTranslation.color[1],world.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (540):
 * Single equations (assignments): 534
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.003641/0.5954, allocations: 1.903 MB / 0.6886 GB, free: 12.3 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004695/0.5959, allocations: 222.5 kB / 0.6888 GB, free: 12.08 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0006801/0.5966, allocations: 275.3 kB / 0.6891 GB, free: 11.81 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.166e-05/0.5966, allocations: 12 kB / 0.6891 GB, free: 11.8 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.377e-05/0.5966, allocations: 0 / 0.6891 GB, free: 11.8 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.424e-05/0.5966, allocations: 7.984 kB / 0.6891 GB, free: 11.79 MB / 0.5294 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01073/0.6074, allocations: 5.607 MB / 0.6945 GB, free: 6.059 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.015e-05/0.6074, allocations: 7.938 kB / 0.6946 GB, free: 6.051 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006143/0.608, allocations: 143.8 kB / 0.6947 GB, free: 5.91 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.199e-05/0.608, allocations: 8 kB / 0.6947 GB, free: 5.902 MB / 0.5294 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.623e-05/0.6081, allocations: 20 kB / 0.6947 GB, free: 5.883 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.255e-05/0.6081, allocations: 11.94 kB / 0.6947 GB, free: 5.871 MB / 0.5294 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.403e-06/0.6081, allocations: 8.484 kB / 0.6947 GB, free: 5.863 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002423/0.6105, allocations: 1.805 MB / 0.6965 GB, free: 3.984 MB / 0.5294 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005993/0.6111, allocations: 303.3 kB / 0.6968 GB, free: 3.668 MB / 0.5294 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002752/0.6114, allocations: 16 kB / 0.6968 GB, free: 3.652 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006252/0.612, allocations: 39.95 kB / 0.6968 GB, free: 3.613 MB / 0.5294 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001249/0.6121, allocations: 64.38 kB / 0.6969 GB, free: 3.551 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.545e-05/0.6122, allocations: 43.98 kB / 0.6969 GB, free: 3.508 MB / 0.5294 GB
Notification: Performance of sorting global known variables: time 0.002498/0.6147, allocations: 1.556 MB / 0.6985 GB, free: 1.938 MB / 0.5294 GB
Notification: Performance of sort global known variables: time 2.11e-07/0.6147, allocations: 0 / 0.6985 GB, free: 1.938 MB / 0.5294 GB
Notification: Performance of remove unused functions: time 0.00388/0.6186, allocations: 1 MB / 0.6994 GB, free: 0.9336 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 19
 * 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 simulation (78):
 * Single equations (assignments): 72
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.003178/0.6218, allocations: 2.44 MB / 0.7018 GB, free: 14.41 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.005159/0.6269, allocations: 3.019 MB / 0.7048 GB, free: 11.42 MB / 0.545 GB
Notification: Performance of simCode: created event and clocks part: time 1.065e-05/0.6269, allocations: 0 / 0.7048 GB, free: 11.42 MB / 0.545 GB
Notification: Performance of simCode: created simulation system equations: time 0.001052/0.628, allocations: 0.6878 MB / 0.7054 GB, free: 10.73 MB / 0.545 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001587/0.6296, allocations: 254.2 kB / 0.7057 GB, free: 10.48 MB / 0.545 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3486/0.9782, allocations: 29.37 MB / 0.7344 GB, free: 153.6 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.004468/0.9827, allocations: 2.967 MB / 0.7373 GB, free: 152.4 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.003439/0.9861, allocations: 1.481 MB / 0.7387 GB, free: 152.4 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002301/0.9884, allocations: 1.516 MB / 0.7402 GB, free: 151 MB / 0.5606 GB
Notification: Performance of SimCode: time 2.004e-06/0.9884, allocations: 0 / 0.7402 GB, free: 151 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.07355/1.062, allocations: 39 MB / 0.7783 GB, free: 113.5 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate platform static: time 15.12/16.18, allocations: 3.984 kB / 0.7783 GB, free: 113.5 MB / 0.5606 GB
"
[Timeout remaining time 644]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Torus.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Torus.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Torus.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Torus.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Torus_res.mat --tempDir=temp_ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Visualizers_Torus_fmu --startTime=0 --stopTime=2 --stepSize=0.00039984 --timeout=50 --tolerance=1e-06 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Visualizers_Torus.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Torus.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 19.159538235981017]
Failed to read output from testmodel.py, exit status != 0:
['time', 'torus1.surface.nu', 'torus1.surface.nv', 'torus2.surface.nu', 'torus2.surface.nv']
16.2778362701647 16.29621519 15.879938255
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4741a42020>