Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr AES_AES.Devel_test_models.dz.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.001169/0.001169, allocations: 108.3 kB / 19.72 MB, free: 2.137 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.001334/0.001334, allocations: 209.9 kB / 23 MB, free: 4.957 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.398/1.398, allocations: 230.6 MB / 256.8 MB, free: 7.809 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/AES main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/AES main/package.mo): time 0.1045/0.1045, allocations: 24.13 MB / 337.3 MB, free: 11.25 MB / 270.1 MB
"
[Timeout remaining time 180]
Using package AES with version  (/home/hudson/saved_omc/libraries/.openmodelica/libraries/AES main/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)
Ignoring simflag  -noemit -variableFilter=.* since it seems broken on HelloWorld
Running command: translateModel(AES.Devel_test_models.dz,tolerance=1e-06,outputFormat="empty",numberOfIntervals=1000,variableFilter="",fileNamePrefix="AES_AES.Devel_test_models.dz")
translateModel(AES.Devel_test_models.dz,tolerance=1e-06,outputFormat="empty",numberOfIntervals=1000,variableFilter="",fileNamePrefix="AES_AES.Devel_test_models.dz") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.689e-05/2.689e-05, allocations: 4.812 kB / 475.2 MB, free: 5.035 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(AES.Devel_test_models.dz): time 0.003315/0.003341, allocations: 1.898 MB / 477.1 MB, free: 3.121 MB / 334.1 MB
Notification: Performance of NFInst.instExpressions: time 0.0005753/0.003917, allocations: 223.2 kB / 477.3 MB, free: 2.902 MB / 334.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 9.651e-05/0.004013, allocations: 4 kB / 477.3 MB, free: 2.898 MB / 334.1 MB
Notification: Performance of NFTyping.typeComponents: time 4.933e-05/0.004063, allocations: 19.73 kB / 477.3 MB, free: 2.879 MB / 334.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0002243/0.004287, allocations: 39.67 kB / 477.4 MB, free: 2.84 MB / 334.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0002633/0.00455, allocations: 99.83 kB / 477.5 MB, free: 2.742 MB / 334.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0002842/0.004834, allocations: 239.5 kB / 477.7 MB, free: 2.508 MB / 334.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0001869/0.005021, allocations: 47.2 kB / 477.7 MB, free: 2.457 MB / 334.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.000141/0.005162, allocations: 99.69 kB / 477.8 MB, free: 2.359 MB / 334.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0001152/0.005277, allocations: 75.62 kB / 477.9 MB, free: 2.285 MB / 334.1 MB
Notification: Performance of NFPackage.collectConstants: time 3.563e-05/0.005313, allocations: 16 kB / 477.9 MB, free: 2.27 MB / 334.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 2.936e-05/0.005342, allocations: 16 kB / 477.9 MB, free: 2.254 MB / 334.1 MB
Notification: Performance of combineBinaries: time 0.0002738/0.005616, allocations: 340.3 kB / 478.3 MB, free: 1.918 MB / 334.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0001315/0.005748, allocations: 213.1 kB / 478.5 MB, free: 1.707 MB / 334.1 MB
Notification: Performance of NFVerifyModel.verify: time 2.639e-05/0.005774, allocations: 12 kB / 478.5 MB, free: 1.695 MB / 334.1 MB
Notification: Performance of FrontEnd: time 2.987e-05/0.005804, allocations: 11.88 kB / 478.5 MB, free: 1.684 MB / 334.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 30 (30)
 * Number of variables: 30 (30)
Notification: Performance of [SIM] Bindings: time 0.0009952/0.006799, allocations: 0.8796 MB / 479.4 MB, free: 0.6797 MB / 334.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0001673/0.006966, allocations: 117 kB / 479.5 MB, free: 0.5547 MB / 334.1 MB
Notification: Performance of [SIM] Early Inline: time 0.000408/0.007374, allocations: 401.4 kB / 479.9 MB, free: 136 kB / 334.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0002072/0.007582, allocations: 135.2 kB / 480 MB, free: 15.96 MB / 350.1 MB
Notification: Performance of [SIM] Alias: time 0.001168/0.00875, allocations: 0.7753 MB / 480.8 MB, free: 15.03 MB / 350.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0001501/0.0089, allocations: 111.5 kB / 480.9 MB, free: 14.88 MB / 350.1 MB
Notification: Performance of [SIM] Remove Stream: time 7.602e-05/0.008976, allocations: 68.33 kB / 481 MB, free: 14.78 MB / 350.1 MB
Notification: Performance of [SIM] Detect States: time 0.0002073/0.009183, allocations: 148 kB / 481.1 MB, free: 14.59 MB / 350.1 MB
Notification: Performance of [SIM] Events: time 9.086e-05/0.009274, allocations: 52.09 kB / 481.2 MB, free: 14.54 MB / 350.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0002121/0.009486, allocations: 156.1 kB / 481.3 MB, free: 14.37 MB / 350.1 MB
Notification: Performance of [SIM] Causalize: time 0.0003881/0.009874, allocations: 260.8 kB / 481.6 MB, free: 14.1 MB / 350.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.000259/0.01013, allocations: 287.5 kB / 481.9 MB, free: 13.82 MB / 350.1 MB
Notification: Performance of [INI] Simplify: time 0.0003603/0.01049, allocations: 234.3 kB / 482.1 MB, free: 13.52 MB / 350.1 MB
Notification: Performance of [INI] Inline: time 0.0003779/0.01087, allocations: 403.6 kB / 482.5 MB, free: 13.12 MB / 350.1 MB
Notification: Performance of [INI] Partitioning: time 2.341e-05/0.0109, allocations: 16.12 kB / 482.5 MB, free: 13.1 MB / 350.1 MB
Notification: Performance of [INI] Cleanup: time 9.587e-05/0.01099, allocations: 87.98 kB / 482.6 MB, free: 13.01 MB / 350.1 MB
Notification: Performance of [INI] Causalize: time 0.001854/0.01285, allocations: 1.318 MB / 483.9 MB, free: 11.67 MB / 350.1 MB
Notification: Performance of [INI] Tearing: time 2.989e-05/0.01288, allocations: 39.88 kB / 483.9 MB, free: 11.63 MB / 350.1 MB
Notification: Performance of [SIM] Initialization: time 5.81e-07/0.01288, allocations: 0 / 483.9 MB, free: 11.63 MB / 350.1 MB
Notification: Performance of [SIM] Remove Dummies: time 5.069e-06/0.01288, allocations: 0 / 483.9 MB, free: 11.63 MB / 350.1 MB
Notification: Performance of [SIM] Tearing: time 5.771e-06/0.01289, allocations: 8 kB / 483.9 MB, free: 11.62 MB / 350.1 MB
Notification: Performance of [SIM] Categorize: time 9.047e-06/0.0129, allocations: 3.984 kB / 483.9 MB, free: 11.62 MB / 350.1 MB
Notification: Performance of [SIM] Solve: time 0.0003114/0.01321, allocations: 203.8 kB / 484.1 MB, free: 11.42 MB / 350.1 MB
Notification: Performance of [SIM] Jacobian: time 0.000278/0.01349, allocations: 266 kB / 484.4 MB, free: 11.12 MB / 350.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 1
 * 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: ............................. 4 (4) {C.add1.u2, Aplus.y, Aminus.y, P.y}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 5 (5) {$SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_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: ............ 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: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 25 (scalar:25, 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: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 25 (scalar:25, 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.476e-05/0.01354, allocations: 39.33 kB / 484.4 MB, free: 11.09 MB / 350.1 MB
Notification: Performance of SimCode: time 0.0008056/0.01435, allocations: 0.6282 MB / 485.1 MB, free: 10.45 MB / 350.1 MB
Notification: Performance of Templates: time 0.004775/0.01912, allocations: 1.608 MB / 486.7 MB, free: 8.863 MB / 350.1 MB
"
[Timeout remaining time 660]
make -j1 -f AES_AES.Devel_test_models.dz.makefile [Timeout 660]
(rm -f AES_AES.Devel_test_models.dz.pipe ; mkfifo AES_AES.Devel_test_models.dz.pipe ; head -c 1048576 < AES_AES.Devel_test_models.dz.pipe >> ../files/AES_AES.Devel_test_models.dz.sim & ./AES_AES.Devel_test_models.dz  -lv=LOG_STDOUT,LOG_ASSERT,LOG_STATS -s=dassl -abortSlowSimulation -alarm=480 -lv LOG_STATS > AES_AES.Devel_test_models.dz.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 4.36223620781675]
Failed to read output from testmodel.py, exit status != 0:
0.027547867968678474 0.041794151 0.033008532
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f6ebb31ef20>