Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr AixLib_AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime.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.0007798/0.0007798, allocations: 80.25 kB / 20.05 MB, free: 4.465 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.0009232/0.0009232, allocations: 173.9 kB / 23.36 MB, free: 1.156 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 0.8072/0.8072, allocations: 177.1 MB / 203.7 MB, free: 5.688 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/package.mo): time 0.03161/0.03161, allocations: 9.122 MB / 269.2 MB, free: 4.422 MB / 250.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/EmbeddedTargets/STM32F4/Functions/HAL.mo:5:3-11:14:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/OperatingSystem/DynamicArray.mo:6:3-94:18:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1041:3-1062:27:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1064:3-1092:25:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF master/package.mo): time 0.0003838/0.0003838, allocations: 19.95 kB / 328.7 MB, free: 20.58 MB / 266.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/EmbeddedTargets/STM32F4/Functions/HAL.mo:5:3-11:14:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/OperatingSystem/DynamicArray.mo:6:3-94:18:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1041:3-1062:27:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1064:3-1092:25:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 3.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 3.0.0/package.mo): time 1.365/1.365, allocations: 275 MB / 0.6476 GB, free: 2.48 MB / 0.4949 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/EmbeddedTargets/STM32F4/Functions/HAL.mo:5:3-11:14:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/OperatingSystem/DynamicArray.mo:6:3-94:18:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1041:3-1062:27:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1064:3-1092:25:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 178]
Using package AixLib with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 3.0.0/package.mo)
Using package SDF with version 0.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF master/package.mo)
Using package Modelica_DeviceDrivers with version 2.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/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(AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="AixLib_AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime")
translateModel(AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="AixLib_AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.09e-05/2.09e-05, allocations: 5.266 kB / 0.9295 GB, free: 8.004 MB / 0.698 GB
Notification: Performance of NFInst.instantiate(AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime): time 0.001423/0.001444, allocations: 1.074 MB / 0.9306 GB, free: 6.949 MB / 0.698 GB
Notification: Performance of NFInst.instExpressions: time 0.001985/0.00343, allocations: 2.23 MB / 0.9328 GB, free: 4.707 MB / 0.698 GB
Notification: Performance of NFInst.updateImplicitVariability: time 6.625e-05/0.003496, allocations: 0 / 0.9328 GB, free: 4.707 MB / 0.698 GB
Notification: Performance of NFTyping.typeComponents: time 4.081e-05/0.003537, allocations: 25.36 kB / 0.9328 GB, free: 4.684 MB / 0.698 GB
Notification: Performance of NFTyping.typeBindings: time 0.0001298/0.003667, allocations: 104 kB / 0.9329 GB, free: 4.582 MB / 0.698 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001541/0.005208, allocations: 1.344 MB / 0.9342 GB, free: 3.242 MB / 0.698 GB
Notification: Performance of NFFlatten.flatten: time 0.0008312/0.006039, allocations: 1.241 MB / 0.9354 GB, free: 2.004 MB / 0.698 GB
Notification: Performance of NFFlatten.resolveConnections: time 6.461e-05/0.006104, allocations: 20.92 kB / 0.9354 GB, free: 1.984 MB / 0.698 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.000495/0.006599, allocations: 0.6516 MB / 0.9361 GB, free: 1.332 MB / 0.698 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0003103/0.006909, allocations: 366 kB / 0.9364 GB, free: 0.9766 MB / 0.698 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001081/0.007017, allocations: 104 kB / 0.9365 GB, free: 0.875 MB / 0.698 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001535/0.007171, allocations: 116 kB / 0.9366 GB, free: 0.7617 MB / 0.698 GB
Notification: Performance of combineBinaries: time 0.0005285/0.007699, allocations: 1.022 MB / 0.9376 GB, free: 15.73 MB / 0.7137 GB
Notification: Performance of replaceArrayConstructors: time 0.0003831/0.008082, allocations: 284.2 kB / 0.9379 GB, free: 15.45 MB / 0.7137 GB
Notification: Performance of NFVerifyModel.verify: time 0.0001667/0.008249, allocations: 78.03 kB / 0.938 GB, free: 15.38 MB / 0.7137 GB
Notification: Performance of FrontEnd: time 2.819e-05/0.008277, allocations: 8 kB / 0.938 GB, free: 15.37 MB / 0.7137 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 15 (15)
 * Number of variables: 15 (15)
Notification: Performance of [SIM] Bindings: time 0.001783/0.01006, allocations: 2.811 MB / 0.9407 GB, free: 12.43 MB / 0.7137 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0001289/0.01019, allocations: 159.8 kB / 0.9409 GB, free: 12.27 MB / 0.7137 GB
Notification: Performance of [SIM] Early Inline: time 0.001222/0.01141, allocations: 2.143 MB / 0.943 GB, free: 10.09 MB / 0.7137 GB
Notification: Performance of [SIM] Simplify 1: time 0.0006331/0.01204, allocations: 0.8429 MB / 0.9438 GB, free: 9.223 MB / 0.7137 GB
Notification: Performance of [SIM] Alias: time 0.000572/0.01262, allocations: 0.8902 MB / 0.9446 GB, free: 8.281 MB / 0.7137 GB
Notification: Performance of [SIM] Simplify 2: time 0.0008116/0.01343, allocations: 0.8469 MB / 0.9455 GB, free: 7.406 MB / 0.7137 GB
Notification: Performance of [SIM] Remove Stream: time 0.0004702/0.0139, allocations: 0.8196 MB / 0.9463 GB, free: 6.559 MB / 0.7137 GB
Notification: Performance of [SIM] Detect States: time 0.0008423/0.01474, allocations: 1.269 MB / 0.9475 GB, free: 5.227 MB / 0.7137 GB
Notification: Performance of [SIM] Events: time 0.0002537/0.01499, allocations: 295.7 kB / 0.9478 GB, free: 4.93 MB / 0.7137 GB
Notification: Performance of [SIM] Partitioning: time 0.0003794/0.01537, allocations: 0.5588 MB / 0.9483 GB, free: 4.227 MB / 0.7137 GB
Notification: Performance of [SIM] Causalize: time 0.0006655/0.01604, allocations: 0.7312 MB / 0.9491 GB, free: 3.383 MB / 0.7137 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.001364/0.0174, allocations: 2.4 MB / 0.9514 GB, free: 0.9805 MB / 0.7137 GB
Notification: Performance of [INI] Simplify: time 0.00148/0.01888, allocations: 1.535 MB / 0.9529 GB, free: 15.39 MB / 0.7293 GB
Notification: Performance of [INI] Inline: time 0.001264/0.02015, allocations: 2.464 MB / 0.9553 GB, free: 12.93 MB / 0.7293 GB
Notification: Performance of [INI] Partitioning: time 3.165e-05/0.02018, allocations: 33.36 kB / 0.9553 GB, free: 12.88 MB / 0.7293 GB
Notification: Performance of [INI] Cleanup: time 0.0005316/0.02071, allocations: 0.8194 MB / 0.9561 GB, free: 12.06 MB / 0.7293 GB
Notification: Performance of [INI] Causalize: time 0.0009914/0.0217, allocations: 1.215 MB / 0.9573 GB, free: 10.85 MB / 0.7293 GB
Notification: Performance of [INI] Tearing: time 2.078e-05/0.02172, allocations: 32 kB / 0.9574 GB, free: 10.82 MB / 0.7293 GB
Notification: Performance of [SIM] Initialization: time 8.82e-07/0.02172, allocations: 0 / 0.9574 GB, free: 10.82 MB / 0.7293 GB
Notification: Performance of [SIM] DAE-Mode: time 9.487e-05/0.02182, allocations: 185.7 kB / 0.9575 GB, free: 10.52 MB / 0.7293 GB
Notification: Performance of [SIM] Remove Dummies: time 7.384e-06/0.02183, allocations: 3.938 kB / 0.9575 GB, free: 10.52 MB / 0.7293 GB
Notification: Performance of [SIM] Tearing: time 1.209e-05/0.02184, allocations: 28 kB / 0.9576 GB, free: 10.49 MB / 0.7293 GB
Notification: Performance of [SIM] Categorize: time 2.241e-05/0.02186, allocations: 7.938 kB / 0.9576 GB, free: 10.48 MB / 0.7293 GB
Notification: Performance of [SIM] Solve: time 0.0002394/0.0221, allocations: 234.2 kB / 0.9578 GB, free: 10.26 MB / 0.7293 GB
Notification: Performance of [SIM] Jacobian: time 8.987e-06/0.02211, allocations: 4 kB / 0.9578 GB, free: 10.25 MB / 0.7293 GB
Notification: Performance of [SIM] Minimize Homotopy System: time 4.9e-07/0.02211, allocations: 0 / 0.9578 GB, free: 10.25 MB / 0.7293 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: ......... 15
 * 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: .................... 15 (15) {booPul_c5.y, booPul_b5.y, booPul_a5.y, booPul_c4.y, booPul_b4.y, booPul_a4.y, booPul_c3.y, booPul_b3.y, booPul_a3.y, booPul_c2.y, booPul_b2.y, booPul_a2.y, booPul_c1.y, booPul_b1.y, booPul_a1.y}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 30 (30) {$TEV_29, $TEV_28, $TEV_27, $TEV_26, $TEV_25, $TEV_24, $TEV_23, $TEV_22, $TEV_21, $TEV_20, $TEV_19, $TEV_18, $TEV_17, $TEV_16, $TEV_15, $TEV_14, $TEV_13, $TEV_12, $TEV_11, $TEV_10, $TEV_9, $TEV_8, $TEV_7, $TEV_6, $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: ........... 30 (scalar:30, array:0, record:0)
 * Number of multi strong components: ............ 15 (algorithm:0, when:15, 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: ........... 45 (scalar:45, array:0, record:0)
 * Number of multi strong components: ............ 15 (algorithm:15, 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.0001093/0.02222, allocations: 52 kB / 0.9578 GB, free: 10.21 MB / 0.7293 GB
Notification: Performance of SimCode: time 0.001425/0.02364, allocations: 1.336 MB / 0.9592 GB, free: 8.852 MB / 0.7293 GB
Notification: Performance of Templates: time 0.009408/0.03305, allocations: 10.12 MB / 0.969 GB, free: 14.96 MB / 0.7449 GB
"
[Timeout remaining time 660]
make -j1 -f AixLib_AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime.makefile [Timeout 660]
(rm -f AixLib_AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime.pipe ; mkfifo AixLib_AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime.pipe ; head -c 1048576 < AixLib_AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime.pipe >> ../files/AixLib_AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime.sim & ./AixLib_AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime  -abortSlowSimulation -alarm=480 -lv LOG_STATS > AixLib_AixLib.Controls.OBC.CDL.Logical.Sources.Validation.PulseNegativeStartTime.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 4.559069834649563]
Failed to read output from testmodel.py, exit status != 0:
0.03794344887137413 0.045340489000000005 0.035608172
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7277895c1490>