Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr TILMedia_TILMedia.Testers.TestSolid.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo): time 0.01038/0.01038, allocations: 1.741 MB / 21.38 MB, free: 476 kB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo): time 0.9114/0.9114, allocations: 130.7 MB / 155.6 MB, free: 4.289 MB / 126.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001397/0.001397, allocations: 111.1 kB / 187.3 MB, free: 1.965 MB / 142.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.00156/0.00156, allocations: 213.1 kB / 219.2 MB, free: 2.133 MB / 174.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.589/1.589, allocations: 230.6 MB / 481.5 MB, free: 96 kB / 318.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia main/package.mo): time 0.1186/0.1186, allocations: 25.57 MB / 0.5782 GB, free: 2.066 MB / 430.1 MB
"
[Timeout remaining time 180]
Using package TILMedia with version 1.9.0 ClaRa (/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia main/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Using package ClaRa with version 1.9.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo)
Using package SMArtInt with version 0.3.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo)
Running command: "" <> buildModelFMU(TILMedia.Testers.TestSolid,fileNamePrefix="TILMedia_TILMedia_Testers_TestSolid",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(TILMedia.Testers.TestSolid,fileNamePrefix="TILMedia_TILMedia_Testers_TestSolid",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: SMArtInt requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: ClaRa requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Warning: Requested package SMArtInt of version 0.5.1, but this package was already loaded with version 0.3.1. There are no conversion annotations for this version but 0.5.1 is newer than 0.3.1. There is a possibility that 0.3.1 remains backwards compatible, but it is not loaded so OpenModelica cannot verify this.
Notification: TILMedia requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.003765/0.003765, allocations: 133.6 kB / 0.7703 GB, free: 4.895 MB / 478.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2253/0.229, allocations: 89.27 MB / 0.8574 GB, free: 7.812 MB / 0.545 GB
Notification: Performance of NFInst.instantiate(TILMedia.Testers.TestSolid): time 0.003301/0.2323, allocations: 1.38 MB / 0.8588 GB, free: 6.418 MB / 0.545 GB
Notification: Performance of NFInst.instExpressions: time 0.0005082/0.2329, allocations: 171.4 kB / 0.859 GB, free: 6.25 MB / 0.545 GB
Notification: Performance of NFInst.updateImplicitVariability: time 4.003e-05/0.2329, allocations: 7.938 kB / 0.859 GB, free: 6.242 MB / 0.545 GB
Notification: Performance of NFTyping.typeComponents: time 2.591e-05/0.2329, allocations: 3.984 kB / 0.859 GB, free: 6.238 MB / 0.545 GB
Notification: Performance of NFTyping.typeBindings: time 0.0001045/0.233, allocations: 19.86 kB / 0.859 GB, free: 6.219 MB / 0.545 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0004079/0.2334, allocations: 63.81 kB / 0.859 GB, free: 6.156 MB / 0.545 GB
Notification: Performance of NFFlatten.flatten: time 0.0001133/0.2335, allocations: 43.8 kB / 0.8591 GB, free: 6.113 MB / 0.545 GB
Notification: Performance of NFFlatten.resolveConnections: time 4.679e-05/0.2336, allocations: 0 / 0.8591 GB, free: 6.113 MB / 0.545 GB
Notification: Performance of NFEvalConstants.evaluate: time 6.271e-05/0.2337, allocations: 23.86 kB / 0.8591 GB, free: 6.09 MB / 0.545 GB
Notification: Performance of NFSimplifyModel.simplify: time 5.569e-05/0.2337, allocations: 15.92 kB / 0.8591 GB, free: 6.074 MB / 0.545 GB
Notification: Performance of NFPackage.collectConstants: time 1.128e-05/0.2337, allocations: 3.938 kB / 0.8591 GB, free: 6.07 MB / 0.545 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001897/0.2339, allocations: 75.94 kB / 0.8592 GB, free: 5.996 MB / 0.545 GB
Notification: Performance of NFScalarize.scalarize: time 2.764e-05/0.2339, allocations: 15.92 kB / 0.8592 GB, free: 5.98 MB / 0.545 GB
Notification: Performance of NFVerifyModel.verify: time 3.426e-05/0.234, allocations: 11.94 kB / 0.8592 GB, free: 5.969 MB / 0.545 GB
Notification: Performance of NFConvertDAE.convert: time 0.0002272/0.2342, allocations: 83.94 kB / 0.8593 GB, free: 5.887 MB / 0.545 GB
Notification: Performance of FrontEnd - DAE generated: time 6.703e-06/0.2342, allocations: 0 / 0.8593 GB, free: 5.887 MB / 0.545 GB
Notification: Performance of FrontEnd: time 2.735e-06/0.2342, allocations: 0 / 0.8593 GB, free: 5.887 MB / 0.545 GB
Notification: Performance of Transformations before backend: time 8.186e-06/0.2342, allocations: 4 kB / 0.8593 GB, free: 5.883 MB / 0.545 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 7
 * Number of variables: 7
Notification: Performance of Generate backend data structure: time 0.0005297/0.2347, allocations: 274.2 kB / 0.8596 GB, free: 5.582 MB / 0.545 GB
Notification: Performance of prepare preOptimizeDAE: time 4.93e-05/0.2348, allocations: 8.031 kB / 0.8596 GB, free: 5.574 MB / 0.545 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 1.85e-05/0.2348, allocations: 4.188 kB / 0.8596 GB, free: 5.57 MB / 0.545 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 3.548e-05/0.2348, allocations: 38.75 kB / 0.8596 GB, free: 5.523 MB / 0.545 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 3.161e-05/0.2349, allocations: 4.891 kB / 0.8596 GB, free: 5.52 MB / 0.545 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0001202/0.235, allocations: 110.5 kB / 0.8597 GB, free: 5.387 MB / 0.545 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 1.599e-05/0.235, allocations: 4.375 kB / 0.8597 GB, free: 5.383 MB / 0.545 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 1.852e-05/0.235, allocations: 4.375 kB / 0.8597 GB, free: 5.379 MB / 0.545 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.000142/0.2352, allocations: 53.92 kB / 0.8598 GB, free: 5.32 MB / 0.545 GB
Notification: Performance of preOpt findStateOrder (simulation): time 1.255e-05/0.2352, allocations: 0 / 0.8598 GB, free: 5.32 MB / 0.545 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 1.257e-05/0.2352, allocations: 4 kB / 0.8598 GB, free: 5.316 MB / 0.545 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.259e-06/0.2352, allocations: 0 / 0.8598 GB, free: 5.316 MB / 0.545 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 6.227e-05/0.2353, allocations: 15.98 kB / 0.8598 GB, free: 5.301 MB / 0.545 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.0004344/0.2357, allocations: 247.8 kB / 0.86 GB, free: 5.016 MB / 0.545 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0001019/0.2358, allocations: 34.52 kB / 0.8601 GB, free: 4.973 MB / 0.545 GB
Notification: Performance of preOpt resolveLoops (simulation): time 3.605e-05/0.2358, allocations: 8.922 kB / 0.8601 GB, free: 4.961 MB / 0.545 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003365/0.2362, allocations: 35.95 kB / 0.8601 GB, free: 4.926 MB / 0.545 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.209e-05/0.2362, allocations: 30.56 kB / 0.8602 GB, free: 4.887 MB / 0.545 GB
Notification: Performance of pre-optimization done (n=3): time 1.162e-06/0.2362, allocations: 0 / 0.8602 GB, free: 4.887 MB / 0.545 GB
Notification: Performance of matching and sorting (n=3): time 0.0001901/0.2364, allocations: 49.69 kB / 0.8602 GB, free: 4.832 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.12e-05/0.2364, allocations: 51.41 kB / 0.8602 GB, free: 4.77 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 9.702e-05/0.2365, allocations: 113.8 kB / 0.8604 GB, free: 4.648 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 1.747e-05/0.2366, allocations: 25.7 kB / 0.8604 GB, free: 4.617 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 4.994e-05/0.2366, allocations: 92.09 kB / 0.8605 GB, free: 4.523 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 2.038e-05/0.2366, allocations: 7.891 kB / 0.8605 GB, free: 4.516 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 4.38e-05/0.2367, allocations: 25.66 kB / 0.8605 GB, free: 4.484 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 0.0001418/0.2368, allocations: 314.8 kB / 0.8608 GB, free: 4.168 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 1.654e-05/0.2368, allocations: 4 kB / 0.8608 GB, free: 4.164 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 1.112e-05/0.2368, allocations: 4 kB / 0.8608 GB, free: 4.16 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 3.213e-05/0.2369, allocations: 21.12 kB / 0.8608 GB, free: 4.137 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.503e-06/0.2369, allocations: 0 / 0.8608 GB, free: 4.137 MB / 0.545 GB
Notification: Performance of matching and sorting (n=3) (initialization): time 6.924e-05/0.2369, allocations: 45.69 kB / 0.8609 GB, free: 4.086 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 1.531e-05/0.237, allocations: 4 kB / 0.8609 GB, free: 4.082 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.618e-06/0.237, allocations: 0 / 0.8609 GB, free: 4.082 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.512e-05/0.237, allocations: 11.88 kB / 0.8609 GB, free: 4.07 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 6.186e-05/0.237, allocations: 7.984 kB / 0.8609 GB, free: 4.062 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 5.49e-06/0.237, allocations: 0 / 0.8609 GB, free: 4.062 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 1.643e-05/0.2371, allocations: 4 kB / 0.8609 GB, free: 4.059 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.749e-06/0.2371, allocations: 0 / 0.8609 GB, free: 4.059 MB / 0.545 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 1
 * 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 initialization (3):
 * Single equations (assignments): 3
 * Array equations: 0
 * 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 8.664e-05/0.2372, allocations: 113.8 kB / 0.861 GB, free: 3.938 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 2.169e-05/0.2372, allocations: 0 / 0.861 GB, free: 3.938 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0001178/0.2373, allocations: 57.67 kB / 0.8611 GB, free: 3.867 MB / 0.545 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.563e-06/0.2373, allocations: 0 / 0.8611 GB, free: 3.867 MB / 0.545 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.987e-06/0.2373, allocations: 0 / 0.8611 GB, free: 3.867 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.315e-06/0.2373, allocations: 4 kB / 0.8611 GB, free: 3.863 MB / 0.545 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.000172/0.2375, allocations: 136.6 kB / 0.8612 GB, free: 3.688 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.016e-06/0.2375, allocations: 3.984 kB / 0.8612 GB, free: 3.684 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 2.587e-05/0.2375, allocations: 11.88 kB / 0.8612 GB, free: 3.672 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (simulation): time 2.084e-06/0.2375, allocations: 0 / 0.8612 GB, free: 3.672 MB / 0.545 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 9.829e-06/0.2375, allocations: 4 kB / 0.8612 GB, free: 3.668 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.062e-06/0.2375, allocations: 0 / 0.8612 GB, free: 3.668 MB / 0.545 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.82e-07/0.2375, allocations: 0 / 0.8612 GB, free: 3.668 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0001247/0.2376, allocations: 143.5 kB / 0.8614 GB, free: 3.512 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 2.971e-05/0.2377, allocations: 7.984 kB / 0.8614 GB, free: 3.504 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 1.242e-05/0.2377, allocations: 4 kB / 0.8614 GB, free: 3.5 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 4.705e-05/0.2377, allocations: 4 kB / 0.8614 GB, free: 3.496 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 1.193e-05/0.2377, allocations: 0 / 0.8614 GB, free: 3.496 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 2.425e-06/0.2377, allocations: 0 / 0.8614 GB, free: 3.496 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 5.189e-05/0.2378, allocations: 95.52 kB / 0.8615 GB, free: 3.402 MB / 0.545 GB
Notification: Performance of sort global known variables: time 6.1e-08/0.2378, allocations: 0 / 0.8615 GB, free: 3.402 MB / 0.545 GB
Notification: Performance of remove unused functions: time 0.0002561/0.2381, allocations: 27.89 kB / 0.8615 GB, free: 3.375 MB / 0.545 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * 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 (3):
 * Single equations (assignments): 3
 * Array equations: 0
 * 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.0005328/0.2386, allocations: 0.7045 MB / 0.8622 GB, free: 2.645 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 8.951e-05/0.2387, allocations: 8 kB / 0.8622 GB, free: 2.637 MB / 0.545 GB
Notification: Performance of simCode: created event and clocks part: time 2.245e-06/0.2387, allocations: 0 / 0.8622 GB, free: 2.637 MB / 0.545 GB
Notification: Performance of simCode: created simulation system equations: time 1.709e-05/0.2387, allocations: 11.94 kB / 0.8622 GB, free: 2.625 MB / 0.545 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0002282/0.2389, allocations: 7.984 kB / 0.8622 GB, free: 2.617 MB / 0.545 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00051/0.2394, allocations: 169.1 kB / 0.8624 GB, free: 2.414 MB / 0.545 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 7.555e-05/0.2395, allocations: 67.33 kB / 0.8624 GB, free: 2.328 MB / 0.545 GB
Notification: Performance of simCode: alias equations: time 2.487e-05/0.2395, allocations: 8 kB / 0.8624 GB, free: 2.32 MB / 0.545 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 2.998e-05/0.2396, allocations: 11.44 kB / 0.8624 GB, free: 2.309 MB / 0.545 GB
Notification: Performance of SimCode: time 2.91e-07/0.2396, allocations: 0 / 0.8624 GB, free: 2.309 MB / 0.545 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.02132/0.2609, allocations: 1.59 MB / 0.864 GB, free: 0.8555 MB / 0.545 GB
Notification: Performance of buildModelFMU: Generate platform static: time 11.03/11.3, allocations: 10.02 kB / 0.864 GB, free: 0.8477 MB / 0.545 GB
"
[Timeout remaining time 649]
(rm -f TILMedia_TILMedia.Testers.TestSolid.pipe ; mkfifo TILMedia_TILMedia.Testers.TestSolid.pipe ; head -c 1048576 < TILMedia_TILMedia.Testers.TestSolid.pipe >> ../files/TILMedia_TILMedia.Testers.TestSolid.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_TILMedia_TILMedia_Testers_TestSolid_fmu --startTime=0 --stopTime=1 --stepSize=0.0004 --timeout=50 --tolerance=1e-06 TILMedia_TILMedia_Testers_TestSolid.fmu > TILMedia_TILMedia.Testers.TestSolid.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 15.792467155959457]
Failed to read output from testmodel.py, exit status != 0:
11.456097487010993 11.487626577 11.24840074
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f51761ef3a0>