Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSysPro_PowerSysPro.Examples.LargerNetwork.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.002103/0.002103, allocations: 104.3 kB / 20.56 MB, free: 1.262 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.002716/0.002716, allocations: 218.5 kB / 23.86 MB, free: 4.414 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.562/1.562, allocations: 230.7 MB / 257.7 MB, free: 7.766 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSysPro 2.1.3/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSysPro 2.1.3/package.mo): time 0.04896/0.04896, allocations: 6.131 MB / 320.2 MB, free: 12.73 MB / 254.1 MB " [Timeout remaining time 180] Using package PowerSysPro with version 2.1.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSysPro 2.1.3/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(PowerSysPro.Examples.LargerNetwork,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSysPro_PowerSysPro.Examples.LargerNetwork") translateModel(PowerSysPro.Examples.LargerNetwork,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSysPro_PowerSysPro.Examples.LargerNetwork") [Timeout 660] "Notification: Performance of FrontEnd - Absyn->SCode: time 2.465e-05/2.465e-05, allocations: 4.531 kB / 445.8 MB, free: 4.516 MB / 302.1 MB Notification: Performance of NFInst.instantiate(PowerSysPro.Examples.LargerNetwork): time 0.01269/0.01272, allocations: 12.09 MB / 457.9 MB, free: 8.375 MB / 318.1 MB Notification: Performance of NFInst.instExpressions: time 0.009357/0.02207, allocations: 3.895 MB / 461.8 MB, free: 4.457 MB / 318.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.003115/0.02519, allocations: 44 kB / 461.9 MB, free: 4.414 MB / 318.1 MB Notification: Performance of NFTyping.typeComponents: time 0.00259/0.02778, allocations: 0.6824 MB / 462.5 MB, free: 3.727 MB / 318.1 MB Notification: Performance of NFTyping.typeBindings: time 0.006517/0.0343, allocations: 1.567 MB / 464.1 MB, free: 2.152 MB / 318.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.005993/0.04029, allocations: 2.036 MB / 466.2 MB, free: 140 kB / 318.1 MB Notification: Performance of NFFlatten.flatten: time 0.008037/0.04833, allocations: 4.5 MB / 470.7 MB, free: 11.62 MB / 334.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.00196/0.05029, allocations: 0.6269 MB / 471.3 MB, free: 10.95 MB / 334.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.002984/0.05327, allocations: 1.603 MB / 472.9 MB, free: 9.336 MB / 334.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.00331/0.05658, allocations: 1.71 MB / 474.6 MB, free: 7.617 MB / 334.1 MB Notification: Performance of NFPackage.collectConstants: time 0.0008773/0.05746, allocations: 288 kB / 474.9 MB, free: 7.336 MB / 334.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.001995/0.05945, allocations: 0.531 MB / 475.4 MB, free: 6.805 MB / 334.1 MB Notification: Performance of combineBinaries: time 0.007971/0.06742, allocations: 7.703 MB / 483.1 MB, free: 15.03 MB / 350.1 MB Notification: Performance of replaceArrayConstructors: time 0.005441/0.07286, allocations: 4.774 MB / 487.9 MB, free: 10.19 MB / 350.1 MB Notification: Performance of NFVerifyModel.verify: time 0.001135/0.074, allocations: 248 kB / 488.1 MB, free: 9.949 MB / 350.1 MB Notification: Performance of FrontEnd: time 0.0006068/0.07461, allocations: 59.88 kB / 488.2 MB, free: 9.891 MB / 350.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 598 (503) * Number of variables: 598 (598) Notification: Performance of [SIM] Bindings: time 0.02932/0.1039, allocations: 19.46 MB / 0.4957 GB, free: 5.938 MB / 366.1 MB Notification: Performance of [SIM] FunctionAlias: time 0.00292/0.1068, allocations: 1.98 MB / 0.4977 GB, free: 3.922 MB / 366.1 MB Notification: Performance of [SIM] Early Inline: time 0.2302/0.3371, allocations: 28.65 MB / 0.5257 GB, free: 43.59 MB / 382.1 MB Notification: Performance of [SIM] Simplify 1: time 0.005538/0.3426, allocations: 2.779 MB / 0.5284 GB, free: 42.98 MB / 382.1 MB Notification: Performance of [SIM] Alias: time 0.01315/0.3558, allocations: 10.46 MB / 0.5386 GB, free: 39.05 MB / 382.1 MB Notification: Performance of [SIM] Simplify 2: time 0.004625/0.3604, allocations: 2.732 MB / 0.5413 GB, free: 38.1 MB / 382.1 MB Notification: Performance of [SIM] Remove Stream: time 0.002043/0.3624, allocations: 1.444 MB / 0.5427 GB, free: 37.17 MB / 382.1 MB Notification: Performance of [SIM] Detect States: time 0.002167/0.3646, allocations: 3.572 MB / 0.5462 GB, free: 34.26 MB / 382.1 MB Notification: Performance of [SIM] Events: time 0.001852/0.3665, allocations: 1.303 MB / 0.5474 GB, free: 33.46 MB / 382.1 MB Notification: Performance of [SIM] Partitioning: time 0.005483/0.3719, allocations: 6.681 MB / 0.554 GB, free: 29.06 MB / 382.1 MB Notification: Performance of [SIM] Causalize: time 0.01573/0.3877, allocations: 12.52 MB / 0.5662 GB, free: 22.06 MB / 382.1 MB Notification: Performance of [SIM] After Index Reduction Inline: time 0.01324/0.4009, allocations: 14.11 MB / 0.58 GB, free: 7.941 MB / 382.1 MB Notification: Performance of [INI] Simplify: time 0.0105/0.4114, allocations: 6.831 MB / 0.5866 GB, free: 1.078 MB / 382.1 MB Notification: Performance of [INI] Inline: time 0.03225/0.4437, allocations: 26.08 MB / 0.6121 GB, free: 7.047 MB / 414.1 MB Notification: Performance of [INI] Partitioning: time 0.001044/0.4447, allocations: 246 kB / 0.6123 GB, free: 6.762 MB / 414.1 MB Notification: Performance of [INI] Cleanup: time 0.005818/0.4505, allocations: 3.568 MB / 0.6158 GB, free: 3.191 MB / 414.1 MB Warning: NBResolveSingularities.balanceInitialization reports an overdetermined initialization! Checking for consistency is not yet supported, following equations had to be removed: [SCAL] (1) $SEV_19 = b61106.U < b61106.minU and b61106.switchToImpedanceMode ($RES_EVT_955); [SCAL] (1) $SEV_18 = b87656.U < b87656.minU and b87656.switchToImpedanceMode ($RES_EVT_954); [SCAL] (1) $SEV_17 = b56131.U < b56131.minU and b56131.switchToImpedanceMode ($RES_EVT_953); [SCAL] (1) $SEV_16 = b61275.U < b61275.minU and b61275.switchToImpedanceMode ($RES_EVT_952); [SCAL] (1) $SEV_14 = b61274.U < b61274.minU and b61274.switchToImpedanceMode ($RES_EVT_950); [SCAL] (1) $SEV_13 = b61089.U < b61089.minU and b61089.switchToImpedanceMode ($RES_EVT_949); [SCAL] (1) $SEV_12 = b61227.U < b61227.minU and b61227.switchToImpedanceMode ($RES_EVT_948); [SCAL] (1) $SEV_10 = b11407.U < b11407.minU and b11407.switchToImpedanceMode ($RES_EVT_946); [SCAL] (1) $SEV_9 = b61254.U < b61254.minU and b61254.switchToImpedanceMode ($RES_EVT_945); [SCAL] (1) $SEV_7 = b11465.U < b11465.minU and b11465.switchToImpedanceMode ($RES_EVT_943); [SCAL] (1) $SEV_6 = b87694.U < b87694.minU and b87694.switchToImpedanceMode ($RES_EVT_942); [SCAL] (1) $SEV_5 = b61121.U < b61121.minU and b61121.switchToImpedanceMode ($RES_EVT_941); [SCAL] (1) $SEV_4 = b61232.U < b61232.minU and b61232.switchToImpedanceMode ($RES_EVT_940); [SCAL] (1) $SEV_3 = b11480.U < b11480.minU and b11480.switchToImpedanceMode ($RES_EVT_939); [SCAL] (1) $SEV_2 = b61256.U < b61256.minU and b61256.switchToImpedanceMode ($RES_EVT_938); [SCAL] (1) $SEV_1 = b11458.U < b11458.minU and b11458.switchToImpedanceMode ($RES_EVT_937); [SCAL] (1) b61273.degradedMode = $START.b61273.degradedMode ($RES_SRT_1014); [SCAL] (1) b11403.degradedMode = $START.b11403.degradedMode ($RES_SRT_1010); [SCAL] (1) b61260.degradedMode = $START.b61260.degradedMode ($RES_SRT_1007); [SCAL] (1) b61280.degradedMode = $START.b61280.degradedMode ($RES_SRT_999); Notification: Performance of [INI] Causalize: time 0.06073/0.5113, allocations: 30.48 MB / 0.6456 GB, free: 4.688 MB / 446.1 MB Notification: Performance of [INI] Tearing: time 0.008099/0.5194, allocations: 5.598 MB / 0.651 GB, free: 15.25 MB / 462.1 MB Notification: Performance of [SIM] Initialization: time 2.634e-06/0.5194, allocations: 0 / 0.651 GB, free: 15.25 MB / 462.1 MB Notification: Performance of [SIM] DAE-Mode: time 0.009971/0.5293, allocations: 6.201 MB / 0.6571 GB, free: 9.117 MB / 462.1 MB Notification: Performance of [SIM] Remove Dummies: time 3.316e-05/0.5294, allocations: 2.094 kB / 0.6571 GB, free: 9.113 MB / 462.1 MB Notification: Performance of [SIM] Tearing: time 0.008223/0.5376, allocations: 5.254 MB / 0.6622 GB, free: 3.879 MB / 462.1 MB Notification: Performance of [SIM] Categorize: time 9.935e-05/0.5377, allocations: 23.66 kB / 0.6623 GB, free: 3.859 MB / 462.1 MB Notification: Performance of [SIM] Solve: time 0.03293/0.5706, allocations: 20.6 MB / 0.6824 GB, free: 15.3 MB / 494.1 MB Notification: Performance of [SIM] Jacobian: time 0.2747/0.8453, allocations: 38.67 MB / 0.7201 GB, free: 116.5 MB / 494.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: ............... 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: .................... 0 (0) {} * Number of clocked states: ..................... 0 (0) {} * Number of discrete variables: ................. 83 (83) {b61280.degradedMode, b11458.degradedMode, b61256.degradedMode, b11480.degradedMode, b61232.degradedMode, b61121.degradedMode, b87694.degradedMode, b11465.degradedMode, b61260.degradedMode, b61254.degradedMode, b11407.degradedMode, b11403.degradedMode, b61227.degradedMode, b61089.degradedMode, b61274.degradedMode, b61273.degradedMode, b61275.degradedMode, b56131.degradedMode, b87656.degradedMode, b61106.degradedMode, $SEV_62, $SEV_61, $SEV_60, $SEV_59, $SEV_58, $SEV_57, $SEV_56, $SEV_55, $SEV_54, $SEV_53, $SEV_52, $SEV_51, $SEV_50, $SEV_49, $SEV_48, $SEV_47, $SEV_46, $SEV_45, $SEV_44, $SEV_43, $SEV_42, $SEV_41, $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} * Number of clocks: ............................. 0 (0) {} * Number of top-level inputs: ................... 40 (40) {BUILDING0000000322361280_Pload, BUILDING0000000322361280_Qload, BUILDING0000000005011458_Pload, BUILDING0000000005011458_Qload, BUILDING0000000322361256_Pload, BUILDING0000000322361256_Qload, BUILDING0000000005011480_Pload, BUILDING0000000005011480_Qload, BUILDING0000000322361232_Pload, BUILDING0000000322361232_Qload, BUILDING0000000322361121_Pload, BUILDING0000000322361121_Qload, BUILDING0000000356487694_Pload, BUILDING0000000356487694_Qload, BUILDING0000000005011465_Pload, BUILDING0000000005011465_Qload, BUILDING0000000322361260_Pload, BUILDING0000000322361260_Qload, BUILDING0000000322361254_Pload, BUILDING0000000322361254_Qload, BUILDING0000000005011407_Pload, BUILDING0000000005011407_Qload, BUILDING0000000005011403_Pload, BUILDING0000000005011403_Qload, BUILDING0000000322361227_Pload, BUILDING0000000322361227_Qload, BUILDING0000000322361089_Pload, BUILDING0000000322361089_Qload, BUILDING0000000322361274_Pload, BUILDING0000000322361274_Qload, BUILDING0000000322361273_Pload, BUILDING0000000322361273_Qload, BUILDING0000000322361275_Pload, BUILDING0000000322361275_Qload, BUILDING0000000320456131_Pload, BUILDING0000000320456131_Qload, BUILDING0000000356487656_Pload, BUILDING0000000356487656_Qload, BUILDING0000000322361106_Pload, BUILDING0000000322361106_Qload} Notification: [Simulation] Strong Component statistics after passing the back-end: * Number of single strong components: ........... 267 (scalar:267, 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: ... 1 (linear: 1, nonlinear:0) Notification: [Initialization] Strong Component statistics after passing the back-end: * Number of single strong components: ........... 509 (scalar:509, 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: ... 1 (linear: 1, nonlinear:0) Notification: Performance of backend: time 0.0003302/0.8456, allocations: 154.2 kB / 0.7203 GB, free: 116.5 MB / 494.1 MB Notification: Performance of SimCode: time 0.02151/0.8671, allocations: 18.81 MB / 0.7386 GB, free: 111.5 MB / 494.1 MB Notification: Performance of Templates: time 0.09392/0.961, allocations: 77.29 MB / 0.8141 GB, free: 53.72 MB / 494.1 MB " [Timeout remaining time 659] make -j1 -f PowerSysPro_PowerSysPro.Examples.LargerNetwork.makefile [Timeout 660] (rm -f PowerSysPro_PowerSysPro.Examples.LargerNetwork.pipe ; mkfifo PowerSysPro_PowerSysPro.Examples.LargerNetwork.pipe ; head -c 1048576 < PowerSysPro_PowerSysPro.Examples.LargerNetwork.pipe >> ../files/PowerSysPro_PowerSysPro.Examples.LargerNetwork.sim & ./PowerSysPro_PowerSysPro.Examples.LargerNetwork -abortSlowSimulation -alarm=480 -lv LOG_STATS > PowerSysPro_PowerSysPro.Examples.LargerNetwork.pipe 2>&1) [Timeout 480] [Calling os._exit(0), Time elapsed: 9.188908874988556] Failed to read output from testmodel.py, exit status != 0: 0.9684465680038556 0.977489487 0.900560646 Calling exit ...