Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001642/0.001642, allocations: 106.5 kB / 21.22 MB, free: 0.6797 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.002046/0.002046, allocations: 208.6 kB / 24.54 MB, free: 4.055 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.547/1.547, allocations: 230.3 MB / 258 MB, free: 8.23 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo): time 0.2391/0.2391, allocations: 46.94 MB / 361.4 MB, free: 4.418 MB / 286.1 MB
"
[Timeout remaining time 179]
Using package ModelicaTest with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo)
Using package Modelica with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo)
Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
Running command: translateModel(ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5006,variableFilter="time|I.1..re|I.1..im|I.2..re|I.2..im|I.3..re|I.3..im",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal")
translateModel(ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5006,variableFilter="time|I.1..re|I.1..im|I.2..re|I.2..im|I.3..re|I.3..im",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal") [Timeout 660]
"Warning: Requested package Modelica of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Notification: Performance of FrontEnd - loaded program: time 0.002608/0.002608, allocations: 79.86 kB / 0.4972 GB, free: 7.535 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3494/0.352, allocations: 66.04 MB / 0.5617 GB, free: 4.547 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal): time 0.02773/0.3797, allocations: 22.87 MB / 0.584 GB, free: 13.58 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01395/0.3936, allocations: 5.549 MB / 0.5894 GB, free: 8.016 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.006263/0.3999, allocations: 230.8 kB / 0.5896 GB, free: 7.789 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.02362/0.4235, allocations: 9.585 MB / 0.599 GB, free: 14.18 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0103/0.4338, allocations: 2.214 MB / 0.6012 GB, free: 11.96 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.007043/0.4409, allocations: 1.941 MB / 0.603 GB, free: 10.04 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02215/0.463, allocations: 14.54 MB / 0.6172 GB, free: 11.44 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.04661/0.5096, allocations: 20.06 MB / 0.6368 GB, free: 7.156 MB / 478.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.01403/0.5237, allocations: 8.156 MB / 0.6448 GB, free: 14.98 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01552/0.5392, allocations: 8.803 MB / 0.6534 GB, free: 6.148 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.006556/0.5457, allocations: 2.098 MB / 0.6555 GB, free: 4.051 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.009966/0.5557, allocations: 2.351 MB / 0.6577 GB, free: 1.699 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.008381/0.5641, allocations: 4.333 MB / 0.662 GB, free: 13.36 MB / 0.4981 GB
Notification: Performance of NFVerifyModel.verify: time 0.01558/0.5797, allocations: 8.424 MB / 0.6702 GB, free: 4.898 MB / 0.4981 GB
Notification: Performance of NFConvertDAE.convert: time 0.04028/0.62, allocations: 28.79 MB / 0.6983 GB, free: 8.023 MB / 0.5294 GB
Notification: Performance of FrontEnd - DAE generated: time 8.456e-06/0.62, allocations: 3.938 kB / 0.6983 GB, free: 8.02 MB / 0.5294 GB
Notification: Performance of FrontEnd: time 2.314e-06/0.62, allocations: 0 / 0.6983 GB, free: 8.02 MB / 0.5294 GB
Notification: Performance of Transformations before backend: time 0.0009582/0.6209, allocations: 4 kB / 0.6983 GB, free: 8.016 MB / 0.5294 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3858
 * Number of variables: 3858
Notification: Performance of Generate backend data structure: time 0.06301/0.6839, allocations: 18.93 MB / 0.7168 GB, free: 4.98 MB / 0.545 GB
Notification: Performance of prepare preOptimizeDAE: time 4.696e-05/0.684, allocations: 8.031 kB / 0.7168 GB, free: 4.973 MB / 0.545 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06019/0.7442, allocations: 11.48 MB / 0.728 GB, free: 9.465 MB / 0.5606 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0241/0.7683, allocations: 9.048 MB / 0.7369 GB, free: 328 kB / 0.5606 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.6109/1.379, allocations: 1.057 MB / 0.7379 GB, free: 131.6 MB / 0.5607 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.007856/1.387, allocations: 1.373 MB / 0.7392 GB, free: 131.6 MB / 0.5607 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.05789/1.445, allocations: 23.63 MB / 0.7623 GB, free: 123.4 MB / 0.5607 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0008526/1.446, allocations: 21.83 kB / 0.7623 GB, free: 123.4 MB / 0.5607 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00534/1.451, allocations: 0.5709 MB / 0.7629 GB, free: 122.9 MB / 0.5607 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0006972/1.452, allocations: 0.707 MB / 0.7636 GB, free: 122.8 MB / 0.5607 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0424/1.494, allocations: 21.74 MB / 0.7848 GB, free: 101.2 MB / 0.5607 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.1158/1.61, allocations: 69.74 MB / 0.8529 GB, free: 31.16 MB / 0.5607 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01747/1.627, allocations: 11.4 MB / 0.8641 GB, free: 21.18 MB / 0.5607 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007443/1.635, allocations: 4.516 MB / 0.8685 GB, free: 16.64 MB / 0.5607 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.02776/1.663, allocations: 15.27 MB / 0.8834 GB, free: 1.52 MB / 0.5607 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.197e-05/1.663, allocations: 90.05 kB / 0.8835 GB, free: 1.422 MB / 0.5607 GB
Notification: Performance of pre-optimization done (n=465): time 6.643e-06/1.663, allocations: 0 / 0.8835 GB, free: 1.422 MB / 0.5607 GB
Notification: Performance of matching and sorting (n=465): time 0.0412/1.704, allocations: 15.44 MB / 0.8985 GB, free: 1.957 MB / 0.5763 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001309/1.704, allocations: 128.6 kB / 0.8987 GB, free: 1.812 MB / 0.5763 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01338/1.717, allocations: 5.116 MB / 0.9037 GB, free: 12.76 MB / 0.5919 GB
Notification: Performance of collectPreVariables (initialization): time 0.001183/1.719, allocations: 101.7 kB / 0.9038 GB, free: 12.65 MB / 0.5919 GB
Notification: Performance of collectInitialEqns (initialization): time 0.005807/1.724, allocations: 6.1 MB / 0.9097 GB, free: 7.062 MB / 0.5919 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002905/1.727, allocations: 1.662 MB / 0.9113 GB, free: 5.387 MB / 0.5919 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004323/1.732, allocations: 1.555 MB / 0.9129 GB, free: 3.824 MB / 0.5919 GB
Notification: Performance of setup shared object (initialization): time 0.0001264/1.732, allocations: 305.1 kB / 0.9131 GB, free: 3.523 MB / 0.5919 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006845/1.739, allocations: 4.156 MB / 0.9172 GB, free: 15.36 MB / 0.6075 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01056/1.749, allocations: 7.262 MB / 0.9243 GB, free: 6.656 MB / 0.6075 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02229/1.771, allocations: 11.95 MB / 0.936 GB, free: 9.238 MB / 0.6232 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001814/1.772, allocations: 31.94 kB / 0.936 GB, free: 9.207 MB / 0.6232 GB
Notification: Performance of matching and sorting (n=961) (initialization): time 0.02919/1.801, allocations: 13.08 MB / 0.9488 GB, free: 12.1 MB / 0.6388 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001431/1.801, allocations: 56.72 kB / 0.9488 GB, free: 12.05 MB / 0.6388 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002036/1.801, allocations: 104 kB / 0.9489 GB, free: 11.95 MB / 0.6388 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.007166/1.808, allocations: 3.091 MB / 0.9519 GB, free: 8.848 MB / 0.6388 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006154/1.814, allocations: 1.599 MB / 0.9535 GB, free: 7.246 MB / 0.6388 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.000783/1.815, allocations: 342.9 kB / 0.9538 GB, free: 6.898 MB / 0.6388 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005997/1.821, allocations: 1.067 MB / 0.9549 GB, free: 5.824 MB / 0.6388 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001249/1.822, allocations: 388 kB / 0.9552 GB, free: 5.445 MB / 0.6388 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 473
 * Number of states: 0 ()
 * Number of discrete variables: 12 (booleanStep4[3].y,booleanStep4[2].y,booleanStep4[1].y,booleanStep3[3].y,booleanStep3[2].y,booleanStep3[1].y,booleanStep2[3].y,booleanStep2[2].y,booleanStep2[1].y,booleanStep1[3].y,booleanStep1[2].y,booleanStep1[1].y)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (814):
 * Single equations (assignments): 811
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(20,30), (20,28), (20,28)}
Notification: Performance of prepare postOptimizeDAE: time 0.01253/1.835, allocations: 5.394 MB / 0.9605 GB, free: 36 kB / 0.6388 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002609/1.838, allocations: 0.5277 MB / 0.961 GB, free: 15.5 MB / 0.6544 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02377/1.861, allocations: 9.541 MB / 0.9703 GB, free: 5.93 MB / 0.6544 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.891e-05/1.861, allocations: 48 kB / 0.9704 GB, free: 5.883 MB / 0.6544 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.733e-05/1.861, allocations: 7.938 kB / 0.9704 GB, free: 5.875 MB / 0.6544 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.678e-05/1.862, allocations: 23.91 kB / 0.9704 GB, free: 5.852 MB / 0.6544 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04774/1.909, allocations: 20.87 MB / 0.9908 GB, free: 0.9023 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.745e-05/1.909, allocations: 8 kB / 0.9908 GB, free: 0.8945 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003934/1.913, allocations: 0.7371 MB / 0.9915 GB, free: 160 kB / 0.67 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.3521/2.265, allocations: 2.958 MB / 0.9944 GB, free: 220.9 MB / 0.67 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0008729/2.266, allocations: 105.6 kB / 0.9945 GB, free: 220.9 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0005495/2.267, allocations: 159.8 kB / 0.9947 GB, free: 220.9 MB / 0.67 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.813e-06/2.267, allocations: 0 / 0.9947 GB, free: 220.9 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01799/2.285, allocations: 14.19 MB / 1.009 GB, free: 216.7 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003261/2.288, allocations: 1.363 MB / 1.01 GB, free: 216.4 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001021/2.289, allocations: 78.78 kB / 1.01 GB, free: 216.3 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002749/2.292, allocations: 368.1 kB / 1.01 GB, free: 216.2 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006288/2.292, allocations: 334 kB / 1.011 GB, free: 216.2 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002784/2.293, allocations: 140.9 kB / 1.011 GB, free: 216.2 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.004749/2.297, allocations: 3.426 MB / 1.014 GB, free: 215 MB / 0.67 GB
Notification: Performance of sort global known variables: time 3.1e-07/2.297, allocations: 3.938 kB / 1.014 GB, free: 215 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.009083/2.307, allocations: 3.234 MB / 1.017 GB, free: 214.4 MB / 0.67 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 1 (voltageSource.plug_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 (357):
 * Single equations (assignments): 354
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(20,30), (20,28), (20,28)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.001195/2.308, allocations: 246.5 kB / 1.017 GB, free: 214.2 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.01717/2.325, allocations: 9.519 MB / 1.027 GB, free: 211.1 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 1.084e-05/2.325, allocations: 3.844 kB / 1.027 GB, free: 211.1 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.00746/2.332, allocations: 4.105 MB / 1.031 GB, free: 209.8 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006023/2.338, allocations: 1.276 MB / 1.032 GB, free: 209.3 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.04303/2.381, allocations: 24.55 MB / 1.056 GB, free: 188.3 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01753/2.399, allocations: 9.007 MB / 1.065 GB, free: 180.3 MB / 0.67 GB
Notification: Performance of simCode: alias equations: time 0.01116/2.41, allocations: 2.873 MB / 1.068 GB, free: 177.4 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00247/2.413, allocations: 403.3 kB / 1.068 GB, free: 177 MB / 0.67 GB
Notification: Performance of SimCode: time 2.204e-06/2.413, allocations: 0 / 1.068 GB, free: 177 MB / 0.67 GB
Notification: Performance of Templates: time 0.1719/2.585, allocations: 98.75 MB / 1.164 GB, free: 78.87 MB / 0.67 GB
"
[Timeout remaining time 657]
make -j1 -f ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal.sim & ./ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal.pipe 2>&1) [Timeout 480]
diffSimulationResults("ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/Electrical/QuasiStatic/Polyphase/Ideal/Ideal.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ModelicaTest_4.1.0-beta.om_ModelicaTest.Electrical.QuasiStatic.Polyphase.Ideal.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660]
""
[Timeout remaining time 660]
Reference file matches
[Calling sys.exit(0), Time elapsed: 10.549223388545215]