Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer.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.001734/0.001734, allocations: 96.42 kB / 19.71 MB, free: 2.125 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.002513/0.002513, allocations: 213.1 kB / 23.01 MB, free: 4.93 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.649/1.649, allocations: 230.6 MB / 256.8 MB, free: 7.789 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 3.433/3.433, allocations: 0.49 GB / 0.7958 GB, free: 35.38 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/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.Utilities.IO.Files.Examples.Printer,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|pri1.x.1.|pri1.x.2.",fileNamePrefix="Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer")
translateModel(Buildings.Utilities.IO.Files.Examples.Printer,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|pri1.x.1.|pri1.x.2.",fileNamePrefix="Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.853e-05/2.853e-05, allocations: 4.25 kB / 1.155 GB, free: 25.27 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.Utilities.IO.Files.Examples.Printer): time 0.003108/0.003136, allocations: 1.592 MB / 1.157 GB, free: 24.2 MB / 0.9044 GB
Notification: Performance of NFInst.instExpressions: time 0.001652/0.004788, allocations: 0.724 MB / 1.157 GB, free: 23.74 MB / 0.9044 GB
Notification: Performance of NFInst.updateImplicitVariability: time 5.928e-05/0.004848, allocations: 0.9375 kB / 1.157 GB, free: 23.74 MB / 0.9044 GB
Notification: Performance of NFTyping.typeComponents: time 0.0001394/0.004987, allocations: 16.62 kB / 1.157 GB, free: 23.73 MB / 0.9044 GB
Notification: Performance of NFTyping.typeBindings: time 5.713e-05/0.005044, allocations: 16.38 kB / 1.157 GB, free: 23.71 MB / 0.9044 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/Utilities/IO/Files/BaseClasses/printRealArray.mo:2:1-45:19:writable] Warning: Pure function 'Buildings.Utilities.IO.Files.BaseClasses.printRealArray' contains a call to impure function 'Modelica.Utilities.Streams.print'.
Notification: Performance of NFTyping.typeClassSections: time 0.0005478/0.005592, allocations: 121.3 kB / 1.157 GB, free: 23.65 MB / 0.9044 GB
Notification: Performance of NFFlatten.flatten: time 0.0002268/0.005819, allocations: 166.3 kB / 1.158 GB, free: 23.57 MB / 0.9044 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0001708/0.00599, allocations: 20.58 kB / 1.158 GB, free: 23.57 MB / 0.9044 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.000104/0.006094, allocations: 69.23 kB / 1.158 GB, free: 23.54 MB / 0.9044 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0001187/0.006212, allocations: 68.09 kB / 1.158 GB, free: 23.5 MB / 0.9044 GB
Notification: Performance of NFPackage.collectConstants: time 2.906e-05/0.006241, allocations: 10.03 kB / 1.158 GB, free: 23.5 MB / 0.9044 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001177/0.006359, allocations: 57.5 kB / 1.158 GB, free: 23.48 MB / 0.9044 GB
Notification: Performance of combineBinaries: time 0.0001812/0.00654, allocations: 234.6 kB / 1.158 GB, free: 23.33 MB / 0.9044 GB
Notification: Performance of replaceArrayConstructors: time 7.969e-05/0.00662, allocations: 143.8 kB / 1.158 GB, free: 23.23 MB / 0.9044 GB
Notification: Performance of NFVerifyModel.verify: time 3.891e-05/0.006659, allocations: 8.25 kB / 1.158 GB, free: 23.23 MB / 0.9044 GB
Notification: Performance of FrontEnd: time 1.947e-05/0.006678, allocations: 10.47 kB / 1.158 GB, free: 23.23 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: 14 (14)
 * Number of variables: 14 (11)
Notification: Performance of [SIM] Bindings: time 0.0006714/0.00735, allocations: 0.5881 MB / 1.159 GB, free: 22.72 MB / 0.9044 GB
Notification: Performance of [SIM] FunctionAlias: time 7.304e-05/0.007423, allocations: 60.55 kB / 1.159 GB, free: 22.68 MB / 0.9044 GB
Notification: Performance of [SIM] Early Inline: time 0.0002489/0.007672, allocations: 259.6 kB / 1.159 GB, free: 22.51 MB / 0.9044 GB
Notification: Performance of [SIM] Simplify 1: time 0.000134/0.007806, allocations: 108.7 kB / 1.159 GB, free: 22.39 MB / 0.9044 GB
Notification: Performance of [SIM] Alias: time 0.0001708/0.007976, allocations: 167.1 kB / 1.159 GB, free: 22.24 MB / 0.9044 GB
Notification: Performance of [SIM] Simplify 2: time 0.0001047/0.008081, allocations: 116.1 kB / 1.159 GB, free: 22.12 MB / 0.9044 GB
Notification: Performance of [SIM] Remove Stream: time 6.263e-05/0.008144, allocations: 78.09 kB / 1.159 GB, free: 22.04 MB / 0.9044 GB
Notification: Performance of [SIM] Detect States: time 0.0002224/0.008366, allocations: 155.3 kB / 1.16 GB, free: 21.89 MB / 0.9044 GB
Notification: Performance of [SIM] Events: time 0.0001493/0.008515, allocations: 75.38 kB / 1.16 GB, free: 21.84 MB / 0.9044 GB
Notification: Performance of [SIM] Partitioning: time 0.0002208/0.008736, allocations: 162.5 kB / 1.16 GB, free: 21.74 MB / 0.9044 GB
Notification: Performance of [SIM] Causalize: time 0.000593/0.009329, allocations: 271 kB / 1.16 GB, free: 21.63 MB / 0.9044 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.00027/0.009599, allocations: 275.8 kB / 1.16 GB, free: 21.49 MB / 0.9044 GB
Notification: Performance of [INI] Simplify: time 0.000344/0.009943, allocations: 244.5 kB / 1.161 GB, free: 21.28 MB / 0.9044 GB
Notification: Performance of [INI] Inline: time 0.0003435/0.01029, allocations: 344.4 kB / 1.161 GB, free: 21.1 MB / 0.9044 GB
Notification: Performance of [INI] Partitioning: time 2.514e-05/0.01031, allocations: 14.41 kB / 1.161 GB, free: 21.09 MB / 0.9044 GB
Notification: Performance of [INI] Cleanup: time 7.156e-05/0.01038, allocations: 71.39 kB / 1.161 GB, free: 21.04 MB / 0.9044 GB
Notification: Performance of [INI] Causalize: time 0.0005429/0.01093, allocations: 329.8 kB / 1.161 GB, free: 20.95 MB / 0.9044 GB
Notification: Performance of [INI] Tearing: time 1.761e-05/0.01094, allocations: 7.688 kB / 1.161 GB, free: 20.94 MB / 0.9044 GB
Notification: Performance of [SIM] Initialization: time 1.773e-06/0.01095, allocations: 1.094 kB / 1.161 GB, free: 20.94 MB / 0.9044 GB
Notification: Performance of [SIM] Remove Dummies: time 5.74e-06/0.01095, allocations: 4 kB / 1.161 GB, free: 20.94 MB / 0.9044 GB
Notification: Performance of [SIM] Tearing: time 9.758e-06/0.01096, allocations: 15.97 kB / 1.161 GB, free: 20.93 MB / 0.9044 GB
Notification: Performance of [SIM] Categorize: time 1.3e-05/0.01097, allocations: 5.031 kB / 1.161 GB, free: 20.92 MB / 0.9044 GB
Notification: Performance of [SIM] Solve: time 0.0002175/0.01119, allocations: 116.9 kB / 1.161 GB, free: 20.88 MB / 0.9044 GB
Notification: Performance of [SIM] Jacobian: time 8.837e-06/0.0112, allocations: 1.344 kB / 1.161 GB, free: 20.88 MB / 0.9044 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: ......... 4
 * 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: .................... 3 (3) {pri3.firstTrigger, pri2.firstTrigger, pri1.firstTrigger}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 9 (9) {pri1.sampleTrigger, pri2.sampleTrigger, pri3.sampleTrigger, $TEV_5, $TEV_4, $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: ........... 17 (scalar:17, array:0, record:0)
 * Number of multi strong components: ............ 3 (algorithm:0, when:3, 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: ........... 23 (scalar:23, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, 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 5.005e-05/0.01125, allocations: 31.75 kB / 1.162 GB, free: 20.85 MB / 0.9044 GB
Notification: Performance of SimCode: time 0.001122/0.01237, allocations: 0.5302 MB / 1.162 GB, free: 20.55 MB / 0.9044 GB
Notification: Performance of Templates: time 0.005246/0.01762, allocations: 1.297 MB / 1.163 GB, free: 20.39 MB / 0.9044 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer.pipe ; mkfifo Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer.pipe ; head -c 1048576 < Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer.pipe >> ../files/Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer.sim & ./Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer.pipe 2>&1) [Timeout 400]
diffSimulationResults("Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_12.x/Buildings_Utilities_IO_Files_Examples_Printer.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_12_Buildings.Utilities.IO.Files.Examples.Printer.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
Reference file matches
[Calling sys.exit(0), Time elapsed: 9.324711232911795]