Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Fluid.Sensors.Examples.TraceSubstances.conf.json
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.002632/0.002632, allocations: 93.47 kB / 19.44 MB, free: 336 kB / 13.93 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.00298/0.00298, allocations: 169.2 kB / 22.76 MB, free: 1.664 MB / 18.57 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.546/1.546, allocations: 177.1 MB / 203.1 MB, free: 5.578 MB / 186.7 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-545:108:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-545:116:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.order:0:0-0:0:readonly] Warning: Found duplicate names in package.order file: A, B, C.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.mo:2:1-70:10:writable] Warning: C was referenced in the package.order file, but was not found in package.mo, C/package.mo or C.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo): time 1.331/1.331, allocations: 154.6 MB / 414.1 MB, free: 0.6484 MB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 178]
Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/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)
Running command: translateModel(IDEAS.Fluid.Sensors.Examples.TraceSubstances,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Sensors.Examples.TraceSubstances")
translateModel(IDEAS.Fluid.Sensors.Examples.TraceSubstances,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Sensors.Examples.TraceSubstances") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.583e-06/1.583e-06, allocations: 0 / 0.5197 GB, free: 2.512 MB / 490.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.949e-05/3.107e-05, allocations: 2.312 kB / 0.5197 GB, free: 2.508 MB / 490.7 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Sensors.Examples.TraceSubstances): time 0.5784/0.5784, allocations: 192.2 MB / 0.7074 GB, free: 47.11 MB / 0.6355 GB
Notification: Performance of NFInst.instExpressions: time 0.01525/0.5936, allocations: 14.66 MB / 0.7217 GB, free: 46.92 MB / 0.6355 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001406/0.595, allocations: 54 kB / 0.7217 GB, free: 46.92 MB / 0.6355 GB
Notification: Performance of NFTyping.typeComponents: time 0.001377/0.5964, allocations: 0.4949 MB / 0.7222 GB, free: 46.91 MB / 0.6355 GB
Notification: Performance of NFTyping.typeBindings: time 0.004784/0.6012, allocations: 1.989 MB / 0.7241 GB, free: 46.82 MB / 0.6355 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002514/0.6037, allocations: 0.9542 MB / 0.7251 GB, free: 46.79 MB / 0.6355 GB
Notification: Performance of NFFlatten.flatten: time 0.003873/0.6076, allocations: 2.635 MB / 0.7277 GB, free: 46.32 MB / 0.6355 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001284/0.6089, allocations: 0.8212 MB / 0.7285 GB, free: 46.24 MB / 0.6355 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005531/0.6144, allocations: 2.228 MB / 0.7306 GB, free: 46.03 MB / 0.6355 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001778/0.6162, allocations: 0.8989 MB / 0.7315 GB, free: 45.94 MB / 0.6355 GB
Notification: Performance of NFPackage.collectConstants: time 0.0002152/0.6164, allocations: 107.2 kB / 0.7316 GB, free: 45.94 MB / 0.6355 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002091/0.6185, allocations: 0.9305 MB / 0.7325 GB, free: 45.85 MB / 0.6355 GB
Notification: Performance of NFScalarize.scalarize: time 0.0005573/0.6191, allocations: 0.5384 MB / 0.733 GB, free: 45.79 MB / 0.6355 GB
Notification: Performance of NFVerifyModel.verify: time 0.0007239/0.6198, allocations: 467.6 kB / 0.7335 GB, free: 45.73 MB / 0.6355 GB
Notification: Performance of NFConvertDAE.convert: time 0.00325/0.623, allocations: 1.955 MB / 0.7354 GB, free: 45.55 MB / 0.6355 GB
Notification: Performance of FrontEnd - DAE generated: time 5.26e-06/0.623, allocations: 1.188 kB / 0.7354 GB, free: 45.55 MB / 0.6355 GB
Notification: Performance of FrontEnd: time 1.402e-06/0.623, allocations: 0.75 kB / 0.7354 GB, free: 45.55 MB / 0.6355 GB
Notification: Performance of Transformations before backend: time 3.208e-05/0.6231, allocations: 0 / 0.7354 GB, free: 45.55 MB / 0.6355 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 226
 * Number of variables: 226
Notification: Performance of Generate backend data structure: time 0.002759/0.6258, allocations: 1.588 MB / 0.737 GB, free: 44.73 MB / 0.6355 GB
Notification: Performance of prepare preOptimizeDAE: time 4.035e-05/0.6259, allocations: 10.72 kB / 0.737 GB, free: 44.73 MB / 0.6355 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001666/0.6275, allocations: 348.3 kB / 0.7373 GB, free: 44.64 MB / 0.6355 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001683/0.6292, allocations: 0.7587 MB / 0.738 GB, free: 44.36 MB / 0.6355 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.371e-05/0.6293, allocations: 67.94 kB / 0.7381 GB, free: 44.36 MB / 0.6355 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001519/0.6294, allocations: 96.03 kB / 0.7382 GB, free: 44.36 MB / 0.6355 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001922/0.6313, allocations: 1.158 MB / 0.7393 GB, free: 43.9 MB / 0.6355 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.196e-05/0.6314, allocations: 2.344 kB / 0.7393 GB, free: 43.9 MB / 0.6355 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.141e-05/0.6315, allocations: 37.78 kB / 0.7394 GB, free: 43.9 MB / 0.6355 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.486e-05/0.6315, allocations: 38.41 kB / 0.7394 GB, free: 43.89 MB / 0.6355 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001139/0.6327, allocations: 0.5678 MB / 0.74 GB, free: 43.89 MB / 0.6355 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.005336/0.638, allocations: 5.178 MB / 0.745 GB, free: 40.72 MB / 0.6355 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0006674/0.6387, allocations: 343.3 kB / 0.7453 GB, free: 40.68 MB / 0.6355 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003096/0.639, allocations: 181.5 kB / 0.7455 GB, free: 40.66 MB / 0.6355 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004688/0.6394, allocations: 78.72 kB / 0.7456 GB, free: 40.66 MB / 0.6355 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.434e-05/0.6395, allocations: 44.02 kB / 0.7456 GB, free: 40.62 MB / 0.6355 GB
Notification: Performance of pre-optimization done (n=49): time 2.094e-06/0.6395, allocations: 0.875 kB / 0.7456 GB, free: 40.62 MB / 0.6355 GB
Notification: Performance of matching and sorting (n=49): time 0.001299/0.6408, allocations: 0.5347 MB / 0.7461 GB, free: 40.54 MB / 0.6355 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.911e-05/0.6408, allocations: 58.86 kB / 0.7462 GB, free: 40.47 MB / 0.6355 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0008621/0.6416, allocations: 0.8118 MB / 0.747 GB, free: 40.27 MB / 0.6355 GB
Notification: Performance of collectPreVariables (initialization): time 5.115e-05/0.6417, allocations: 38.05 kB / 0.747 GB, free: 40.23 MB / 0.6355 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003537/0.6421, allocations: 0.6073 MB / 0.7476 GB, free: 39.91 MB / 0.6355 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001711/0.6422, allocations: 203.8 kB / 0.7478 GB, free: 39.79 MB / 0.6355 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002489/0.6425, allocations: 175.4 kB / 0.748 GB, free: 39.76 MB / 0.6355 GB
Notification: Performance of setup shared object (initialization): time 0.0001945/0.6427, allocations: 0.6114 MB / 0.7486 GB, free: 39.26 MB / 0.6355 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0002497/0.6429, allocations: 136 kB / 0.7487 GB, free: 39.26 MB / 0.6355 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005265/0.6434, allocations: 0.8509 MB / 0.7495 GB, free: 38.39 MB / 0.6355 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0006874/0.6441, allocations: 0.6986 MB / 0.7502 GB, free: 37.96 MB / 0.6355 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.252e-06/0.6441, allocations: 3.125 kB / 0.7502 GB, free: 37.96 MB / 0.6355 GB
Notification: Performance of matching and sorting (n=86) (initialization): time 0.0009936/0.6451, allocations: 0.6541 MB / 0.7509 GB, free: 37.88 MB / 0.6355 GB
Notification: Performance of prepare postOptimizeDAE: time 2.12e-05/0.6452, allocations: 11.88 kB / 0.7509 GB, free: 37.88 MB / 0.6355 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.171e-05/0.6452, allocations: 9.906 kB / 0.7509 GB, free: 37.88 MB / 0.6355 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.614e-05/0.6452, allocations: 12.5 kB / 0.7509 GB, free: 37.88 MB / 0.6355 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005354/0.6457, allocations: 155.4 kB / 0.7511 GB, free: 37.88 MB / 0.6355 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.622e-05/0.6457, allocations: 19.05 kB / 0.7511 GB, free: 37.88 MB / 0.6355 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0007042/0.6464, allocations: 73.33 kB / 0.7511 GB, free: 37.84 MB / 0.6355 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.633e-05/0.6465, allocations: 35.97 kB / 0.7512 GB, free: 37.84 MB / 0.6355 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 36
 * 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 (86):
 * Single equations (assignments): 86
 * 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 0.0003348/0.6468, allocations: 280.2 kB / 0.7514 GB, free: 37.68 MB / 0.6355 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001298/0.647, allocations: 97.95 kB / 0.7515 GB, free: 37.64 MB / 0.6355 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0007441/0.6477, allocations: 433.3 kB / 0.7519 GB, free: 37.58 MB / 0.6355 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.971e-06/0.6477, allocations: 5.75 kB / 0.752 GB, free: 37.58 MB / 0.6355 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.1e-06/0.6477, allocations: 0 / 0.752 GB, free: 37.58 MB / 0.6355 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0004854/0.6482, allocations: 345.8 kB / 0.7523 GB, free: 37.51 MB / 0.6355 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.002177/0.6504, allocations: 1.447 MB / 0.7537 GB, free: 37.1 MB / 0.6355 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.526e-06/0.6504, allocations: 0 / 0.7537 GB, free: 37.1 MB / 0.6355 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004834/0.6509, allocations: 112.3 kB / 0.7538 GB, free: 37.1 MB / 0.6355 GB
Notification: Performance of postOpt tearingSystem (simulation): time 6.011e-06/0.6509, allocations: 2.719 kB / 0.7538 GB, free: 37.1 MB / 0.6355 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.702e-05/0.6509, allocations: 15.69 kB / 0.7538 GB, free: 37.1 MB / 0.6355 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 5.34e-06/0.6509, allocations: 7.188 kB / 0.7538 GB, free: 37.1 MB / 0.6355 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.727e-06/0.6509, allocations: 7.812 kB / 0.7538 GB, free: 37.1 MB / 0.6355 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0008884/0.6518, allocations: 0.7819 MB / 0.7546 GB, free: 36.77 MB / 0.6355 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003187/0.6521, allocations: 141.3 kB / 0.7547 GB, free: 36.72 MB / 0.6355 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 9.681e-05/0.6522, allocations: 11.03 kB / 0.7547 GB, free: 36.72 MB / 0.6355 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0004411/0.6527, allocations: 25.91 kB / 0.7548 GB, free: 36.71 MB / 0.6355 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 7.846e-05/0.6527, allocations: 29.45 kB / 0.7548 GB, free: 36.71 MB / 0.6355 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 3.959e-05/0.6528, allocations: 23.89 kB / 0.7548 GB, free: 36.71 MB / 0.6355 GB
Notification: Performance of sorting global known variables: time 0.0004753/0.6533, allocations: 484.5 kB / 0.7553 GB, free: 36.58 MB / 0.6355 GB
Notification: Performance of sort global known variables: time 8e-08/0.6533, allocations: 0 / 0.7553 GB, free: 36.58 MB / 0.6355 GB
Notification: Performance of remove unused functions: time 0.001006/0.6543, allocations: 311.9 kB / 0.7556 GB, free: 36.54 MB / 0.6355 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 8
 * Number of states: 4 (vol.dynBal.medium.Xi[1],vol.dynBal.U,vol.dynBal.m,vol.dynBal.mC[1])
 * 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 (49):
 * Single equations (assignments): 49
 * 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.0005087/0.6548, allocations: 194.9 kB / 0.7558 GB, free: 36.44 MB / 0.6355 GB
Notification: Performance of simCode: created initialization part: time 0.0007233/0.6555, allocations: 460.1 kB / 0.7562 GB, free: 36.36 MB / 0.6355 GB
Notification: Performance of simCode: created event and clocks part: time 2.685e-06/0.6555, allocations: 0 / 0.7562 GB, free: 36.36 MB / 0.6355 GB
Notification: Performance of simCode: created simulation system equations: time 0.0002494/0.6558, allocations: 202.6 kB / 0.7564 GB, free: 36.3 MB / 0.6355 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001713/0.6575, allocations: 387 kB / 0.7568 GB, free: 36.21 MB / 0.6355 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.002915/0.6604, allocations: 1.709 MB / 0.7584 GB, free: 35.23 MB / 0.6355 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004529/0.6608, allocations: 0.5002 MB / 0.7589 GB, free: 35.07 MB / 0.6355 GB
Notification: Performance of simCode: alias equations: time 0.0006837/0.6615, allocations: 211.7 kB / 0.7591 GB, free: 35.04 MB / 0.6355 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001669/0.6617, allocations: 75.72 kB / 0.7592 GB, free: 35.02 MB / 0.6355 GB
Notification: Performance of SimCode: time 1.022e-06/0.6617, allocations: 0 / 0.7592 GB, free: 35.02 MB / 0.6355 GB
Notification: Performance of Templates: time 0.02215/0.6838, allocations: 14.51 MB / 0.7734 GB, free: 33.43 MB / 0.6355 GB
"
[Timeout remaining time 659]
make -j1 -f IDEAS_IDEAS.Fluid.Sensors.Examples.TraceSubstances.makefile [Timeout 660]
(rm -f IDEAS_IDEAS.Fluid.Sensors.Examples.TraceSubstances.pipe ; mkfifo IDEAS_IDEAS.Fluid.Sensors.Examples.TraceSubstances.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Sensors.Examples.TraceSubstances.pipe >> ../files/IDEAS_IDEAS.Fluid.Sensors.Examples.TraceSubstances.sim & ./IDEAS_IDEAS.Fluid.Sensors.Examples.TraceSubstances -abortSlowSimulation -alarm=240  -lv LOG_STATS > IDEAS_IDEAS.Fluid.Sensors.Examples.TraceSubstances.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 7.183817825047299]