Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.0.0_Modelica.Electrical.Analog.Examples.NandGate.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.00186/0.00186, allocations: 96.03 kB / 21.25 MB, free: 428 kB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.002343/0.002343, allocations: 192.1 kB / 24.52 MB, free: 3.758 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.642/1.642, allocations: 225.7 MB / 253.4 MB, free: 12.78 MB / 206.1 MB
"
[Timeout remaining time 178]
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(Modelica.Electrical.Analog.Examples.NandGate,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5059,variableFilter="time|Nand.C4.v|Nand.C7.v",fileNamePrefix="Modelica_4.0.0_Modelica.Electrical.Analog.Examples.NandGate")
translateModel(Modelica.Electrical.Analog.Examples.NandGate,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5059,variableFilter="time|Nand.C4.v|Nand.C7.v",fileNamePrefix="Modelica_4.0.0_Modelica.Electrical.Analog.Examples.NandGate") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.26e-05/2.26e-05, allocations: 2.281 kB / 371.7 MB, free: 9.18 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.Analog.Examples.NandGate): time 0.005786/0.005809, allocations: 3.794 MB / 375.5 MB, free: 5.359 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.002317/0.008126, allocations: 1.148 MB / 376.7 MB, free: 4.207 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0002197/0.008346, allocations: 11.94 kB / 376.7 MB, free: 4.195 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0002698/0.008616, allocations: 106.8 kB / 376.8 MB, free: 4.09 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0003694/0.008985, allocations: 190.3 kB / 377 MB, free: 3.902 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0006628/0.009648, allocations: 335.6 kB / 377.3 MB, free: 3.574 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001176/0.01082, allocations: 1.218 MB / 378.5 MB, free: 2.352 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003394/0.01116, allocations: 171.4 kB / 378.7 MB, free: 2.18 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0004392/0.0116, allocations: 414.8 kB / 379.1 MB, free: 1.773 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004677/0.01207, allocations: 398.3 kB / 379.5 MB, free: 1.383 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001609/0.01223, allocations: 116 kB / 379.6 MB, free: 1.27 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001697/0.0124, allocations: 120 kB / 379.7 MB, free: 1.152 MB / 302.1 MB
Notification: Performance of combineBinaries: time 0.2214/0.2338, allocations: 1.621 MB / 381.3 MB, free: 15.5 MB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0007122/0.2345, allocations: 0.9078 MB / 382.2 MB, free: 15.33 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.000215/0.2347, allocations: 67.55 kB / 382.3 MB, free: 15.33 MB / 302.1 MB
Notification: Performance of FrontEnd: time 0.0002138/0.2349, allocations: 22.12 kB / 382.3 MB, free: 15.33 MB / 302.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 147 (147)
 * Number of variables: 147 (147)
Notification: Performance of [SIM] Bindings: time 0.003244/0.2381, allocations: 3.786 MB / 386.1 MB, free: 13.83 MB / 302.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0005042/0.2386, allocations: 0.5464 MB / 386.7 MB, free: 13.67 MB / 302.1 MB
Notification: Performance of [SIM] Early Inline: time 0.002129/0.2408, allocations: 2.434 MB / 389.1 MB, free: 12.18 MB / 302.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0008472/0.2416, allocations: 0.4984 MB / 389.6 MB, free: 11.96 MB / 302.1 MB
Notification: Performance of [SIM] Alias: time 0.00451/0.2461, allocations: 3.442 MB / 393 MB, free: 9.918 MB / 302.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0005234/0.2467, allocations: 377.8 kB / 393.4 MB, free: 9.758 MB / 302.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0002446/0.2469, allocations: 247.6 kB / 393.6 MB, free: 9.59 MB / 302.1 MB
Notification: Performance of [SIM] Detect States: time 0.0005695/0.2475, allocations: 0.5715 MB / 394.2 MB, free: 9.168 MB / 302.1 MB
Notification: Performance of [SIM] Events: time 0.0006474/0.2481, allocations: 485.4 kB / 394.7 MB, free: 8.926 MB / 302.1 MB
Notification: Performance of [SIM] Partitioning: time 0.001122/0.2492, allocations: 1.026 MB / 395.7 MB, free: 8.297 MB / 302.1 MB
Notification: Performance of [SIM] Causalize: time 0.002748/0.252, allocations: 2.027 MB / 397.7 MB, free: 7.684 MB / 302.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.00156/0.2535, allocations: 1.963 MB / 399.7 MB, free: 6.598 MB / 302.1 MB
Notification: Performance of [INI] Simplify: time 0.001698/0.2552, allocations: 1.157 MB / 400.9 MB, free: 5.824 MB / 302.1 MB
Notification: Performance of [INI] Inline: time 0.002119/0.2574, allocations: 2.748 MB / 403.6 MB, free: 3.738 MB / 302.1 MB
Notification: Performance of [INI] Partitioning: time 0.0001193/0.2575, allocations: 62.3 kB / 403.7 MB, free: 3.695 MB / 302.1 MB
Notification: Performance of [INI] Cleanup: time 0.0003273/0.2578, allocations: 479.3 kB / 404.1 MB, free: 3.297 MB / 302.1 MB
Notification: Performance of [INI] Causalize: time 0.003723/0.2615, allocations: 2.793 MB / 406.9 MB, free: 2.371 MB / 302.1 MB
Notification: Performance of [INI] Tearing: time 4.659e-05/0.2616, allocations: 82.47 kB / 407 MB, free: 2.312 MB / 302.1 MB
Notification: Performance of [SIM] Initialization: time 1.223e-06/0.2616, allocations: 0 / 407 MB, free: 2.312 MB / 302.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.0008161/0.2624, allocations: 0.6781 MB / 407.7 MB, free: 1.816 MB / 302.1 MB
Notification: Performance of [SIM] Remove Dummies: time 1.119e-05/0.2624, allocations: 3.719 kB / 407.7 MB, free: 1.816 MB / 302.1 MB
Notification: Performance of [SIM] Tearing: time 4.997e-05/0.2625, allocations: 96.12 kB / 407.8 MB, free: 1.75 MB / 302.1 MB
Notification: Performance of [SIM] Categorize: time 3.554e-05/0.2625, allocations: 11.05 kB / 407.8 MB, free: 1.75 MB / 302.1 MB
Notification: Performance of [SIM] Solve: time 0.002593/0.2651, allocations: 2.542 MB / 410.3 MB, free: 15.43 MB / 318.1 MB
Notification: Performance of [SIM] Jacobian: time 0.001475/0.2666, allocations: 1.293 MB / 411.6 MB, free: 14.02 MB / 318.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 2
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 12
 * 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: ............................. 2 (2) {Nand.C4.v, Nand.C7.v}
 * Number of discrete states: .................... 4 (4) {VIN2.signalSource.T_start, VIN2.signalSource.count, VIN1.signalSource.T_start, VIN1.signalSource.count}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 43 (43) {$SEV_40, $SEV_39, $SEV_38, $SEV_37, $SEV_36, $SEV_35, $SEV_34, $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_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: ........... 103 (scalar:103, array:0, record:0)
 * Number of multi strong components: ............ 4 (algorithm:0, when:4, 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: ........... 151 (scalar:151, array:0, record:0)
 * Number of multi strong components: ............ 2 (algorithm:2, 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 9.249e-05/0.2667, allocations: 66.28 kB / 411.7 MB, free: 13.96 MB / 318.1 MB
Notification: Performance of SimCode: time 0.003841/0.2705, allocations: 3.122 MB / 414.8 MB, free: 10.75 MB / 318.1 MB
Notification: Performance of Templates: time 0.01976/0.2903, allocations: 10.86 MB / 425.7 MB, free: 15.96 MB / 334.1 MB
"
[Timeout remaining time 660]
make -j1 -f Modelica_4.0.0_Modelica.Electrical.Analog.Examples.NandGate.makefile [Timeout 660]
(rm -f Modelica_4.0.0_Modelica.Electrical.Analog.Examples.NandGate.pipe ; mkfifo Modelica_4.0.0_Modelica.Electrical.Analog.Examples.NandGate.pipe ; head -c 1048576 < Modelica_4.0.0_Modelica.Electrical.Analog.Examples.NandGate.pipe >> ../files/Modelica_4.0.0_Modelica.Electrical.Analog.Examples.NandGate.sim & ./Modelica_4.0.0_Modelica.Electrical.Analog.Examples.NandGate  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_4.0.0_Modelica.Electrical.Analog.Examples.NandGate.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 5.259332140965853]
Failed to read output from testmodel.py, exit status != 0:
['time', 'Nand.C4.v', 'Nand.C7.v']
0.29863899503834546 0.31646353400000005 0.07827293
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f93c599efb0>