Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.2_ModelicaTest.Electrical.QuasiStatic.MultiPhase.Ideal.conf.json
Using package ModelicaTest with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+maint.om/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(ModelicaTest.Electrical.QuasiStatic.MultiPhase.Ideal,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|I.1..re|I.1..im|I.2..re|I.2..im|I.3..re|I.3..im",fileNamePrefix="ModelicaTest_3.2.2_ModelicaTest.Electrical.QuasiStatic.MultiPhase.Ideal")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo): time 0.002977/0.002976, allocations: 359.9 kB / 15.95 MB, free: 6.207 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 4.358/4.358, allocations: 388.9 MB / 405.6 MB, free: 9.668 MB / 346.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo): time 0.003498/0.003498, allocations: 225.8 kB / 451 MB, free: 145 MB / 346.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+maint.om/package.mo): time 0.5907/0.5907, allocations: 80.02 MB / 0.5627 GB, free: 19.77 MB / 346.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004779/0.0004782, allocations: 12 kB / 0.6265 GB, free: 96.54 MB / 346.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1128/0.1133, allocations: 54.87 MB / 0.6801 GB, free: 41.65 MB / 346.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 0.5069/0.6203, allocations: 106.5 MB / 0.7841 GB, free: 10.91 MB / 378.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0003528/0.6207, allocations: 90.75 kB / 0.7842 GB, free: 10.86 MB / 378.7 MB
Notification: Performance of FrontEnd - DAE generated: time 2.629/3.25, allocations: 469 MB / 1.242 GB, free: 8.477 MB / 490.7 MB
Notification: Performance of FrontEnd: time 3.527e-06/3.25, allocations: 0 / 1.242 GB, free: 8.477 MB / 490.7 MB
Notification: Performance of Transformations before backend: time 0.001154/3.251, allocations: 299.7 kB / 1.243 GB, free: 8.184 MB / 490.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3915
 * Number of variables: 3915
Notification: Performance of Generate backend data structure: time 0.8944/4.146, allocations: 13.95 MB / 1.256 GB, free: 32.54 MB / 490.8 MB
Notification: Performance of prepare preOptimizeDAE: time 4.495e-05/4.146, allocations: 14.56 kB / 1.256 GB, free: 32.54 MB / 490.8 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06881/4.215, allocations: 8.552 MB / 1.265 GB, free: 31.82 MB / 490.8 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.03042/4.245, allocations: 8.591 MB / 1.273 GB, free: 31.14 MB / 490.8 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002184/4.247, allocations: 1.028 MB / 1.274 GB, free: 31.11 MB / 490.8 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.008406/4.256, allocations: 1.302 MB / 1.275 GB, free: 31.11 MB / 490.8 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1607/4.417, allocations: 25.01 MB / 1.3 GB, free: 29.51 MB / 490.8 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000797/4.418, allocations: 21.41 kB / 1.3 GB, free: 29.5 MB / 490.8 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00696/4.424, allocations: 0.5603 MB / 1.3 GB, free: 29.5 MB / 490.8 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001618/4.426, allocations: 0.9246 MB / 1.301 GB, free: 29.47 MB / 490.8 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0701/4.496, allocations: 22.52 MB / 1.323 GB, free: 29.35 MB / 490.8 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.2111/4.707, allocations: 75.53 MB / 1.397 GB, free: 10.56 MB / 0.4949 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.05144/4.759, allocations: 21.27 MB / 1.418 GB, free: 7.996 MB / 0.5105 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.03396/4.793, allocations: 11.7 MB / 1.429 GB, free: 13.83 MB / 0.5262 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.5878/5.381, allocations: 38.7 MB / 1.467 GB, free: 74.49 MB / 0.5262 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.00017/5.381, allocations: 130.9 kB / 1.467 GB, free: 74.45 MB / 0.5262 GB
Notification: Performance of pre-optimization done (n=1188): time 1.924e-05/5.381, allocations: 0 / 1.467 GB, free: 74.45 MB / 0.5262 GB
Notification: Performance of matching and sorting (n=1188): time 0.1039/5.485, allocations: 31.36 MB / 1.498 GB, free: 73.78 MB / 0.5262 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001295/5.485, allocations: 177.3 kB / 1.498 GB, free: 73.68 MB / 0.5262 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008571/5.494, allocations: 3.307 MB / 1.501 GB, free: 73.45 MB / 0.5262 GB
Notification: Performance of collectPreVariables (initialization): time 0.002946/5.497, allocations: 180.3 kB / 1.501 GB, free: 73.42 MB / 0.5262 GB
Notification: Performance of collectInitialEqns (initialization): time 0.004095/5.501, allocations: 4.428 MB / 1.505 GB, free: 71.54 MB / 0.5262 GB
Notification: Performance of collectInitialBindings (initialization): time 0.007478/5.508, allocations: 3.87 MB / 1.509 GB, free: 69.01 MB / 0.5262 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003447/5.512, allocations: 173.2 kB / 1.509 GB, free: 69.01 MB / 0.5262 GB
Notification: Performance of setup shared object (initialization): time 0.001699/5.514, allocations: 0.7469 MB / 1.51 GB, free: 68.69 MB / 0.5262 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.02323/5.537, allocations: 8.077 MB / 1.518 GB, free: 68.66 MB / 0.5262 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.02529/5.562, allocations: 9.605 MB / 1.527 GB, free: 67.38 MB / 0.5262 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02607/5.588, allocations: 9.407 MB / 1.537 GB, free: 64.94 MB / 0.5262 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.06e-05/5.589, allocations: 15.19 kB / 1.537 GB, free: 64.94 MB / 0.5262 GB
Notification: Performance of matching and sorting (n=1360) (initialization): time 0.08621/5.675, allocations: 23.94 MB / 1.56 GB, free: 55.41 MB / 0.5262 GB
Notification: Performance of prepare postOptimizeDAE: time 9.951e-05/5.675, allocations: 20.81 kB / 1.56 GB, free: 55.41 MB / 0.5262 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002019/5.675, allocations: 32.69 kB / 1.56 GB, free: 55.41 MB / 0.5262 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.05906/5.734, allocations: 10.36 MB / 1.57 GB, free: 52.36 MB / 0.5262 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01142/5.746, allocations: 1.349 MB / 1.571 GB, free: 52.32 MB / 0.5262 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06941/5.815, allocations: 19.53 MB / 1.591 GB, free: 40.98 MB / 0.5262 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01544/5.831, allocations: 1.116 MB / 1.592 GB, free: 40.02 MB / 0.5262 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004492/5.835, allocations: 385.4 kB / 1.592 GB, free: 39.67 MB / 0.5262 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 149
 * Number of states: 0 ()
 * Number of discrete variables: 12 (booleanStep1[1].y,booleanStep1[2].y,booleanStep1[3].y,booleanStep2[1].y,booleanStep2[2].y,booleanStep2[3].y,booleanStep3[1].y,booleanStep3[2].y,booleanStep3[3].y,booleanStep4[1].y,booleanStep4[2].y,booleanStep4[3].y)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (695):
 * Single equations (assignments): 628
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 63
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 4 {6 9,6 9,6 9,99 312}
Notification: Performance of prepare postOptimizeDAE: time 0.01956/5.855, allocations: 5.35 MB / 1.597 GB, free: 35.35 MB / 0.5262 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004281/5.859, allocations: 0.5901 MB / 1.598 GB, free: 34.75 MB / 0.5262 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.08541/5.945, allocations: 24.7 MB / 1.622 GB, free: 13.97 MB / 0.5262 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0006243/5.945, allocations: 334.4 kB / 1.622 GB, free: 13.69 MB / 0.5262 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.783e-05/5.945, allocations: 0 / 1.622 GB, free: 13.69 MB / 0.5262 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0002264/5.946, allocations: 48.08 kB / 1.622 GB, free: 13.69 MB / 0.5262 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.588/6.534, allocations: 43.76 MB / 1.665 GB, free: 87.83 MB / 0.5418 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001924/6.534, allocations: 0 / 1.665 GB, free: 87.83 MB / 0.5418 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.01049/6.544, allocations: 1.085 MB / 1.666 GB, free: 87.83 MB / 0.5418 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.03827/6.583, allocations: 10.32 MB / 1.676 GB, free: 87.47 MB / 0.5418 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.003391/6.586, allocations: 245 kB / 1.676 GB, free: 87.47 MB / 0.5418 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04911/6.635, allocations: 19.46 MB / 1.695 GB, free: 85.24 MB / 0.5418 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.118e-06/6.635, allocations: 3.906 kB / 1.695 GB, free: 85.24 MB / 0.5418 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.1102/6.746, allocations: 36.34 MB / 1.731 GB, free: 84.37 MB / 0.5418 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01031/6.756, allocations: 2.686 MB / 1.733 GB, free: 83.18 MB / 0.5418 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.004551/6.761, allocations: 158.8 kB / 1.734 GB, free: 83.06 MB / 0.5418 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01045/6.771, allocations: 0.5292 MB / 1.734 GB, free: 82.94 MB / 0.5418 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00514/6.776, allocations: 0.8824 MB / 1.735 GB, free: 82.77 MB / 0.5418 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.003522/6.78, allocations: 314.1 kB / 1.735 GB, free: 82.69 MB / 0.5418 GB
Notification: Performance of sorting global known variables: time 0.006016/6.786, allocations: 2.248 MB / 1.738 GB, free: 81.91 MB / 0.5418 GB
Notification: Performance of sort global known variables: time 8.82e-07/6.786, allocations: 0 / 1.738 GB, free: 81.91 MB / 0.5418 GB
Notification: Performance of remove unused functions: time 0.01708/6.803, allocations: 3.468 MB / 1.741 GB, free: 81.16 MB / 0.5418 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 1 (idealClosingSwitch.idealClosingSwitch[1].pin_p.reference.gamma)
 * Number of discrete variables: 12 (booleanStep1[1].y,booleanStep1[2].y,booleanStep1[3].y,booleanStep2[1].y,booleanStep2[2].y,booleanStep2[3].y,booleanStep3[1].y,booleanStep3[2].y,booleanStep3[3].y,booleanStep4[1].y,booleanStep4[2].y,booleanStep4[3].y)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (565):
 * Single equations (assignments): 498
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 63
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 4 {6 9,6 9,6 9,99 312}
Notification: Performance of Backend phase and start with SimCode phase: time 0.003891/6.807, allocations: 360.4 kB / 1.741 GB, free: 80.95 MB / 0.5418 GB
Notification: Performance of simCode: created initialization part: time 0.06471/6.872, allocations: 20.18 MB / 1.761 GB, free: 73.82 MB / 0.5418 GB
Notification: Performance of simCode: created event and clocks part: time 1.101e-05/6.872, allocations: 0 / 1.761 GB, free: 73.82 MB / 0.5418 GB
Notification: Performance of simCode: created simulation system equations: time 0.0387/6.911, allocations: 11.14 MB / 1.772 GB, free: 69.77 MB / 0.5418 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01739/6.928, allocations: 1.308 MB / 1.773 GB, free: 69.35 MB / 0.5418 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.09707/7.025, allocations: 36.55 MB / 1.809 GB, free: 36.9 MB / 0.5418 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.02382/7.049, allocations: 9.65 MB / 1.818 GB, free: 29.59 MB / 0.5418 GB
Notification: Performance of simCode: alias equations: time 0.01322/7.062, allocations: 2.092 MB / 1.82 GB, free: 28.12 MB / 0.5418 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.003866/7.066, allocations: 378.7 kB / 1.821 GB, free: 27.78 MB / 0.5418 GB
Notification: Performance of SimCode: time 3.246e-06/7.066, allocations: 0 / 1.821 GB, free: 27.78 MB / 0.5418 GB
Notification: Performance of Templates: time 0.9274/7.994, allocations: 201.4 MB / 2.017 GB, free: 19.8 MB / 0.5418 GB