Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSystems_master_PowerSystems.Examples.AC3ph.Transmission.RXline.conf.json
Using package PowerSystems with version 1.0.0 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 1.0.0-master/package.mo)
Using package ModelicaServices with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo)
Using package Modelica with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo)
Using package Complex with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo)
Running command: translateModel(PowerSystems.Examples.AC3ph.Transmission.RXline,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="PowerSystems_master_PowerSystems.Examples.AC3ph.Transmission.RXline")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo): time 0.00251/0.00251, allocations: 361.6 kB / 17.21 MB, free: 4.926 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo): time 3.72/3.72, allocations: 388.9 MB / 406.8 MB, free: 12.01 MB / 330.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo): time 0.003621/0.003621, allocations: 227.8 kB / 452.2 MB, free: 14.61 MB / 378.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 1.0.0-master/package.mo): time 0.7727/0.7727, allocations: 76.01 MB / 0.56 GB, free: 177.5 MB / 442.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004458/0.000446, allocations: 12 kB / 0.6222 GB, free: 114.1 MB / 442.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.09006/0.09053, allocations: 53.23 MB / 0.6742 GB, free: 60.82 MB / 442.7 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transmission.RXline): time 0.007842/0.09843, allocations: 6.572 MB / 0.6807 GB, free: 54.21 MB / 442.7 MB
Notification: Performance of NFInst.instExpressions: time 0.004966/0.1035, allocations: 2.261 MB / 0.6829 GB, free: 51.95 MB / 442.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0006782/0.1042, allocations: 35.81 kB / 0.6829 GB, free: 51.91 MB / 442.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0005402/0.1047, allocations: 150.9 kB / 0.683 GB, free: 51.76 MB / 442.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.00178/0.1065, allocations: 0.5404 MB / 0.6836 GB, free: 51.22 MB / 442.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001172/0.1077, allocations: 339.2 kB / 0.6839 GB, free: 50.89 MB / 442.7 MB
Notification: Performance of NFFlatten.flatten: time 0.001564/0.1093, allocations: 1.368 MB / 0.6852 GB, free: 49.52 MB / 442.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001651/0.111, allocations: 0.8382 MB / 0.686 GB, free: 48.64 MB / 442.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0008058/0.1118, allocations: 414.1 kB / 0.6864 GB, free: 48.23 MB / 442.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0009953/0.1128, allocations: 0.631 MB / 0.6871 GB, free: 47.6 MB / 442.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001269/0.113, allocations: 43.94 kB / 0.6871 GB, free: 47.56 MB / 442.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001048/0.114, allocations: 479.2 kB / 0.6876 GB, free: 47.09 MB / 442.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0004597/0.1145, allocations: 430.9 kB / 0.688 GB, free: 46.67 MB / 442.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002601/0.1148, allocations: 197.2 kB / 0.6882 GB, free: 46.47 MB / 442.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.001292/0.1161, allocations: 1.009 MB / 0.6891 GB, free: 45.46 MB / 442.7 MB
Notification: Performance of FrontEnd - DAE generated: time 6.793e-06/0.1162, allocations: 0 / 0.6891 GB, free: 45.46 MB / 442.7 MB
Notification: Performance of FrontEnd: time 2.575e-06/0.1162, allocations: 0 / 0.6891 GB, free: 45.46 MB / 442.7 MB
Notification: Performance of Transformations before backend: time 3.4e-05/0.1162, allocations: 0 / 0.6891 GB, free: 45.46 MB / 442.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 242
 * Number of variables: 242
Notification: Performance of Generate backend data structure: time 0.003138/0.1194, allocations: 1.214 MB / 0.6903 GB, free: 44.18 MB / 442.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.501e-05/0.1194, allocations: 12.03 kB / 0.6903 GB, free: 44.16 MB / 442.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001219/0.1207, allocations: 162.6 kB / 0.6905 GB, free: 44 MB / 442.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00131/0.122, allocations: 0.5842 MB / 0.6911 GB, free: 43.38 MB / 442.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.19e-05/0.1221, allocations: 70 kB / 0.6911 GB, free: 43.31 MB / 442.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002883/0.1224, allocations: 107.8 kB / 0.6912 GB, free: 43.2 MB / 442.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002633/0.125, allocations: 1.307 MB / 0.6925 GB, free: 41.83 MB / 442.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.046e-05/0.1251, allocations: 3.938 kB / 0.6925 GB, free: 41.83 MB / 442.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001293/0.1252, allocations: 40 kB / 0.6925 GB, free: 41.79 MB / 442.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002165/0.1255, allocations: 61.97 kB / 0.6926 GB, free: 41.73 MB / 442.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002677/0.1282, allocations: 1.175 MB / 0.6938 GB, free: 40.55 MB / 442.7 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.008546/0.1367, allocations: 4.672 MB / 0.6983 GB, free: 35.71 MB / 442.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001955/0.1387, allocations: 1.288 MB / 0.6996 GB, free: 34.29 MB / 442.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001246/0.14, allocations: 0.6408 MB / 0.7002 GB, free: 33.65 MB / 442.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 8.314e-05/0.1401, allocations: 9.594 kB / 0.7002 GB, free: 33.64 MB / 442.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001639/0.1418, allocations: 0.8947 MB / 0.7011 GB, free: 32.71 MB / 442.7 MB
Notification: Performance of pre-optimization done (n=122): time 3.706e-06/0.1418, allocations: 0 / 0.7011 GB, free: 32.71 MB / 442.7 MB
Notification: Performance of matching and sorting (n=123): time 0.007347/0.1491, allocations: 3.593 MB / 0.7046 GB, free: 29.02 MB / 442.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.687e-05/0.1493, allocations: 238.9 kB / 0.7048 GB, free: 28.74 MB / 442.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0006743/0.15, allocations: 0.4939 MB / 0.7053 GB, free: 28.23 MB / 442.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001097/0.1501, allocations: 49.7 kB / 0.7054 GB, free: 28.18 MB / 442.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0002883/0.1504, allocations: 408 kB / 0.7057 GB, free: 27.77 MB / 442.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003281/0.1507, allocations: 367 kB / 0.7061 GB, free: 27.41 MB / 442.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 9.686e-05/0.1509, allocations: 24 kB / 0.7061 GB, free: 27.39 MB / 442.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001313/0.151, allocations: 398.7 kB / 0.7065 GB, free: 26.99 MB / 442.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001162/0.1522, allocations: 0.5146 MB / 0.707 GB, free: 26.48 MB / 442.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001301/0.1535, allocations: 0.77 MB / 0.7077 GB, free: 25.61 MB / 442.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001325/0.1548, allocations: 0.6417 MB / 0.7084 GB, free: 24.96 MB / 442.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.622e-06/0.1548, allocations: 0 / 0.7084 GB, free: 24.96 MB / 442.7 MB
Notification: Performance of matching and sorting (n=175) (initialization): time 0.005534/0.1604, allocations: 2.043 MB / 0.7104 GB, free: 22.89 MB / 442.7 MB
Notification: Performance of prepare postOptimizeDAE: time 5.858e-05/0.1605, allocations: 63.39 kB / 0.7104 GB, free: 22.82 MB / 442.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.004575/0.1651, allocations: 1.63 MB / 0.712 GB, free: 21.19 MB / 442.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003124/0.1683, allocations: 0.6611 MB / 0.7127 GB, free: 20.52 MB / 442.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008707/0.1692, allocations: 135.7 kB / 0.7128 GB, free: 20.39 MB / 442.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01298/0.1822, allocations: 5.895 MB / 0.7186 GB, free: 14.36 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001005/0.1832, allocations: 59.95 kB / 0.7186 GB, free: 14.3 MB / 442.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001177/0.1834, allocations: 51.94 kB / 0.7187 GB, free: 14.25 MB / 442.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 31
 * Number of states: 0 ()
 * Number of discrete variables: 67 (system.initime,$whenCondition2,$PRE.relay2.cnt,relay2.cnt,$whenCondition1,$PRE.relay1.cnt,relay1.cnt,$whenCondition20,$whenCondition19,$whenCondition18,$whenCondition17,$whenCondition16,$whenCondition15,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$PRE.switch1.switch_a.arc,switch1.switch_a.arc,$PRE.switch1.switch_a.open,switch1.switch_a.open,switch1.switch_a.closed,$PRE.switch1.switch_b.arc,switch1.switch_b.arc,$PRE.switch1.switch_b.open,switch1.switch_b.open,switch1.switch_b.closed,$PRE.switch1.switch_c.arc,switch1.switch_c.arc,$PRE.switch1.switch_c.open,switch1.switch_c.open,switch1.switch_c.closed,$PRE.switch2.switch_a.arc,switch2.switch_a.arc,$PRE.switch2.switch_a.open,switch2.switch_a.open,switch2.switch_a.closed,$PRE.switch2.switch_b.arc,switch2.switch_b.arc,$PRE.switch2.switch_b.open,switch2.switch_b.open,switch2.switch_b.closed,$PRE.switch2.switch_c.arc,switch2.switch_c.arc,$PRE.switch2.switch_c.open,switch2.switch_c.open,switch2.switch_c.closed,$PRE.relay1.y[1],relay1.y[1],$PRE.relay1.y[2],relay1.y[2],$PRE.relay1.y[3],relay1.y[3],$PRE.relay2.y[1],relay2.y[1],$PRE.relay2.y[2],relay2.y[2],$PRE.relay2.y[3],relay2.y[3])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (124):
 * Single equations (assignments): 119
 * Array equations: 2
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(7,100.0%) 29}
 * Non-linear torn systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0007184/0.1841, allocations: 442.1 kB / 0.7191 GB, free: 13.79 MB / 442.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002144/0.1843, allocations: 79.06 kB / 0.7192 GB, free: 13.71 MB / 442.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002952/0.1873, allocations: 1.419 MB / 0.7205 GB, free: 12.27 MB / 442.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.447e-05/0.1873, allocations: 7.984 kB / 0.7206 GB, free: 12.27 MB / 442.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.081e-06/0.1873, allocations: 0 / 0.7206 GB, free: 12.27 MB / 442.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0001575/0.1875, allocations: 152 kB / 0.7207 GB, free: 12.1 MB / 442.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001928/0.1877, allocations: 39.94 kB / 0.7207 GB, free: 12.06 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006898/0.1884, allocations: 51.94 kB / 0.7208 GB, free: 12.01 MB / 442.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002803/0.1887, allocations: 96.86 kB / 0.7209 GB, free: 11.91 MB / 442.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.004347/0.193, allocations: 1.853 MB / 0.7227 GB, free: 10.01 MB / 442.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.004405/0.1975, allocations: 2.466 MB / 0.7251 GB, free: 7.516 MB / 442.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001362/0.1989, allocations: 0.7146 MB / 0.7258 GB, free: 6.801 MB / 442.7 MB
Notification: Performance of sorting global known variables: time 0.0005177/0.1994, allocations: 342.8 kB / 0.7261 GB, free: 6.465 MB / 442.7 MB
Notification: Performance of Backend: time 1.383e-06/0.1994, allocations: 4 kB / 0.7261 GB, free: 6.461 MB / 442.7 MB
Notification: Performance of simCode: created initialization part: time 0.004349/0.2038, allocations: 1.449 MB / 0.7275 GB, free: 4.965 MB / 442.7 MB
Notification: Performance of SimCode: time 0.004013/0.2078, allocations: 2.666 MB / 0.7301 GB, free: 2.227 MB / 442.7 MB
Notification: Performance of Templates: time 0.3293/0.5372, allocations: 13.94 MB / 0.7438 GB, free: 126.9 MB / 442.7 MB