Running: ./testmodel.py --libraries=/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelica/./OMCompiler/build/lib/omlibrary/ --ompython_omhome=/usr Modelica_3.2.2_Modelica.StateGraph.Examples.ControlledTanks.conf.json
Using package ModelicaServices with version 3.2.2 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/build/lib/omlibrary/ModelicaServices 3.2.2/package.mo)
Using package Complex with version 3.2.2 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/build/lib/omlibrary/Complex 3.2.2.mo)
Using package Modelica with version 3.2.2 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/build/lib/omlibrary/Modelica 3.2.2/package.mo)
Running command: translateModel(Modelica.StateGraph.Examples.ControlledTanks,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|tank1.level|tank2.level|shut.on|shut.reset.1.|shut.reset.2.|start.on|start.reset.1.|start.reset.2.|stop.on|stop.reset.1.|stop.reset.2.|tankController.T1.enableFire|tankController.T2.enableFire|tankController.T3.enableFire|tankController.T4.enableFire|tankController.T5.enableFire|tankController.T6.enableFire|tankController.makeProduct.T1.enableFire|tankController.makeProduct.T2.enableFire|tankController.makeProduct.T3.enableFire|tankController.makeProduct.T4.enableFire",fileNamePrefix="Modelica_3.2.2_Modelica.StateGraph.Examples.ControlledTanks")
Notification: Performance of loadModel(Modelica): time 2.031/2.031, allocations: 194.9 MB / 211.7 MB, free: 3.008 MB / 170.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003504/0.0003504, allocations: 8 kB / 264.5 MB, free: 12.96 MB / 218.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.09039/0.09076, allocations: 44.52 MB / 309.1 MB, free: 380 kB / 250.7 MB
Notification: Performance of NFInst.instantiate(Modelica.StateGraph.Examples.ControlledTanks): time 0.005124/0.09594, allocations: 2.639 MB / 311.7 MB, free: 13.71 MB / 266.7 MB
Notification: Performance of NFInst.instExpressions: time 0.00446/0.1005, allocations: 1.897 MB / 313.6 MB, free: 11.81 MB / 266.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0004582/0.101, allocations: 23.88 kB / 313.6 MB, free: 11.79 MB / 266.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0003149/0.1013, allocations: 103.1 kB / 313.7 MB, free: 11.68 MB / 266.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.0004739/0.1018, allocations: 91.64 kB / 313.8 MB, free: 11.59 MB / 266.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002397/0.1042, allocations: 0.585 MB / 314.4 MB, free: 11.01 MB / 266.7 MB
Notification: Performance of NFFlatten.flatten: time 0.001931/0.1061, allocations: 0.9448 MB / 315.3 MB, free: 10.06 MB / 266.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.00144/0.1076, allocations: 0.7586 MB / 316.1 MB, free: 9.285 MB / 266.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.000591/0.1082, allocations: 358.7 kB / 316.5 MB, free: 8.934 MB / 266.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0006097/0.1088, allocations: 398 kB / 316.8 MB, free: 8.543 MB / 266.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001524/0.109, allocations: 52 kB / 316.9 MB, free: 8.492 MB / 266.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0004987/0.1095, allocations: 171.8 kB / 317.1 MB, free: 8.324 MB / 266.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003425/0.1099, allocations: 235.2 kB / 317.3 MB, free: 8.094 MB / 266.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002889/0.1102, allocations: 217.1 kB / 317.5 MB, free: 7.875 MB / 266.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.002061/0.1123, allocations: 1.391 MB / 318.9 MB, free: 6.48 MB / 266.7 MB
Notification: Performance of FrontEnd - DAE generated: time 3.577e-06/0.1123, allocations: 3.938 kB / 318.9 MB, free: 6.477 MB / 266.7 MB
Notification: Performance of FrontEnd: time 4.949e-06/0.1123, allocations: 3.984 kB / 318.9 MB, free: 6.473 MB / 266.7 MB
Notification: Performance of Transformations before backend: time 3.121e-05/0.1123, allocations: 0 / 318.9 MB, free: 6.473 MB / 266.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 292
 * Number of variables: 292
Notification: Performance of Generate backend data structure: time 0.002607/0.115, allocations: 1.314 MB / 320.2 MB, free: 5.039 MB / 266.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.007e-05/0.115, allocations: 12.03 kB / 320.2 MB, free: 5.027 MB / 266.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0003271/0.1154, allocations: 118.7 kB / 320.3 MB, free: 4.91 MB / 266.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001415/0.1168, allocations: 0.6228 MB / 321 MB, free: 4.246 MB / 266.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002586/0.1171, allocations: 191.7 kB / 321.2 MB, free: 4.039 MB / 266.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000306/0.1174, allocations: 136 kB / 321.3 MB, free: 3.906 MB / 266.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003492/0.1209, allocations: 1.338 MB / 322.6 MB, free: 2.457 MB / 266.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.157e-05/0.121, allocations: 7.938 kB / 322.6 MB, free: 2.449 MB / 266.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000168/0.1212, allocations: 48 kB / 322.7 MB, free: 2.402 MB / 266.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.883e-05/0.1213, allocations: 45.98 kB / 322.7 MB, free: 2.355 MB / 266.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002844/0.1241, allocations: 0.9523 MB / 323.7 MB, free: 1.398 MB / 266.7 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.0122/0.1364, allocations: 5.147 MB / 328.8 MB, free: 12.1 MB / 282.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002394/0.1388, allocations: 0.9821 MB / 329.8 MB, free: 11.05 MB / 282.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001764/0.139, allocations: 94.64 kB / 329.9 MB, free: 10.94 MB / 282.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001597/0.1406, allocations: 0.7061 MB / 330.6 MB, free: 10.18 MB / 282.7 MB
Notification: Performance of pre-optimization done (n=110): time 4.268e-06/0.1407, allocations: 3.938 kB / 330.6 MB, free: 10.18 MB / 282.7 MB
Notification: Performance of matching and sorting (n=110): time 0.003976/0.1446, allocations: 1.873 MB / 332.5 MB, free: 8.156 MB / 282.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002519/0.1449, allocations: 400.8 kB / 332.9 MB, free: 7.68 MB / 282.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0008814/0.1458, allocations: 0.5048 MB / 333.4 MB, free: 7.168 MB / 282.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002419/0.1461, allocations: 97.67 kB / 333.5 MB, free: 7.066 MB / 282.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004746/0.1466, allocations: 428.3 kB / 333.9 MB, free: 6.641 MB / 282.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003871/0.147, allocations: 276.5 kB / 334.2 MB, free: 6.367 MB / 282.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001043/0.1471, allocations: 32 kB / 334.2 MB, free: 6.336 MB / 282.7 MB
Notification: Performance of setup shared object (initialization): time 0.0002499/0.1473, allocations: 390.7 kB / 334.6 MB, free: 5.945 MB / 282.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001224/0.1486, allocations: 395.8 kB / 335 MB, free: 5.559 MB / 282.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00149/0.1501, allocations: 0.7072 MB / 335.7 MB, free: 4.723 MB / 282.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001354/0.1515, allocations: 0.65 MB / 336.3 MB, free: 4.02 MB / 282.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.376e-05/0.1515, allocations: 4 kB / 336.3 MB, free: 4.016 MB / 282.7 MB
Notification: Performance of matching and sorting (n=197) (initialization): time 0.003603/0.1551, allocations: 1.534 MB / 337.9 MB, free: 2.418 MB / 282.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0002486/0.1554, allocations: 267.3 kB / 338.1 MB, free: 2.102 MB / 282.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.24e-05/0.1554, allocations: 8 kB / 338.1 MB, free: 2.094 MB / 282.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 4.869e-05/0.1555, allocations: 20.72 kB / 338.1 MB, free: 2.074 MB / 282.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.000878/0.1564, allocations: 139.6 kB / 338.3 MB, free: 1.938 MB / 282.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.777e-05/0.1564, allocations: 31.92 kB / 338.3 MB, free: 1.906 MB / 282.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0007872/0.1572, allocations: 134.7 kB / 338.4 MB, free: 1.773 MB / 282.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001681/0.1574, allocations: 63.97 kB / 338.5 MB, free: 1.711 MB / 282.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 41
 * Number of states: 0 ()
 * Number of discrete variables: 149 ($PRE.tankController.makeProduct.T1.enableFire,$PRE.tankController.makeProduct.T3.enableFire,$PRE.tankController.makeProduct.T2.enableFire,$PRE.tankController.makeProduct.T4.enableFire,$PRE.tankController.T1.enableFire,$PRE.tankController.T2.enableFire,$PRE.tankController.T3.enableFire,$PRE.tankController.T4.enableFire,$PRE.tankController.T5.enableFire,$PRE.tankController.T6.enableFire,$whenCondition3,$whenCondition2,$whenCondition1,$whenCondition5,$whenCondition4,$whenCondition6,$whenCondition8,$whenCondition7,$whenCondition9,$whenCondition11,$whenCondition10,$whenCondition12,tankController.s1.active,$PRE.tankController.s1.localActive,tankController.s1.localActive,tankController.s1.inPort[2].occupied,$PRE.tankController.s1.newActive,tankController.s1.newActive,$PRE.tankController.s1.oldActive,tankController.s1.oldActive,tankController.makeProduct.stateGraphRoot.suspend,tankController.makeProduct.stateGraphRoot.resume,tankController.makeProduct.active,$PRE.tankController.makeProduct.newActive,tankController.makeProduct.newActive,tankController.makeProduct.activeSteps,tankController.makeProduct.fillTank1.active,$PRE.tankController.makeProduct.fillTank1.localActive,tankController.makeProduct.fillTank1.localActive,$PRE.tankController.makeProduct.fillTank1.newActive,tankController.makeProduct.fillTank1.newActive,$PRE.tankController.makeProduct.fillTank1.oldActive,tankController.makeProduct.fillTank1.oldActive,tankController.makeProduct.T1.condition,tankController.makeProduct.T1.enableFire,tankController.makeProduct.fillTank2.active,$PRE.tankController.makeProduct.fillTank2.localActive,tankController.makeProduct.fillTank2.localActive,$PRE.tankController.makeProduct.fillTank2.newActive,tankController.makeProduct.fillTank2.newActive,$PRE.tankController.makeProduct.fillTank2.oldActive,tankController.makeProduct.fillTank2.oldActive,tankController.makeProduct.T3.condition,tankController.makeProduct.T3.enableFire,tankController.makeProduct.emptyTank2.active,$PRE.tankController.makeProduct.emptyTank2.localActive,tankController.makeProduct.emptyTank2.localActive,$PRE.tankController.makeProduct.emptyTank2.newActive,tankController.makeProduct.emptyTank2.newActive,$PRE.tankController.makeProduct.emptyTank2.oldActive,tankController.makeProduct.emptyTank2.oldActive,tankController.makeProduct.wait1.active,$PRE.tankController.makeProduct.wait1.localActive,tankController.makeProduct.wait1.localActive,$PRE.tankController.makeProduct.wait1.newActive,tankController.makeProduct.wait1.newActive,$PRE.tankController.makeProduct.wait1.oldActive,tankController.makeProduct.wait1.oldActive,tankController.makeProduct.T2.enableFire,tankController.makeProduct.T2.fire,$PRE.tankController.makeProduct.T2.t_start,tankController.makeProduct.T2.t_start,tankController.makeProduct.wait2.active,$PRE.tankController.makeProduct.wait2.localActive,tankController.makeProduct.wait2.localActive,$PRE.tankController.makeProduct.wait2.newActive,tankController.makeProduct.wait2.newActive,$PRE.tankController.makeProduct.wait2.oldActive,tankController.makeProduct.wait2.oldActive,tankController.makeProduct.T4.enableFire,tankController.makeProduct.T4.fire,$PRE.tankController.makeProduct.T4.t_start,tankController.makeProduct.T4.t_start,tankController.T1.enableFire,tankController.T2.condition,tankController.T2.enableFire,tankController.T3.enableFire,tankController.s2.active,$PRE.tankController.s2.localActive,tankController.s2.localActive,tankController.s2.outPort[2].available,$PRE.tankController.s2.newActive,tankController.s2.newActive,$PRE.tankController.s2.oldActive,tankController.s2.oldActive,tankController.T4.enableFire,tankController.T5.enableFire,tankController.emptyTanks.active,$PRE.tankController.emptyTanks.localActive,tankController.emptyTanks.localActive,$PRE.tankController.emptyTanks.newActive,tankController.emptyTanks.newActive,$PRE.tankController.emptyTanks.oldActive,tankController.emptyTanks.oldActive,tankController.T6.condition,tankController.T6.enableFire,$PRE.start.reset[1],start.reset[1],$PRE.start.reset[2],start.reset[2],$PRE.start.on,start.on,$PRE.start.table.y,start.table.y,$PRE.start.table.nextTime,start.table.nextTime,$PRE.start.table.index,start.table.index,$PRE.stop.reset[1],stop.reset[1],$PRE.stop.reset[2],stop.reset[2],$PRE.stop.on,stop.on,$PRE.stop.table.y,stop.table.y,$PRE.stop.table.nextTime,stop.table.nextTime,$PRE.stop.table.index,stop.table.index,$PRE.shut.reset[1],shut.reset[1],$PRE.shut.reset[2],shut.reset[2],$PRE.shut.on,shut.on,$PRE.shut.table.y,shut.table.y,$PRE.shut.table.nextTime,shut.table.nextTime,$PRE.shut.table.index,shut.table.index,valve2.valveControl,valve3.valveControl,stateGraphRoot.activeSteps,stateGraphRoot.resume,stateGraphRoot.suspend,tankController.makeProduct.T4.condition,tankController.makeProduct.T2.condition)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (185):
 * Single equations (assignments): 176
 * Array equations: 0
 * Algorithm blocks: 9
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.001107/0.1585, allocations: 0.6116 MB / 339.1 MB, free: 1.039 MB / 282.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002122/0.1587, allocations: 75.14 kB / 339.2 MB, free: 0.9648 MB / 282.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003053/0.1618, allocations: 1.222 MB / 340.4 MB, free: 15.7 MB / 298.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.843e-05/0.1619, allocations: 11.91 kB / 340.4 MB, free: 15.68 MB / 298.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.696e-06/0.1619, allocations: 8 kB / 340.4 MB, free: 15.68 MB / 298.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004986/0.1624, allocations: 251.9 kB / 340.7 MB, free: 15.41 MB / 298.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002503/0.1626, allocations: 43.98 kB / 340.7 MB, free: 15.37 MB / 298.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005388/0.1632, allocations: 51.91 kB / 340.8 MB, free: 15.32 MB / 298.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000453/0.1637, allocations: 144.8 kB / 340.9 MB, free: 15.18 MB / 298.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.003596/0.1673, allocations: 1.641 MB / 342.6 MB, free: 13.42 MB / 298.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.004347/0.1716, allocations: 1.885 MB / 344.4 MB, free: 11.48 MB / 298.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001206/0.1729, allocations: 0.5154 MB / 345 MB, free: 10.96 MB / 298.7 MB
Notification: Performance of sorting global known variables: time 0.0004672/0.1734, allocations: 271.1 kB / 345.2 MB, free: 10.7 MB / 298.7 MB
Notification: Performance of Backend: time 6.61e-07/0.1734, allocations: 0 / 345.2 MB, free: 10.7 MB / 298.7 MB
Notification: Performance of simCode: created initialization part: time 0.002852/0.1762, allocations: 1.35 MB / 346.6 MB, free: 9.234 MB / 298.7 MB
Notification: Performance of SimCode: time 0.003995/0.1803, allocations: 2.416 MB / 349 MB, free: 6.695 MB / 298.7 MB
Notification: Performance of Templates: time 0.03272/0.213, allocations: 10.65 MB / 359.6 MB, free: 12.06 MB / 314.7 MB