Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.MassExchangers.Examples.ConstantEffectiveness.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: "" <> buildModelFMU(IDEAS.Fluid.MassExchangers.Examples.ConstantEffectiveness,fileNamePrefix="IDEAS_dev_IDEAS_Fluid_MassExchangers_Examples_ConstantEffectiveness",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(IDEAS.Fluid.MassExchangers.Examples.ConstantEffectiveness,fileNamePrefix="IDEAS_dev_IDEAS_Fluid_MassExchangers_Examples_ConstantEffectiveness",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0009048/0.0009048, allocations: 91.06 kB / 15.36 MB, free: 0.7383 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.007605/0.007605, allocations: 172.4 kB / 16.26 MB, free: 6.613 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.344/1.344, allocations: 223.3 MB / 240.3 MB, free: 5.332 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.9235/0.9235, allocations: 135.3 MB / 426.7 MB, free: 14.28 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0002812/0.0002812, allocations: 17.11 kB / 0.5077 GB, free: 4.656 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1591/0.1593, allocations: 82.3 MB / 0.5881 GB, free: 2.52 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.MassExchangers.Examples.ConstantEffectiveness): time 0.5127/0.6721, allocations: 209.2 MB / 0.7923 GB, free: 11.02 MB / 0.6075 GB
Notification: Performance of NFInst.instExpressions: time 0.01067/0.6828, allocations: 7.064 MB / 0.7992 GB, free: 3.949 MB / 0.6075 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001205/0.684, allocations: 39.69 kB / 0.7993 GB, free: 3.91 MB / 0.6075 GB
Notification: Performance of NFTyping.typeComponents: time 0.001703/0.6857, allocations: 0.6528 MB / 0.7999 GB, free: 3.254 MB / 0.6075 GB
Notification: Performance of NFTyping.typeBindings: time 0.005664/0.6914, allocations: 2.35 MB / 0.8022 GB, free: 0.8945 MB / 0.6075 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002643/0.694, allocations: 1.069 MB / 0.8032 GB, free: 15.84 MB / 0.6231 GB
Notification: Performance of NFFlatten.flatten: time 0.00413/0.6982, allocations: 3.741 MB / 0.8069 GB, free: 12.1 MB / 0.6231 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001321/0.6995, allocations: 0.9103 MB / 0.8078 GB, free: 11.16 MB / 0.6231 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003964/0.7035, allocations: 1.759 MB / 0.8095 GB, free: 9.398 MB / 0.6231 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00208/0.7056, allocations: 0.896 MB / 0.8104 GB, free: 8.504 MB / 0.6231 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001299/0.7057, allocations: 44 kB / 0.8104 GB, free: 8.461 MB / 0.6231 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.004897/0.7106, allocations: 2.81 MB / 0.8132 GB, free: 5.645 MB / 0.6231 GB
Notification: Performance of NFScalarize.scalarize: time 0.0004044/0.711, allocations: 342.7 kB / 0.8135 GB, free: 5.309 MB / 0.6231 GB
Notification: Performance of NFVerifyModel.verify: time 0.0007186/0.7118, allocations: 0.6234 MB / 0.8141 GB, free: 4.676 MB / 0.6231 GB
Notification: Performance of NFConvertDAE.convert: time 0.003259/0.715, allocations: 2.52 MB / 0.8166 GB, free: 2.148 MB / 0.6231 GB
Notification: Performance of FrontEnd - DAE generated: time 3.577e-06/0.715, allocations: 0 / 0.8166 GB, free: 2.148 MB / 0.6231 GB
Notification: Performance of FrontEnd: time 2.795e-06/0.7151, allocations: 4 kB / 0.8166 GB, free: 2.145 MB / 0.6231 GB
Notification: Performance of Transformations before backend: time 1.502e-05/0.7151, allocations: 0 / 0.8166 GB, free: 2.145 MB / 0.6231 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 209
 * Number of variables: 209
Notification: Performance of Generate backend data structure: time 0.003892/0.719, allocations: 1.608 MB / 0.8181 GB, free: 488 kB / 0.6231 GB
Notification: Performance of prepare preOptimizeDAE: time 4.25e-05/0.719, allocations: 15.97 kB / 0.8182 GB, free: 472 kB / 0.6231 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0001709/0.7192, allocations: 131.1 kB / 0.8183 GB, free: 332 kB / 0.6231 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00313/0.7223, allocations: 0.5799 MB / 0.8188 GB, free: 15.74 MB / 0.6387 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001663/0.724, allocations: 0.9642 MB / 0.8198 GB, free: 14.73 MB / 0.6387 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006595/0.7247, allocations: 197.9 kB / 0.82 GB, free: 14.54 MB / 0.6387 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001501/0.7249, allocations: 84 kB / 0.8201 GB, free: 14.46 MB / 0.6387 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00271/0.7276, allocations: 1.272 MB / 0.8213 GB, free: 13.18 MB / 0.6387 GB
Notification: Performance of preOpt findStateOrder (simulation): time 1.814e-05/0.7276, allocations: 0 / 0.8213 GB, free: 13.18 MB / 0.6387 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001001/0.7277, allocations: 36 kB / 0.8213 GB, free: 13.14 MB / 0.6387 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.735e-05/0.7278, allocations: 53.91 kB / 0.8214 GB, free: 13.09 MB / 0.6387 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003759/0.7315, allocations: 1.612 MB / 0.823 GB, free: 11.48 MB / 0.6387 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.006038/0.7376, allocations: 4.095 MB / 0.827 GB, free: 7.105 MB / 0.6387 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002585/0.7402, allocations: 1.048 MB / 0.828 GB, free: 6.051 MB / 0.6387 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001582/0.7418, allocations: 0.6982 MB / 0.8287 GB, free: 5.352 MB / 0.6387 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.00109/0.7429, allocations: 0.6359 MB / 0.8293 GB, free: 4.602 MB / 0.6387 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.037e-05/0.7429, allocations: 47.14 kB / 0.8293 GB, free: 4.547 MB / 0.6387 GB
Notification: Performance of pre-optimization done (n=76): time 2.274e-06/0.7429, allocations: 0 / 0.8293 GB, free: 4.547 MB / 0.6387 GB
Notification: Performance of matching and sorting (n=76): time 0.004895/0.7478, allocations: 1.918 MB / 0.8312 GB, free: 2.629 MB / 0.6387 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.899e-05/0.7479, allocations: 64.05 kB / 0.8313 GB, free: 2.551 MB / 0.6387 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0009391/0.7488, allocations: 0.8192 MB / 0.8321 GB, free: 1.707 MB / 0.6387 GB
Notification: Performance of collectPreVariables (initialization): time 8.368e-05/0.7489, allocations: 45.7 kB / 0.8321 GB, free: 1.656 MB / 0.6387 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003328/0.7493, allocations: 436.6 kB / 0.8325 GB, free: 1.223 MB / 0.6387 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001956/0.7495, allocations: 256.2 kB / 0.8328 GB, free: 0.9688 MB / 0.6387 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001143/0.7496, allocations: 61.69 kB / 0.8328 GB, free: 0.9023 MB / 0.6387 GB
Notification: Performance of setup shared object (initialization): time 0.0001099/0.7497, allocations: 337.9 kB / 0.8331 GB, free: 0.5664 MB / 0.6387 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001452/0.7512, allocations: 0.568 MB / 0.8337 GB, free: 16 MB / 0.6544 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001559/0.7527, allocations: 0.7027 MB / 0.8344 GB, free: 15.27 MB / 0.6544 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002913/0.7557, allocations: 1.217 MB / 0.8356 GB, free: 14.03 MB / 0.6544 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.218e-06/0.7557, allocations: 0 / 0.8356 GB, free: 14.03 MB / 0.6544 GB
Notification: Performance of matching and sorting (n=87) (initialization): time 0.003333/0.759, allocations: 1.446 MB / 0.837 GB, free: 12.57 MB / 0.6544 GB
Notification: Performance of prepare postOptimizeDAE: time 9.687e-05/0.7591, allocations: 152.8 kB / 0.8371 GB, free: 12.4 MB / 0.6544 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.543e-06/0.7591, allocations: 0 / 0.8371 GB, free: 12.4 MB / 0.6544 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.534e-05/0.7592, allocations: 4 kB / 0.8371 GB, free: 12.39 MB / 0.6544 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00152/0.7607, allocations: 283.6 kB / 0.8374 GB, free: 12.12 MB / 0.6544 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0006541/0.7613, allocations: 1.184 MB / 0.8386 GB, free: 10.89 MB / 0.6544 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001189/0.7625, allocations: 199.3 kB / 0.8388 GB, free: 10.69 MB / 0.6544 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.46e-05/0.7626, allocations: 39.97 kB / 0.8388 GB, free: 10.65 MB / 0.6544 GB
Notification: Performance of prepare postOptimizeDAE: time 7.434e-06/0.7627, allocations: 4 kB / 0.8388 GB, free: 10.65 MB / 0.6544 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.003197/0.7659, allocations: 1.329 MB / 0.8401 GB, free: 9.312 MB / 0.6544 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.178e-06/0.7659, allocations: 4.062 kB / 0.8401 GB, free: 9.309 MB / 0.6544 GB
Notification: Performance of postOpt tearingSystem (initialization): time 6.242e-06/0.7659, allocations: 4 kB / 0.8401 GB, free: 9.305 MB / 0.6544 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001178/0.7671, allocations: 255.7 kB / 0.8403 GB, free: 9.055 MB / 0.6544 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 6.512e-06/0.7671, allocations: 4 kB / 0.8403 GB, free: 9.051 MB / 0.6544 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00107/0.7682, allocations: 199.1 kB / 0.8405 GB, free: 8.855 MB / 0.6544 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.442e-05/0.7682, allocations: 35.97 kB / 0.8406 GB, free: 8.82 MB / 0.6544 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 5
 * Number of states: 0 ()
 * Number of discrete variables: 2 (hex.i2_w,hex.i1_w)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (86):
 * Single equations (assignments): 83
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 2
 * 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): 2 systems
   {1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0005166/0.7688, allocations: 373 kB / 0.8409 GB, free: 8.445 MB / 0.6544 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000154/0.7689, allocations: 102.7 kB / 0.841 GB, free: 8.344 MB / 0.6544 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003462/0.7724, allocations: 1.445 MB / 0.8424 GB, free: 6.887 MB / 0.6544 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.925e-06/0.7724, allocations: 8 kB / 0.8424 GB, free: 6.879 MB / 0.6544 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.04e-06/0.7724, allocations: 0 / 0.8424 GB, free: 6.879 MB / 0.6544 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.406e-06/0.7724, allocations: 3.984 kB / 0.8424 GB, free: 6.875 MB / 0.6544 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.005253/0.7777, allocations: 2.921 MB / 0.8453 GB, free: 3.855 MB / 0.6544 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.723e-06/0.7777, allocations: 8.062 kB / 0.8453 GB, free: 3.848 MB / 0.6544 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001198/0.7789, allocations: 247.7 kB / 0.8455 GB, free: 3.605 MB / 0.6544 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.208e-05/0.7789, allocations: 11.94 kB / 0.8456 GB, free: 3.594 MB / 0.6544 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.871e-05/0.779, allocations: 23.98 kB / 0.8456 GB, free: 3.57 MB / 0.6544 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0005592/0.7796, allocations: 1.199 MB / 0.8468 GB, free: 2.324 MB / 0.6544 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.011e-06/0.7796, allocations: 7.938 kB / 0.8468 GB, free: 2.316 MB / 0.6544 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.00325/0.7828, allocations: 1.529 MB / 0.8483 GB, free: 0.7227 MB / 0.6544 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004321/0.7833, allocations: 315.4 kB / 0.8486 GB, free: 404 kB / 0.6544 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001209/0.7834, allocations: 16 kB / 0.8486 GB, free: 388 kB / 0.6544 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001201/0.7846, allocations: 171.7 kB / 0.8487 GB, free: 216 kB / 0.6544 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001203/0.7848, allocations: 52.44 kB / 0.8488 GB, free: 164 kB / 0.6544 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.091e-05/0.7848, allocations: 35.97 kB / 0.8488 GB, free: 128 kB / 0.6544 GB
Notification: Performance of sorting global known variables: time 0.0007661/0.7856, allocations: 0.5739 MB / 0.8494 GB, free: 15.54 MB / 0.67 GB
Notification: Performance of sort global known variables: time 1.3e-07/0.7856, allocations: 0 / 0.8494 GB, free: 15.54 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.001272/0.7869, allocations: 0.5341 MB / 0.8499 GB, free: 15 MB / 0.67 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 16
 * 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 (76):
 * Single equations (assignments): 74
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 2
 * 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): 2 systems
   {1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.003909/0.7908, allocations: 2.254 MB / 0.8521 GB, free: 12.7 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.005184/0.796, allocations: 2.151 MB / 0.8542 GB, free: 10.52 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 5.67e-06/0.796, allocations: 4.062 kB / 0.8542 GB, free: 10.52 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.001687/0.7977, allocations: 0.7065 MB / 0.8549 GB, free: 9.812 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002905/0.8006, allocations: 0.5124 MB / 0.8554 GB, free: 9.309 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2555/1.056, allocations: 9.683 MB / 0.8649 GB, free: 217.1 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005455/1.057, allocations: 1.096 MB / 0.8659 GB, free: 216.4 MB / 0.67 GB
Notification: Performance of simCode: alias equations: time 0.001715/1.058, allocations: 385.3 kB / 0.8663 GB, free: 216.4 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005481/1.059, allocations: 391.2 kB / 0.8667 GB, free: 216.4 MB / 0.67 GB
Notification: Performance of SimCode: time 9.92e-07/1.059, allocations: 0 / 0.8667 GB, free: 216.4 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.5259/1.585, allocations: 27.47 MB / 0.8935 GB, free: 215.1 MB / 0.67 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0002167/1.585, allocations: 273.2 kB / 0.8937 GB, free: 214.7 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate platform static: time 2.931/4.516, allocations: 0 / 0.8937 GB, free: 214.7 MB / 0.67 GB
(rm -f IDEAS_dev_IDEAS.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.MassExchangers.Examples.ConstantEffectiveness.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_IDEAS_dev_IDEAS_Fluid_MassExchangers_Examples_ConstantEffectiveness_fmu --startTime=0 --stopTime=360 --timeout=50 --tolerance=1e-06 IDEAS_dev_IDEAS_Fluid_MassExchangers_Examples_ConstantEffectiveness.fmu > IDEAS_dev_IDEAS.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe 2>&1)