Running: ./testmodel.py --libraries=/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelica/../OMCompiler/build/lib/omlibrary/ --ompython_omhome=/usr Modelica_StateGraph2_Modelica_StateGraph2.Examples.Applications.Deadlock.conf.json
Using package ModelicaServices with version 3.2.2 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OMCompiler/build/lib/omlibrary/ModelicaServices 3.2.2/package.mo)
Using package Complex with version 3.2.2 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OMCompiler/build/lib/omlibrary/Complex 3.2.2.mo)
Using package Modelica with version 3.2.2 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OMCompiler/build/lib/omlibrary/Modelica 3.2.2/package.mo)
Using package Modelica_StateGraph2 with version 2.0.3 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OMCompiler/build/lib/omlibrary/Modelica_StateGraph2 2.0.3/package.mo)
Running command: "" <> buildModelFMU(Modelica_StateGraph2.Examples.Applications.Deadlock,fileNamePrefix="Modelica_StateGraph2_Modelica_StateGraph2_Examples_Applications_Deadlock",fmuType="me",version="2.0",platforms={"static"})
[<interactive>:1:1-1:32:writable] Error: Class GC_set_max_heap_size not found in scope <global scope> (looking for a function or record).
Notification: Performance of loadModel(Modelica_StateGraph2): time 2.099/2.099, allocations: 208.4 MB / 215.7 MB, free: 8 kB / 170.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.2073/2.307, allocations: 53.39 MB / 269.1 MB, free: 10.52 MB / 234.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2226/2.529, allocations: 47.77 MB / 316.9 MB, free: 15.14 MB / 266.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 0.1383/2.668, allocations: 71.59 MB / 388.5 MB, free: 7.449 MB / 330.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0001347/2.668, allocations: 51.97 kB / 388.5 MB, free: 7.398 MB / 330.7 MB
[/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OMCompiler/build/lib/omlibrary/Modelica_StateGraph2 2.0.3/package.mo:12150:5-12156:3:writable] Warning: Usage of non-standard operator (not specified in the Modelica specification): Connections.uniqueRoot. Functionality might be partially supported but is not guaranteed.
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 526
 * Number of variables: 526
Notification: Performance of Generate backend data structure: time 0.105/2.773, allocations: 22.94 MB / 411.5 MB, free: 336 kB / 346.7 MB
Notification: Performance of prepare preOptimizeDAE: time 0.000105/2.773, allocations: 11.98 kB / 411.5 MB, free: 324 kB / 346.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00197/2.775, allocations: 217.5 kB / 411.7 MB, free: 104 kB / 346.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1423/2.917, allocations: 0.9077 MB / 412.6 MB, free: 111.8 MB / 346.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001566/2.918, allocations: 73.53 kB / 412.7 MB, free: 111.8 MB / 346.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004489/2.918, allocations: 129.6 kB / 412.8 MB, free: 111.8 MB / 346.7 MB
Notification: Performance of preOpt removeEqualFunctionCalls (simulation): time 0.004543/2.923, allocations: 1.265 MB / 414.1 MB, free: 111.8 MB / 346.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005769/2.928, allocations: 2.05 MB / 416.1 MB, free: 111.1 MB / 346.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 6.699e-05/2.928, allocations: 7.062 kB / 416.1 MB, free: 111.1 MB / 346.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002522/2.929, allocations: 14.86 kB / 416.1 MB, free: 111.1 MB / 346.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.633e-05/2.929, allocations: 50.41 kB / 416.2 MB, free: 111.1 MB / 346.7 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.01301/2.942, allocations: 8.625 MB / 424.8 MB, free: 105.5 MB / 346.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001531/2.943, allocations: 1.239 MB / 426 MB, free: 104.6 MB / 346.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0006112/2.944, allocations: 271.3 kB / 426.3 MB, free: 104.6 MB / 346.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.003224/2.947, allocations: 2.066 MB / 428.4 MB, free: 103.1 MB / 346.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0006736/2.948, allocations: 292.8 kB / 428.7 MB, free: 103.1 MB / 346.7 MB
Notification: Performance of pre-optimization done (n=92): time 4.709e-06/2.948, allocations: 0 / 428.7 MB, free: 103.1 MB / 346.7 MB
Notification: Performance of matching and sorting (n=92): time 0.001606/2.95, allocations: 0.4961 MB / 429.2 MB, free: 103 MB / 346.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.242e-05/2.95, allocations: 180.1 kB / 429.3 MB, free: 102.8 MB / 346.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001423/2.951, allocations: 0.7901 MB / 430.1 MB, free: 102.6 MB / 346.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.000182/2.951, allocations: 46.97 kB / 430.2 MB, free: 102.5 MB / 346.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0007651/2.952, allocations: 0.6443 MB / 430.8 MB, free: 102.2 MB / 346.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003461/2.953, allocations: 199.2 kB / 431 MB, free: 102.1 MB / 346.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 6.85e-05/2.953, allocations: 0 / 431 MB, free: 102.1 MB / 346.7 MB
Notification: Performance of setup shared object (initialization): time 0.0002057/2.953, allocations: 415.3 kB / 431.4 MB, free: 101.8 MB / 346.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007477/2.954, allocations: 267 kB / 431.7 MB, free: 101.8 MB / 346.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001084/2.955, allocations: 0.9578 MB / 432.6 MB, free: 100.9 MB / 346.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001347/2.956, allocations: 413.3 kB / 433 MB, free: 100.8 MB / 346.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.626e-05/2.956, allocations: 6.219 kB / 433 MB, free: 100.8 MB / 346.7 MB
Notification: Performance of matching and sorting (n=216) (initialization): time 0.003451/2.96, allocations: 1.132 MB / 434.2 MB, free: 100.6 MB / 346.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.433e-05/2.96, allocations: 7.469 kB / 434.2 MB, free: 100.6 MB / 346.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.377e-05/2.96, allocations: 24 kB / 434.2 MB, free: 100.6 MB / 346.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 9.492e-05/2.96, allocations: 36.84 kB / 434.2 MB, free: 100.6 MB / 346.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005345/2.96, allocations: 141.1 kB / 434.4 MB, free: 100.6 MB / 346.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.728e-05/2.96, allocations: 48.02 kB / 434.4 MB, free: 100.5 MB / 346.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001223/2.962, allocations: 45.58 kB / 434.5 MB, free: 100.5 MB / 346.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000168/2.962, allocations: 88.48 kB / 434.6 MB, free: 100.5 MB / 346.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 105
 * Number of states: 0 ()
 * Number of discrete variables: 204 ($PRE.request1.enableFire,$PRE.request2.enableFire,$PRE.release2.enableFire,$PRE.release1.enableFire,$PRE.reqest3.enableFire,$PRE.request4.enableFire,$PRE.release4.enableFire,$PRE.release3.enableFire,$PRE.start1.enableFire,$PRE.reserved1.enableFire,$PRE.reserved3.enableFire,$PRE.ready1.enableFire,$PRE.start2.enableFire,$PRE.reserved2.enableFire,$PRE.reserved4.enableFire,$PRE.ready2.enableFire,$PRE.idle2.oldActive,idle2.oldActive,$PRE.busy2.oldActive,busy2.oldActive,$PRE.requesting4.oldActive,requesting4.oldActive,$PRE.requesting2.oldActive,requesting2.oldActive,$PRE.idle1.oldActive,idle1.oldActive,$PRE.busy1.oldActive,busy1.oldActive,$PRE.s4.oldActive,s4.oldActive,$PRE.s3.oldActive,s3.oldActive,$PRE.free2.oldActive,free2.oldActive,$PRE.s2.oldActive,s2.oldActive,$PRE.requesting3.oldActive,requesting3.oldActive,$PRE.requesting1.oldActive,requesting1.oldActive,$PRE.s1.oldActive,s1.oldActive,$PRE.free1.oldActive,free1.oldActive,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,free1.outPort[2].available,free1.active,$PRE.free1.newActive,free1.newActive,free1.inport_fire,free1.outport_fire,$PRE.s1.newActive,s1.newActive,s1.inport_fire,s1.outport_fire,request1.enableFire,request1.conditionPort,$PRE.requesting1.newActive,requesting1.newActive,$PRE.requesting3.newActive,requesting3.newActive,requesting3.inport_fire,$PRE.s2.newActive,s2.newActive,s2.inport_fire,s2.outport_fire,request2.enableFire,request2.conditionPort,release2.enableFire,release1.enableFire,free2.outPort[2].available,free2.active,$PRE.free2.newActive,free2.newActive,free2.inport_fire,free2.outport_fire,$PRE.s3.newActive,s3.newActive,s3.inport_fire,s3.outport_fire,reqest3.enableFire,reqest3.conditionPort,$PRE.s4.newActive,s4.newActive,s4.inport_fire,s4.outport_fire,request4.enableFire,request4.conditionPort,release4.enableFire,release3.enableFire,$PRE.busy1.newActive,busy1.newActive,busy1.inport_fire,start1.fire,start1.enableFire,$PRE.start1.t_start,start1.t_start,reserved1.enableFire,reserved1.conditionPort,reserved3.enableFire,reserved3.conditionPort,ready1.fire,ready1.enableFire,$PRE.ready1.t_start,ready1.t_start,$PRE.idle1.newActive,idle1.newActive,idle1.inport_fire,idle1.outport_fire,$PRE.requesting2.newActive,requesting2.newActive,$PRE.requesting4.newActive,requesting4.newActive,requesting4.inport_fire,$PRE.busy2.newActive,busy2.newActive,busy2.inport_fire,start2.fire,start2.enableFire,$PRE.start2.t_start,start2.t_start,reserved2.enableFire,reserved2.conditionPort,reserved4.enableFire,reserved4.conditionPort,ready2.fire,ready2.enableFire,$PRE.ready2.t_start,ready2.t_start,$PRE.idle2.newActive,idle2.newActive,idle2.inport_fire,idle2.outport_fire,fallingEdge.y,$PRE.fallingEdge.not_u,fallingEdge.not_u,fallingEdge1.y,$PRE.fallingEdge1.not_u,fallingEdge1.not_u,s4.outPort[1].checkOneDelayedTransitionPerLoop,s3.outPort[1].checkOneDelayedTransitionPerLoop,busy2.outPort[1].checkOneDelayedTransitionPerLoop,busy1.outPort[1].checkOneDelayedTransitionPerLoop,s2.outPort[1].checkOneDelayedTransitionPerLoop,s1.outPort[1].checkOneDelayedTransitionPerLoop,free2.node.resume,free2.node.suspend,idle2.node.resume,idle2.node.suspend,idle1.node.resume,idle1.node.suspend,free1.node.resume,free1.node.suspend,ready2.condition,start2.condition,ready1.condition,start1.condition,release4.outPort.checkOneDelayedTransitionPerLoop,release3.outPort.checkOneDelayedTransitionPerLoop,free2.outPort[2].checkOneDelayedTransitionPerLoop,s4.inPort[1].checkUnaryConnection,idle2.inPort[1].checkUnaryConnection,ready2.outPort.checkOneDelayedTransitionPerLoop,idle2.outPort[1].checkOneDelayedTransitionPerLoop,start2.outPort.checkOneDelayedTransitionPerLoop,requesting4.inPort[1].checkOneDelayedTransitionPerLoop,busy2.inPort[1].checkOneDelayedTransitionPerLoop,busy2.inPort[1].checkUnaryConnection,requesting4.inPort[1].checkUnaryConnection,requesting2.inPort[1].checkUnaryConnection,idle1.inPort[1].checkUnaryConnection,ready1.outPort.checkOneDelayedTransitionPerLoop,idle1.outPort[1].checkOneDelayedTransitionPerLoop,start1.outPort.checkOneDelayedTransitionPerLoop,requesting3.inPort[1].checkOneDelayedTransitionPerLoop,busy1.inPort[1].checkOneDelayedTransitionPerLoop,busy1.inPort[1].checkUnaryConnection,requesting3.inPort[1].checkUnaryConnection,requesting1.inPort[1].checkUnaryConnection,free2.inPort[2].checkUnaryConnection,free2.inPort[1].checkUnaryConnection,s3.inPort[1].checkUnaryConnection,free1.inPort[2].checkUnaryConnection,release2.outPort.checkOneDelayedTransitionPerLoop,release1.outPort.checkOneDelayedTransitionPerLoop,free1.outPort[1].checkOneDelayedTransitionPerLoop,free1.inPort[1].checkUnaryConnection,s1.inPort[1].checkUnaryConnection,s2.inPort[1].checkUnaryConnection,reserved4.condition,reserved2.condition,reserved3.condition,reserved1.condition,release3.condition,release4.condition,request4.condition,reqest3.condition,release1.condition,release2.condition,request2.condition,request1.condition)
 * Number of discrete states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (216):
 * Single equations (assignments): 216
 * Array equations: 0
 * Algorithm blocks: 0
 * 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.0003763/2.962, allocations: 269.9 kB / 434.8 MB, free: 100.4 MB / 346.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001906/2.962, allocations: 97.62 kB / 434.9 MB, free: 100.3 MB / 346.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001911/2.964, allocations: 0.5766 MB / 435.5 MB, free: 100.2 MB / 346.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.798e-05/2.964, allocations: 13.55 kB / 435.5 MB, free: 100.2 MB / 346.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.807e-06/2.964, allocations: 0 / 435.5 MB, free: 100.2 MB / 346.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.496e-05/2.964, allocations: 7.688 kB / 435.5 MB, free: 100.2 MB / 346.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004181/2.969, allocations: 1.528 MB / 437 MB, free: 99.52 MB / 346.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.847e-06/2.969, allocations: 1.5 kB / 437 MB, free: 99.52 MB / 346.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0001184/2.969, allocations: 25.98 kB / 437.1 MB, free: 99.51 MB / 346.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 1.414e-05/2.969, allocations: 4.344 kB / 437.1 MB, free: 99.51 MB / 346.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.651e-05/2.969, allocations: 20.77 kB / 437.1 MB, free: 99.5 MB / 346.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.005e-05/2.969, allocations: 7.5 kB / 437.1 MB, free: 99.5 MB / 346.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.417e-06/2.969, allocations: 1.781 kB / 437.1 MB, free: 99.5 MB / 346.7 MB
Notification: Performance of postOpt detectJacobianSparsePattern (simulation): time 0.001598/2.971, allocations: 0.6235 MB / 437.7 MB, free: 99.2 MB / 346.7 MB
Notification: Performance of postOpt generateSymbolicLinearization (simulation): time 0.001675/2.972, allocations: 1.012 MB / 438.7 MB, free: 98.51 MB / 346.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004389/2.973, allocations: 215.5 kB / 439 MB, free: 98.35 MB / 346.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001649/2.973, allocations: 7.219 kB / 439 MB, free: 98.34 MB / 346.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005189/2.973, allocations: 23.47 kB / 439 MB, free: 98.34 MB / 346.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003027/2.974, allocations: 91.36 kB / 439.1 MB, free: 98.33 MB / 346.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.172e-05/2.974, allocations: 27.84 kB / 439.1 MB, free: 98.32 MB / 346.7 MB
Notification: Performance of sort global known variables: time 0.000691/2.975, allocations: 404.4 kB / 439.5 MB, free: 98.13 MB / 346.7 MB
Notification: Performance of remove unused functions: time 0.002543/2.977, allocations: 195 kB / 439.7 MB, free: 98.13 MB / 346.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 0 ()
 * Number of discrete variables: 92 (start2.enableFire,ready2.enableFire,start1.enableFire,ready1.enableFire,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,free1.outPort[2].available,free1.active,free1.newActive,free1.oldActive,free1.inport_fire,free1.outport_fire,s1.newActive,s1.oldActive,s1.inport_fire,s1.outport_fire,request1.enableFire,request1.conditionPort,requesting1.newActive,requesting1.oldActive,requesting3.newActive,requesting3.oldActive,requesting3.inport_fire,s2.newActive,s2.oldActive,s2.inport_fire,s2.outport_fire,request2.enableFire,request2.conditionPort,release2.enableFire,release1.enableFire,free2.outPort[2].available,free2.active,free2.newActive,free2.oldActive,free2.inport_fire,free2.outport_fire,s3.newActive,s3.oldActive,s3.inport_fire,s3.outport_fire,reqest3.enableFire,reqest3.conditionPort,s4.newActive,s4.oldActive,s4.inport_fire,s4.outport_fire,request4.enableFire,request4.conditionPort,release4.enableFire,release3.enableFire,busy1.newActive,busy1.oldActive,busy1.inport_fire,start1.fire,start1.t_start,reserved1.enableFire,reserved1.conditionPort,reserved3.enableFire,reserved3.conditionPort,ready1.fire,ready1.t_start,idle1.newActive,idle1.oldActive,idle1.inport_fire,idle1.outport_fire,requesting2.newActive,requesting2.oldActive,requesting4.newActive,requesting4.oldActive,requesting4.inport_fire,busy2.newActive,busy2.oldActive,busy2.inport_fire,start2.fire,start2.t_start,reserved2.enableFire,reserved2.conditionPort,reserved4.enableFire,reserved4.conditionPort,ready2.fire,ready2.t_start,idle2.newActive,idle2.oldActive,idle2.inport_fire,idle2.outport_fire,fallingEdge.y,fallingEdge.not_u,fallingEdge1.y,fallingEdge1.not_u)
 * Number of discrete states: 16 (free1.newActive,s1.newActive,requesting1.newActive,requesting3.newActive,s2.newActive,free2.newActive,s3.newActive,s4.newActive,busy1.newActive,idle1.newActive,requesting2.newActive,requesting4.newActive,busy2.newActive,idle2.newActive,fallingEdge.not_u,fallingEdge1.not_u)
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (92):
 * Single equations (assignments): 74
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 18
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.0003663/2.978, allocations: 159.5 kB / 439.8 MB, free: 98.02 MB / 346.7 MB
Notification: Performance of simCode: created initialization part: time 0.002103/2.98, allocations: 0.789 MB / 440.6 MB, free: 97.76 MB / 346.7 MB
Notification: Performance of simCode: created event and clocks part: time 4.819e-06/2.98, allocations: 0.5 kB / 440.6 MB, free: 97.76 MB / 346.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.000722/2.98, allocations: 285.7 kB / 440.9 MB, free: 97.66 MB / 346.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0007466/2.981, allocations: 86.91 kB / 441 MB, free: 97.63 MB / 346.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.005556/2.987, allocations: 1.711 MB / 442.7 MB, free: 96.94 MB / 346.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001724/2.989, allocations: 1.428 MB / 444.1 MB, free: 96.17 MB / 346.7 MB
Notification: Performance of SimCode: time 1.563e-06/2.989, allocations: 0 / 444.1 MB, free: 96.17 MB / 346.7 MB