Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource.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 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo", uses=false)
Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+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(IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource")
translateModel(IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001148/0.001148, allocations: 117.4 kB / 16.42 MB, free: 6.5 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.001169/0.001169, allocations: 183.9 kB / 17.35 MB, free: 5.762 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.21/1.21, allocations: 222.9 MB / 241 MB, free: 15.14 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.7304/0.7304, allocations: 135.1 MB / 426.3 MB, free: 4.098 MB / 334.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001121/0.001121, allocations: 66.39 kB / 0.5863 GB, free: 21.68 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1186/0.1197, allocations: 80.93 MB / 0.6653 GB, free: 5.777 MB / 0.4981 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource): time 0.5359/0.6556, allocations: 305.1 MB / 0.9633 GB, free: 13.71 MB / 0.7325 GB
Notification: Performance of NFInst.instExpressions: time 0.0311/0.6867, allocations: 25.8 MB / 0.9885 GB, free: 3.852 MB / 0.7481 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003865/0.6906, allocations: 135.2 kB / 0.9886 GB, free: 3.719 MB / 0.7481 GB
Notification: Performance of NFTyping.typeComponents: time 0.005086/0.6957, allocations: 1.856 MB / 0.9904 GB, free: 1.852 MB / 0.7481 GB
Notification: Performance of NFTyping.typeBindings: time 0.01336/0.7091, allocations: 6.176 MB / 0.9964 GB, free: 11.65 MB / 0.7637 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005671/0.7148, allocations: 2.636 MB / 0.999 GB, free: 9.031 MB / 0.7637 GB
Notification: Performance of NFFlatten.flatten: time 0.009238/0.7241, allocations: 8.918 MB / 1.008 GB, free: 96 kB / 0.7637 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004343/0.7284, allocations: 3.633 MB / 1.011 GB, free: 12.38 MB / 0.7794 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.008135/0.7366, allocations: 4.287 MB / 1.015 GB, free: 8.125 MB / 0.7794 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.004447/0.741, allocations: 2.963 MB / 1.018 GB, free: 5.164 MB / 0.7794 GB
Notification: Performance of NFPackage.collectConstants: time 0.000792/0.7418, allocations: 356 kB / 1.019 GB, free: 4.816 MB / 0.7794 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.008259/0.7501, allocations: 4.618 MB / 1.023 GB, free: 192 kB / 0.7794 GB
Notification: Performance of NFScalarize.scalarize: time 0.002024/0.7521, allocations: 1.644 MB / 1.025 GB, free: 14.54 MB / 0.795 GB
Notification: Performance of NFVerifyModel.verify: time 0.003613/0.7558, allocations: 2.143 MB / 1.027 GB, free: 12.39 MB / 0.795 GB
Notification: Performance of NFConvertDAE.convert: time 0.009416/0.7652, allocations: 6.758 MB / 1.033 GB, free: 5.609 MB / 0.795 GB
Notification: Performance of FrontEnd - DAE generated: time 3.366e-06/0.7652, allocations: 0 / 1.033 GB, free: 5.609 MB / 0.795 GB
Notification: Performance of FrontEnd: time 1.242e-06/0.7652, allocations: 0 / 1.033 GB, free: 5.609 MB / 0.795 GB
Notification: Performance of Transformations before backend: time 0.0001341/0.7654, allocations: 0 / 1.033 GB, free: 5.609 MB / 0.795 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 693
 * Number of variables: 693
Notification: Performance of Generate backend data structure: time 0.007096/0.7725, allocations: 4.176 MB / 1.038 GB, free: 1.363 MB / 0.795 GB
Notification: Performance of prepare preOptimizeDAE: time 3.684e-05/0.7725, allocations: 9.109 kB / 1.038 GB, free: 1.355 MB / 0.795 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005352/0.7779, allocations: 0.9322 MB / 1.038 GB, free: 428 kB / 0.795 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004132/0.782, allocations: 2.404 MB / 1.041 GB, free: 13.93 MB / 0.8106 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002077/0.7822, allocations: 273.3 kB / 1.041 GB, free: 13.65 MB / 0.8106 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003899/0.7826, allocations: 295.9 kB / 1.041 GB, free: 13.36 MB / 0.8106 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004931/0.7876, allocations: 3.351 MB / 1.045 GB, free: 9.926 MB / 0.8106 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.745e-05/0.7876, allocations: 4 kB / 1.045 GB, free: 9.922 MB / 0.8106 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002371/0.7879, allocations: 112 kB / 1.045 GB, free: 9.812 MB / 0.8106 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001056/0.788, allocations: 113.3 kB / 1.045 GB, free: 9.699 MB / 0.8106 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004045/0.792, allocations: 2.792 MB / 1.048 GB, free: 6.895 MB / 0.8106 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.2715/1.064, allocations: 13.23 MB / 1.061 GB, free: 305 MB / 0.8106 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002347/1.066, allocations: 1.824 MB / 1.062 GB, free: 304.6 MB / 0.8106 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001402/1.067, allocations: 1.103 MB / 1.063 GB, free: 304.2 MB / 0.8106 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003651/1.068, allocations: 324.5 kB / 1.064 GB, free: 303.9 MB / 0.8106 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.588e-05/1.068, allocations: 60.33 kB / 1.064 GB, free: 303.9 MB / 0.8106 GB
Notification: Performance of pre-optimization done (n=182): time 3.717e-06/1.068, allocations: 0 / 1.064 GB, free: 303.9 MB / 0.8106 GB
Notification: Performance of matching and sorting (n=182): time 0.004712/1.072, allocations: 2.723 MB / 1.066 GB, free: 303 MB / 0.8106 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.482e-05/1.072, allocations: 73.25 kB / 1.066 GB, free: 302.9 MB / 0.8106 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00229/1.075, allocations: 1.997 MB / 1.068 GB, free: 302.2 MB / 0.8106 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001529/1.075, allocations: 66.77 kB / 1.068 GB, free: 302.1 MB / 0.8106 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0007032/1.076, allocations: 1.412 MB / 1.07 GB, free: 301.1 MB / 0.8106 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0004242/1.076, allocations: 0.6429 MB / 1.07 GB, free: 300.6 MB / 0.8106 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004967/1.077, allocations: 390 kB / 1.071 GB, free: 300.4 MB / 0.8106 GB
Notification: Performance of setup shared object (initialization): time 3.181e-05/1.077, allocations: 301.1 kB / 1.071 GB, free: 300.1 MB / 0.8106 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001145/1.078, allocations: 0.8063 MB / 1.072 GB, free: 299.8 MB / 0.8106 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001461/1.079, allocations: 1.434 MB / 1.073 GB, free: 298.8 MB / 0.8106 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002505/1.082, allocations: 2.251 MB / 1.076 GB, free: 297.5 MB / 0.8106 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.176e-06/1.082, allocations: 5.312 kB / 1.076 GB, free: 297.5 MB / 0.8106 GB
Notification: Performance of matching and sorting (n=257) (initialization): time 0.003456/1.085, allocations: 2.489 MB / 1.078 GB, free: 296.5 MB / 0.8106 GB
Notification: Performance of prepare postOptimizeDAE: time 2.315e-05/1.085, allocations: 11.66 kB / 1.078 GB, free: 296.5 MB / 0.8106 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.702e-05/1.085, allocations: 12.75 kB / 1.078 GB, free: 296.5 MB / 0.8106 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.402e-05/1.085, allocations: 27.5 kB / 1.078 GB, free: 296.5 MB / 0.8106 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002337/1.088, allocations: 0.5509 MB / 1.079 GB, free: 296.5 MB / 0.8106 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001157/1.089, allocations: 4.159 MB / 1.083 GB, free: 292.3 MB / 0.8106 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00217/1.091, allocations: 224.4 kB / 1.083 GB, free: 292.2 MB / 0.8106 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001327/1.091, allocations: 90.53 kB / 1.083 GB, free: 292.2 MB / 0.8106 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001969/1.093, allocations: 1.46 MB / 1.084 GB, free: 291.6 MB / 0.8106 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001285/1.094, allocations: 1.427 MB / 1.086 GB, free: 290.5 MB / 0.8106 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.002229/1.097, allocations: 2.231 MB / 1.088 GB, free: 289.3 MB / 0.8106 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 7.073e-06/1.097, allocations: 4.188 kB / 1.088 GB, free: 289.3 MB / 0.8106 GB
Notification: Performance of matching and sorting (n=257) (initialization_lambda0): time 0.002907/1.1, allocations: 2.474 MB / 1.09 GB, free: 288.3 MB / 0.8106 GB
Notification: Performance of prepare postOptimizeDAE: time 1.263e-05/1.1, allocations: 13.78 kB / 1.09 GB, free: 288.3 MB / 0.8106 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.748e-06/1.1, allocations: 11.84 kB / 1.09 GB, free: 288.3 MB / 0.8106 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.405e-05/1.1, allocations: 23.75 kB / 1.09 GB, free: 288.3 MB / 0.8106 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001416/1.101, allocations: 476.3 kB / 1.091 GB, free: 288.2 MB / 0.8106 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.832e-05/1.101, allocations: 31.02 kB / 1.091 GB, free: 288.2 MB / 0.8106 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001946/1.103, allocations: 190 kB / 1.091 GB, free: 288.1 MB / 0.8106 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001035/1.103, allocations: 86.61 kB / 1.091 GB, free: 288.1 MB / 0.8106 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 59
 * 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 (257):
 * Single equations (assignments): 250
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 7
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 7 systems
   {1, 1, 1, 1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0003526/1.104, allocations: 319.2 kB / 1.091 GB, free: 287.8 MB / 0.8106 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002997/1.104, allocations: 271.4 kB / 1.092 GB, free: 287.7 MB / 0.8106 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002499/1.106, allocations: 2.094 MB / 1.094 GB, free: 286.8 MB / 0.8106 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001814/1.108, allocations: 1.883 MB / 1.096 GB, free: 286 MB / 0.8106 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.667e-06/1.108, allocations: 0 / 1.096 GB, free: 286 MB / 0.8106 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008117/1.109, allocations: 0.565 MB / 1.096 GB, free: 285.6 MB / 0.8106 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002709/1.109, allocations: 39.86 kB / 1.096 GB, free: 285.6 MB / 0.8106 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001463/1.111, allocations: 80.19 kB / 1.096 GB, free: 285.5 MB / 0.8106 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001657/1.111, allocations: 105.2 kB / 1.096 GB, free: 285.5 MB / 0.8106 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.003554/1.114, allocations: 2.33 MB / 1.099 GB, free: 284.3 MB / 0.8106 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.003242/1.118, allocations: 3.418 MB / 1.102 GB, free: 282.4 MB / 0.8106 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0008235/1.119, allocations: 0.918 MB / 1.103 GB, free: 281.9 MB / 0.8106 GB
Notification: Performance of sorting global known variables: time 0.001176/1.12, allocations: 1.331 MB / 1.104 GB, free: 281.2 MB / 0.8106 GB
Notification: Performance of Backend: time 6.71e-07/1.12, allocations: 0 / 1.104 GB, free: 281.2 MB / 0.8106 GB
Notification: Performance of simCode: created initialization part: time 0.00526/1.125, allocations: 3.532 MB / 1.108 GB, free: 279.1 MB / 0.8106 GB
Notification: Performance of SimCode: time 0.00829/1.133, allocations: 7.443 MB / 1.115 GB, free: 271.8 MB / 0.8106 GB
Notification: Performance of Templates: time 0.07024/1.204, allocations: 69.48 MB / 1.183 GB, free: 202.8 MB / 0.8106 GB
make -j1 -f IDEAS_dev_IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource.makefile
(rm -f IDEAS_dev_IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource.sim & ./IDEAS_dev_IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.Sources.Examples.TraceSubstancesFlowSource.pipe 2>&1)