Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay.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.001574/0.001574, allocations: 104.3 kB / 19.76 MB, free: 1.906 MB / 14.72 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.001716/0.001716, allocations: 212.4 kB / 23.06 MB, free: 4.98 MB / 14.72 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.419/1.419, allocations: 230.6 MB / 256.9 MB, free: 7.746 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 3.138/3.138, allocations: 0.5108 GB / 0.8167 GB, free: 17.73 MB / 0.6231 GB " [Timeout remaining time 177] Using package Buildings with version 13.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/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(Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|onDelay0.u|onDelay0.y|onDelay1.y|onDelay2.y|onDelay3.y|onDelay4.y|onDelay5.y|onDelay6.y|onDelay7.y|onDelay00.u|onDelay00.y|onDelay11.y|onDelay22.y|onDelay33.y|onDelay44.y|onDelay55.y|onDelay66.y|onDelay77.y",fileNamePrefix="Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay") translateModel(Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|onDelay0.u|onDelay0.y|onDelay1.y|onDelay2.y|onDelay3.y|onDelay4.y|onDelay5.y|onDelay6.y|onDelay7.y|onDelay00.u|onDelay00.y|onDelay11.y|onDelay22.y|onDelay33.y|onDelay44.y|onDelay55.y|onDelay66.y|onDelay77.y",fileNamePrefix="Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay") [Timeout 300] "Notification: Performance of FrontEnd - Absyn->SCode: time 2.269e-05/2.269e-05, allocations: 2.281 kB / 1.187 GB, free: 13.45 MB / 0.9044 GB Notification: Performance of NFInst.instantiate(Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay): time 0.002246/0.002269, allocations: 1.106 MB / 1.188 GB, free: 12.76 MB / 0.9044 GB Notification: Performance of NFInst.instExpressions: time 0.002062/0.004331, allocations: 1.641 MB / 1.19 GB, free: 11.58 MB / 0.9044 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.0002847/0.004615, allocations: 7.094 kB / 1.19 GB, free: 11.58 MB / 0.9044 GB Notification: Performance of NFTyping.typeComponents: time 9.053e-05/0.004706, allocations: 29.69 kB / 1.19 GB, free: 11.55 MB / 0.9044 GB Notification: Performance of NFTyping.typeBindings: time 0.0002105/0.004916, allocations: 63.78 kB / 1.19 GB, free: 11.5 MB / 0.9044 GB Notification: Performance of NFTyping.typeClassSections: time 0.001177/0.006093, allocations: 437.1 kB / 1.19 GB, free: 11.29 MB / 0.9044 GB Notification: Performance of NFFlatten.flatten: time 0.0008642/0.006957, allocations: 0.7853 MB / 1.191 GB, free: 10.94 MB / 0.9044 GB Notification: Performance of NFFlatten.resolveConnections: time 0.0001799/0.007137, allocations: 59.41 kB / 1.191 GB, free: 10.9 MB / 0.9044 GB Notification: Performance of NFEvalConstants.evaluate: time 0.0003446/0.007482, allocations: 320.8 kB / 1.191 GB, free: 10.77 MB / 0.9044 GB Notification: Performance of NFSimplifyModel.simplify: time 0.0003309/0.007813, allocations: 285.2 kB / 1.192 GB, free: 10.67 MB / 0.9044 GB Notification: Performance of NFPackage.collectConstants: time 0.000116/0.007929, allocations: 73.66 kB / 1.192 GB, free: 10.67 MB / 0.9044 GB Notification: Performance of NFFlatten.collectFunctions: time 0.0001566/0.008085, allocations: 82.77 kB / 1.192 GB, free: 10.67 MB / 0.9044 GB Notification: Performance of combineBinaries: time 0.0006147/0.0087, allocations: 0.9291 MB / 1.193 GB, free: 10.08 MB / 0.9044 GB Notification: Performance of replaceArrayConstructors: time 0.000176/0.008876, allocations: 408.2 kB / 1.193 GB, free: 9.805 MB / 0.9044 GB Notification: Performance of NFVerifyModel.verify: time 0.0001137/0.00899, allocations: 51.44 kB / 1.193 GB, free: 9.805 MB / 0.9044 GB Notification: Performance of FrontEnd: time 3.754e-05/0.009027, allocations: 15.5 kB / 1.193 GB, free: 9.797 MB / 0.9044 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 54 (54) * Number of variables: 54 (54) Notification: Performance of [SIM] Bindings: time 0.002094/0.01112, allocations: 2.433 MB / 1.196 GB, free: 8.344 MB / 0.9044 GB Notification: Performance of [SIM] FunctionAlias: time 0.0003117/0.01143, allocations: 289.1 kB / 1.196 GB, free: 8.199 MB / 0.9044 GB Notification: Performance of [SIM] Early Inline: time 0.003525/0.01496, allocations: 2.751 MB / 1.199 GB, free: 6.918 MB / 0.9044 GB Notification: Performance of [SIM] Simplify 1: time 0.0008096/0.01577, allocations: 473.5 kB / 1.199 GB, free: 6.738 MB / 0.9044 GB Notification: Performance of [SIM] Alias: time 0.002987/0.01875, allocations: 1.921 MB / 1.201 GB, free: 5.773 MB / 0.9044 GB Notification: Performance of [SIM] Simplify 2: time 0.0007335/0.01949, allocations: 479 kB / 1.201 GB, free: 5.59 MB / 0.9044 GB Notification: Performance of [SIM] Remove Stream: time 0.0005575/0.02005, allocations: 435.5 kB / 1.202 GB, free: 5.379 MB / 0.9044 GB Notification: Performance of [SIM] Detect States: time 0.001465/0.02151, allocations: 1.088 MB / 1.203 GB, free: 4.781 MB / 0.9044 GB Notification: Performance of [SIM] Events: time 0.00048/0.02199, allocations: 329.1 kB / 1.203 GB, free: 4.594 MB / 0.9044 GB Notification: Performance of [SIM] Partitioning: time 0.001123/0.02311, allocations: 0.9938 MB / 1.204 GB, free: 4.094 MB / 0.9044 GB Notification: Performance of [SIM] Causalize: time 0.001472/0.02459, allocations: 1.238 MB / 1.205 GB, free: 3.668 MB / 0.9044 GB Notification: Performance of [SIM] After Index Reduction Inline: time 0.001744/0.02633, allocations: 2.392 MB / 1.208 GB, free: 1.914 MB / 0.9044 GB Notification: Performance of [INI] Simplify: time 0.001343/0.02767, allocations: 1.105 MB / 1.209 GB, free: 1.113 MB / 0.9044 GB Notification: Performance of [INI] Inline: time 0.002634/0.03031, allocations: 3.342 MB / 1.212 GB, free: 14.59 MB / 0.92 GB Notification: Performance of [INI] Partitioning: time 0.0001236/0.03043, allocations: 57.45 kB / 1.212 GB, free: 14.55 MB / 0.92 GB Notification: Performance of [INI] Cleanup: time 0.00062/0.03105, allocations: 0.6375 MB / 1.213 GB, free: 14.03 MB / 0.92 GB Notification: Performance of [INI] Causalize: time 0.00402/0.03507, allocations: 2.977 MB / 1.216 GB, free: 12.9 MB / 0.92 GB Notification: Performance of [INI] Tearing: time 6.047e-05/0.03513, allocations: 80.66 kB / 1.216 GB, free: 12.84 MB / 0.92 GB Notification: Performance of [SIM] Initialization: time 1.543e-06/0.03513, allocations: 0 / 1.216 GB, free: 12.84 MB / 0.92 GB Notification: Performance of [SIM] DAE-Mode: time 8.3e-05/0.03521, allocations: 54.44 kB / 1.216 GB, free: 12.79 MB / 0.92 GB Notification: Performance of [SIM] Remove Dummies: time 1.047e-05/0.03523, allocations: 8.688 kB / 1.216 GB, free: 12.79 MB / 0.92 GB Notification: Performance of [SIM] Tearing: time 2.796e-05/0.03525, allocations: 44.22 kB / 1.216 GB, free: 12.75 MB / 0.92 GB Notification: Performance of [SIM] Categorize: time 1.882e-05/0.03527, allocations: 3.344 kB / 1.216 GB, free: 12.75 MB / 0.92 GB Notification: Performance of [SIM] Solve: time 0.0007396/0.03601, allocations: 472.7 kB / 1.216 GB, free: 12.49 MB / 0.92 GB Notification: Performance of [SIM] Jacobian: time 1.137e-05/0.03602, allocations: 5.062 kB / 1.216 GB, free: 12.48 MB / 0.92 GB Notification: Partition statistics after passing the back-end: * Number of ODE partitions: ..................... 0 * Number of algebraic partitions: ............... 0 * Number of ODE event partitions: ............... 0 * Number of algebraic event partitions: ......... 2 * Number of clocked partitions: ................. 0 * Number of initial partitions: ................. 1 * Number of initial(lambda=0) partitions: ....... 0 Notification: Variable statistics after passing the back-end: * Number of states: ............................. 0 (0) {} * Number of discrete states: .................... 34 (34) {booPul1.y, booPul.y, onDelay77.y, onDelay77.t_next, onDelay33.y, onDelay33.t_next, onDelay66.y, onDelay66.t_next, onDelay55.y, onDelay55.t_next, onDelay44.y, onDelay44.t_next, onDelay22.y, onDelay22.t_next, onDelay11.y, onDelay11.t_next, onDelay00.y, onDelay00.t_next, onDelay7.y, onDelay7.t_next, onDelay3.y, onDelay3.t_next, onDelay6.y, onDelay6.t_next, onDelay5.y, onDelay5.t_next, onDelay4.y, onDelay4.t_next, onDelay2.y, onDelay2.t_next, onDelay1.y, onDelay1.t_next, onDelay0.y, onDelay0.t_next} * Number of clocked states: ..................... 0 (0) {} * Number of discrete variables: ................. 56 (56) {onDelay0.u, onDelay1.u, onDelay2.u, onDelay4.u, onDelay5.u, onDelay6.u, not1.u, onDelay3.u, onDelay7.u, onDelay00.u, onDelay11.u, onDelay22.u, onDelay44.u, onDelay55.u, onDelay66.u, not2.u, onDelay33.u, onDelay77.u, $SEV_33, $SEV_32, $SEV_31, $SEV_30, $SEV_29, $SEV_28, $SEV_27, $SEV_26, $SEV_25, $SEV_24, $SEV_23, $SEV_22, $SEV_21, $SEV_20, $SEV_19, $SEV_18, $SEV_17, $SEV_16, $SEV_15, $SEV_14, $SEV_13, $SEV_12, $SEV_11, $SEV_10, $SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $TEV_3, $TEV_2, $TEV_1, $TEV_0} * Number of clocks: ............................. 0 (0) {} * Number of top-level inputs: ................... 0 (0) {} Notification: [Simulation] Strong Component statistics after passing the back-end: * Number of single strong components: ........... 56 (scalar:56, array:0, record:0) * Number of multi strong components: ............ 34 (algorithm:0, when:34, if:0, tuple:0) * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0) * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0) Notification: [Initialization] Strong Component statistics after passing the back-end: * Number of single strong components: ........... 122 (scalar:122, array:0, record:0) * Number of multi strong components: ............ 34 (algorithm:34, when:0, if:0, tuple:0) * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0) * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0) Notification: Performance of backend: time 0.0001563/0.03618, allocations: 82.44 kB / 1.216 GB, free: 12.42 MB / 0.92 GB Notification: Performance of SimCode: time 0.001629/0.03781, allocations: 1.19 MB / 1.217 GB, free: 11.5 MB / 0.92 GB Notification: Performance of Templates: time 0.01125/0.04906, allocations: 5.62 MB / 1.223 GB, free: 8.668 MB / 0.92 GB " [Timeout remaining time 300] make -j1 -f Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay.makefile [Timeout 300] (rm -f Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay.pipe ; mkfifo Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay.pipe ; head -c 1048576 < Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay.pipe >> ../files/Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay.sim & ./Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay -abortSlowSimulation -alarm=800 -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay.pipe 2>&1) [Timeout 800] diffSimulationResults("Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay_res.mat","/mnt/ReferenceFiles/Buildings/csv/master/Buildings_Controls_OBC_CDL_Logical_Validation_TrueDelay.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_latest_Buildings.Controls.OBC.CDL.Logical.Validation.TrueDelay.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300] "" [Timeout remaining time 300] "" Variables in the reference:time,onDelay0.u,onDelay0.y,onDelay1.y,onDelay2.y,onDelay3.y,onDelay4.y,onDelay5.y,onDelay6.y,onDelay7.y,onDelay00.u,onDelay00.y,onDelay11.y,onDelay22.y,onDelay33.y,onDelay44.y,onDelay55.y,onDelay66.y,onDelay77.y Variables in the result:booPul.period,booPul.shift,booPul.t0,booPul.t1,booPul.width,booPul1.period,booPul1.shift,booPul1.t0,booPul1.t1,booPul1.width,onDelay0.delayOnInit,onDelay0.delayTime,onDelay0.t_past,onDelay0.u,onDelay0.y,onDelay00.delayOnInit,onDelay00.delayTime,onDelay00.t_past,onDelay00.u,onDelay00.y,onDelay1.delayOnInit,onDelay1.delayTime,onDelay1.t_past,onDelay1.y,onDelay11.delayOnInit,onDelay11.delayTime,onDelay11.t_past,onDelay11.y,onDelay2.delayOnInit,onDelay2.delayTime,onDelay2.t_past,onDelay2.y,onDelay22.delayOnInit,onDelay22.delayTime,onDelay22.t_past,onDelay22.y,onDelay3.delayOnInit,onDelay3.delayTime,onDelay3.t_past,onDelay3.y,onDelay33.delayOnInit,onDelay33.delayTime,onDelay33.t_past,onDelay33.y,onDelay4.delayOnInit,onDelay4.delayTime,onDelay4.t_past,onDelay4.y,onDelay44.delayOnInit,onDelay44.delayTime,onDelay44.t_past,onDelay44.y,onDelay5.delayOnInit,onDelay5.delayTime,onDelay5.t_past,onDelay5.y,onDelay55.delayOnInit,onDelay55.delayTime,onDelay55.t_past,onDelay55.y,onDelay6.delayOnInit,onDelay6.delayTime,onDelay6.t_past,onDelay6.y,onDelay66.delayOnInit,onDelay66.delayTime,onDelay66.t_past,onDelay66.y,onDelay7.delayOnInit,onDelay7.delayTime,onDelay7.t_past,onDelay7.y,onDelay77.delayOnInit,onDelay77.delayTime,onDelay77.t_past,onDelay77.y,time [Calling sys.exit(0), Time elapsed: 8.284458822046872]