Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO.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.001986/0.001986, allocations: 80.83 kB / 21.56 MB, free: 2.922 MB / 18.57 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.002195/0.002195, allocations: 156.3 kB / 24.97 MB, free: 9.148 MB / 18.57 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.589/1.589, allocations: 177.1 MB / 205.4 MB, free: 5.469 MB / 186.7 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo): time 3.21/3.21, allocations: 389.5 MB / 0.6361 GB, free: 12.31 MB / 0.6042 GB
"
[Timeout remaining time 176]
Using package Buildings with version 13.0.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.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.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO")
translateModel(Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.604e-05/2.604e-05, allocations: 5.969 kB / 0.7759 GB, free: 5.352 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO): time 0.01287/0.0129, allocations: 7.485 MB / 0.7832 GB, free: 13.87 MB / 0.7448 GB
Notification: Performance of NFInst.instExpressions: time 0.008682/0.02158, allocations: 3.352 MB / 0.7865 GB, free: 10.52 MB / 0.7448 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00189/0.02347, allocations: 67.62 kB / 0.7866 GB, free: 10.45 MB / 0.7448 GB
Notification: Performance of NFTyping.typeComponents: time 0.001322/0.02479, allocations: 359.8 kB / 0.7869 GB, free: 10.09 MB / 0.7448 GB
Notification: Performance of NFTyping.typeBindings: time 0.00154/0.02633, allocations: 321.3 kB / 0.7872 GB, free: 9.781 MB / 0.7448 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002867/0.0292, allocations: 1.313 MB / 0.7885 GB, free: 8.469 MB / 0.7448 GB
Notification: Performance of NFFlatten.flatten: time 0.005287/0.03449, allocations: 3.654 MB / 0.7921 GB, free: 4.84 MB / 0.7448 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003365/0.03785, allocations: 1.376 MB / 0.7934 GB, free: 3.387 MB / 0.7448 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002836/0.04069, allocations: 1.849 MB / 0.7952 GB, free: 1.535 MB / 0.7448 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001004/0.04169, allocations: 0.7996 MB / 0.796 GB, free: 0.7344 MB / 0.7448 GB
Notification: Performance of NFPackage.collectConstants: time 0.0005361/0.04223, allocations: 420 kB / 0.7964 GB, free: 332 kB / 0.7448 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0006324/0.04286, allocations: 440.5 kB / 0.7968 GB, free: 15.89 MB / 0.7605 GB
Notification: Performance of combineBinaries: time 0.003997/0.04686, allocations: 4.094 MB / 0.8008 GB, free: 11.78 MB / 0.7605 GB
Notification: Performance of replaceArrayConstructors: time 0.00248/0.04934, allocations: 2.328 MB / 0.8031 GB, free: 9.43 MB / 0.7605 GB
Notification: Performance of NFVerifyModel.verify: time 0.0005596/0.0499, allocations: 164 kB / 0.8032 GB, free: 9.27 MB / 0.7605 GB
Notification: Performance of FrontEnd: time 0.0007318/0.05063, allocations: 189.9 kB / 0.8034 GB, free: 9.082 MB / 0.7605 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 642 (642)
 * Number of variables: 642 (642)
Notification: Performance of [SIM] Bindings: time 0.01483/0.06545, allocations: 11.95 MB / 0.8151 GB, free: 12.8 MB / 0.7761 GB
Notification: Performance of [SIM] FunctionAlias: time 0.002635/0.06809, allocations: 1.952 MB / 0.817 GB, free: 10.82 MB / 0.7761 GB
Notification: Performance of [SIM] Early Inline: time 0.01729/0.08538, allocations: 12.29 MB / 0.829 GB, free: 14.41 MB / 0.7917 GB
Notification: Performance of [SIM] Simplify 1: time 0.002045/0.08743, allocations: 0.8516 MB / 0.8298 GB, free: 13.46 MB / 0.7917 GB
Warning: NBAlias.setStartFixed: Multiple variables are fixed and have identical start values. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.02699/0.1144, allocations: 15.79 MB / 0.8453 GB, free: 12.12 MB / 0.8073 GB
Notification: Performance of [SIM] Simplify 2: time 0.0009039/0.1153, allocations: 465.6 kB / 0.8457 GB, free: 11.62 MB / 0.8073 GB
Notification: Performance of [SIM] Remove Stream: time 0.0006561/0.116, allocations: 469.8 kB / 0.8461 GB, free: 11.12 MB / 0.8073 GB
Notification: Performance of [SIM] Detect States: time 0.002424/0.1184, allocations: 1.578 MB / 0.8477 GB, free: 9.473 MB / 0.8073 GB
Notification: Performance of [SIM] Events: time 0.0008091/0.1192, allocations: 0.5704 MB / 0.8482 GB, free: 8.902 MB / 0.8073 GB
Notification: Performance of [SIM] Partitioning: time 0.002686/0.1219, allocations: 1.737 MB / 0.8499 GB, free: 7.062 MB / 0.8073 GB
Notification: Performance of [SIM] Causalize: time 0.003846/0.1257, allocations: 2.324 MB / 0.8522 GB, free: 4.703 MB / 0.8073 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.004702/0.1304, allocations: 3.915 MB / 0.856 GB, free: 0.7852 MB / 0.8073 GB
Notification: Performance of [INI] Simplify: time 0.006235/0.1367, allocations: 3.713 MB / 0.8597 GB, free: 12.99 MB / 0.823 GB
Notification: Performance of [INI] Inline: time 0.00862/0.1453, allocations: 6.913 MB / 0.8664 GB, free: 6.066 MB / 0.823 GB
Notification: Performance of [INI] Partitioning: time 0.000341/0.1456, allocations: 84.02 kB / 0.8665 GB, free: 5.965 MB / 0.823 GB
Notification: Performance of [INI] Cleanup: time 0.002394/0.148, allocations: 1.647 MB / 0.8681 GB, free: 4.305 MB / 0.823 GB
Notification: Performance of [INI] Causalize: time 0.0163/0.1643, allocations: 9.343 MB / 0.8772 GB, free: 10.73 MB / 0.8386 GB
Notification: Performance of [INI] Tearing: time 0.0002375/0.1646, allocations: 247.9 kB / 0.8775 GB, free: 10.49 MB / 0.8386 GB
Notification: Performance of [SIM] Initialization: time 6.202e-06/0.1646, allocations: 8 kB / 0.8775 GB, free: 10.48 MB / 0.8386 GB
Notification: Performance of [SIM] Remove Dummies: time 5.171e-05/0.1646, allocations: 1.547 kB / 0.8775 GB, free: 10.48 MB / 0.8386 GB
Notification: Performance of [SIM] Tearing: time 8.641e-05/0.1647, allocations: 96 kB / 0.8776 GB, free: 10.39 MB / 0.8386 GB
Notification: Performance of [SIM] Categorize: time 3.725e-05/0.1647, allocations: 8 kB / 0.8776 GB, free: 10.38 MB / 0.8386 GB
Notification: Performance of [SIM] Solve: time 0.003559/0.1683, allocations: 1.675 MB / 0.8792 GB, free: 8.68 MB / 0.8386 GB
Notification: Performance of [SIM] Jacobian: time 0.00234/0.1706, allocations: 1.279 MB / 0.8805 GB, free: 7.293 MB / 0.8386 GB
Notification: Performance of [SIM] Minimize Homotopy System: time 6.612e-06/0.1707, allocations: 0 / 0.8805 GB, free: 7.293 MB / 0.8386 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 2
 * Number of algebraic event partitions: ......... 1
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 1
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 6 (6) {PIWitTun.con.addPID.u2, PIWitTun.conProMod.gain.divIyIu.u1, PI.addPID.u2, PI.D.x, derivative1.x, derivative2.x}
 * Number of discrete states: .................... 25 (25) {autTunSig.y, uniDel1.y, uniDel1.u_internal, sub2.u1, uniDel2.u_internal, PIWitTun.sam_u_s.y, PIWitTun.tunStaDel.y, PIWitTun.tunStaDel.t_next, PIWitTun.samTi.y, PIWitTun.samk.y, PIWitTun.swi.u2, PIWitTun.conProMod.gai.u, PIWitTun.conProMod.T, PIWitTun.conProMod.samtOn.y, PIWitTun.conProMod.L, PIWitTun.conProMod.k, PIWitTun.resPro.tunMon.sub.u2, PIWitTun.resPro.norTimDel.tunEndDel.y, PIWitTun.resPro.norTimDel.tunEndDel.t_next, PIWitTun.resPro.halPerRatio.maxtOntOff.u2, PIWitTun.resPro.halPerRatio.maxtOntOff.u1, PIWitTun.resPro.tOff, PIWitTun.conProMod.tOn, PIWitTun.resPro.onOffPer.lenOnCal.u1, PIWitTun.resPro.onOffPer.lenOffCal.u1}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 59 (59) {PIWitTun.rel.yOn, PIWitTun.rel.hys.y, PIWitTun.resPro.onOffPer.timOnRec.samTri.y, PIWitTun.resPro.onOffPer.timOnRec.swi.u2, PIWitTun.resPro.onOffPer.timOnRec.edgThr.u, PIWitTun.resPro.onOffPer.timOnRec.edgThr.y, PIWitTun.resPro.onOffPer.timOffRec.samTri.y, PIWitTun.resPro.onOffPer.timOffRec.swi.u2, PIWitTun.resPro.onOffPer.timOffRec.edgThr.u, PIWitTun.resPro.onOffPer.timOffRec.edgThr.y, PIWitTun.resPro.norTimDel.gre.greHys.y, PIWitTun.resPro.norTimDel.or2.u1, PIWitTun.resPro.norTimDel.or2.y, PIWitTun.resPro.norTimDel.tunEndDel.u, PIWitTun.resPro.tunMon.samAddtOntOff.trigger, PIWitTun.resPro.tunMon.tCha.u1, PIWitTun.resPro.tunMon.tCha.u2, PIWitTun.resPro.tunMon.tCha.y, PIWitTun.resPro.tunMon.edgTunSta.u, PIWitTun.conProMod.triSta, PIWitTun.conProMod.timConDel.gre1.greHys.y, PIWitTun.conProMod.timConDel.edg.u, PIWitTun.conProMod.not1.u, PIWitTun.samk.trigger, PIWitTun.assMes2.u, PIWitTun.nand.u2, PIWitTun.nand.y, PIWitTun.tunStaDel.u, PIWitTun.nand1.u1, PIWitTun.greThr.greHys.y, PIWitTun.falEdg.y, PIWitTun.falEdg.not_u, PIWitTun.or2.u2, PIWitTun.or2.y, PIWitTun.and2.u2, uniDel2.sampleTrigger, uniDel1.sampleTrigger, $WC_4, $WC_3, $WC_2, $WC_1, $WC_0, $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: ........... 168 (scalar:168, array:0, record:0)
 * Number of multi strong components: ............ 25 (algorithm:0, when:25, 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: ........... 237 (scalar:237, array:0, record:0)
 * Number of multi strong components: ............ 5 (algorithm:5, 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: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 237 (scalar:237, array:0, record:0)
 * Number of multi strong components: ............ 5 (algorithm:5, 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.0002118/0.1709, allocations: 166.8 kB / 0.8806 GB, free: 7.129 MB / 0.8386 GB
Notification: Performance of SimCode: time 0.008669/0.1795, allocations: 4.28 MB / 0.8848 GB, free: 2.621 MB / 0.8386 GB
Notification: Performance of Templates: time 0.03317/0.2127, allocations: 15.33 MB / 0.8998 GB, free: 3.488 MB / 0.8542 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_13_Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO.makefile [Timeout 300]
(rm -f Buildings_13_Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO.pipe ; mkfifo Buildings_13_Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO.pipe ; head -c 1048576 < Buildings_13_Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO.pipe >> ../files/Buildings_13_Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO.sim & ./Buildings_13_Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO -abortSlowSimulation -alarm=240  -lv LOG_STATS > Buildings_13_Buildings.Controls.OBC.Utilities.PIDWithAutotuning.Validation.DirectActingPIWithFirstOrderAMIGO.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 8.69013203214854]