Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC.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.001387/0.001387, allocations: 88.72 kB / 19.44 MB, free: 356 kB / 13.93 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.00161/0.00161, allocations: 157.7 kB / 22.75 MB, free: 1.684 MB / 18.57 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.44/1.44, allocations: 177.1 MB / 203.1 MB, free: 5.59 MB / 186.7 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo): time 0.002776/0.002776, allocations: 232.7 kB / 259.8 MB, free: 12.84 MB / 250.7 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo): time 1.083/1.083, allocations: 138.4 MB / 454.7 MB, free: 1.473 MB / 362.7 MB
"
[Timeout remaining time 179]
Using package BuildingSystems with version 2.0.0-beta (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo)
Using package NcDataReader2 with version 2.5.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/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(BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC")
translateModel(BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.714e-06/1.714e-06, allocations: 0 / 0.5544 GB, free: 0.8203 MB / 474.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.701e-05/2.872e-05, allocations: 5.969 kB / 0.5544 GB, free: 0.8164 MB / 474.7 MB
Notification: Performance of NFInst.instantiate(BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC): time 0.01474/0.01477, allocations: 9.469 MB / 0.5636 GB, free: 7.305 MB / 490.7 MB
Notification: Performance of NFInst.instExpressions: time 0.006318/0.02109, allocations: 3.006 MB / 0.5665 GB, free: 4.289 MB / 490.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0008202/0.02191, allocations: 43.69 kB / 0.5666 GB, free: 4.246 MB / 490.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.002314/0.02422, allocations: 0.6879 MB / 0.5673 GB, free: 3.555 MB / 490.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.001973/0.02619, allocations: 0.7421 MB / 0.568 GB, free: 2.809 MB / 490.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001649/0.02784, allocations: 0.6431 MB / 0.5686 GB, free: 2.164 MB / 490.7 MB
Notification: Performance of NFFlatten.flatten: time 0.003754/0.0316, allocations: 2.788 MB / 0.5713 GB, free: 15.36 MB / 0.4948 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.00243/0.03403, allocations: 1.379 MB / 0.5727 GB, free: 13.94 MB / 0.4948 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002995/0.03702, allocations: 1.575 MB / 0.5742 GB, free: 12.36 MB / 0.4948 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002584/0.03961, allocations: 1.368 MB / 0.5756 GB, free: 10.99 MB / 0.4948 GB
Notification: Performance of NFPackage.collectConstants: time 0.0005913/0.0402, allocations: 216 kB / 0.5758 GB, free: 10.78 MB / 0.4948 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001321/0.04152, allocations: 471.2 kB / 0.5762 GB, free: 10.32 MB / 0.4948 GB
Notification: Performance of NFScalarize.scalarize: time 0.001676/0.04319, allocations: 1.056 MB / 0.5772 GB, free: 9.262 MB / 0.4948 GB
Notification: Performance of NFVerifyModel.verify: time 0.001267/0.04446, allocations: 0.7095 MB / 0.5779 GB, free: 8.547 MB / 0.4948 GB
Notification: Performance of NFConvertDAE.convert: time 0.004152/0.04861, allocations: 3.494 MB / 0.5813 GB, free: 5.043 MB / 0.4948 GB
Notification: Performance of FrontEnd - DAE generated: time 4.528e-06/0.04862, allocations: 0 / 0.5813 GB, free: 5.043 MB / 0.4948 GB
Notification: Performance of FrontEnd: time 1.242e-06/0.04862, allocations: 0 / 0.5813 GB, free: 5.043 MB / 0.4948 GB
Notification: Performance of Transformations before backend: time 2.934e-05/0.04865, allocations: 4 kB / 0.5813 GB, free: 5.039 MB / 0.4948 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 419
 * Number of variables: 419
Notification: Performance of Generate backend data structure: time 0.003706/0.05235, allocations: 1.893 MB / 0.5832 GB, free: 3.105 MB / 0.4948 GB
Notification: Performance of prepare preOptimizeDAE: time 4.052e-05/0.05239, allocations: 12.69 kB / 0.5832 GB, free: 3.094 MB / 0.4948 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006244/0.05864, allocations: 1.205 MB / 0.5844 GB, free: 1.887 MB / 0.4948 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0023/0.06094, allocations: 1.174 MB / 0.5855 GB, free: 0.6836 MB / 0.4948 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001091/0.06105, allocations: 111.3 kB / 0.5856 GB, free: 0.5742 MB / 0.4948 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000317/0.06137, allocations: 144.4 kB / 0.5858 GB, free: 444 kB / 0.4948 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0053/0.06667, allocations: 3.089 MB / 0.5888 GB, free: 13.23 MB / 0.5105 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.301e-05/0.06671, allocations: 12.12 kB / 0.5888 GB, free: 13.21 MB / 0.5105 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002059/0.06691, allocations: 64.34 kB / 0.5889 GB, free: 13.15 MB / 0.5105 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002661/0.06718, allocations: 178.9 kB / 0.589 GB, free: 12.98 MB / 0.5105 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004627/0.07181, allocations: 2.596 MB / 0.5916 GB, free: 10.38 MB / 0.5105 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.01543/0.08724, allocations: 9.088 MB / 0.6004 GB, free: 1.07 MB / 0.5105 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.2047/0.292, allocations: 1.215 MB / 0.6016 GB, free: 99.03 MB / 0.5105 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001342/0.2933, allocations: 0.9831 MB / 0.6026 GB, free: 99.02 MB / 0.5105 GB
Notification: Performance of preOpt evalFunc (simulation): time 6.421e-05/0.2934, allocations: 14.22 kB / 0.6026 GB, free: 99.02 MB / 0.5105 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.222e-05/0.2934, allocations: 44.44 kB / 0.6026 GB, free: 98.98 MB / 0.5105 GB
Notification: Performance of pre-optimization done (n=82): time 2.906e-06/0.2934, allocations: 0 / 0.6026 GB, free: 98.98 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=139): time 0.07108/0.3645, allocations: 18.89 MB / 0.6211 GB, free: 96.24 MB / 0.5105 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.494e-05/0.3645, allocations: 66.09 kB / 0.6212 GB, free: 96.18 MB / 0.5105 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001222/0.3658, allocations: 0.9219 MB / 0.6221 GB, free: 95.8 MB / 0.5105 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001504/0.3659, allocations: 46.52 kB / 0.6221 GB, free: 95.76 MB / 0.5105 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000383/0.3663, allocations: 0.7465 MB / 0.6228 GB, free: 95.28 MB / 0.5105 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003808/0.3667, allocations: 0.4929 MB / 0.6233 GB, free: 94.92 MB / 0.5105 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005832/0.3673, allocations: 289.4 kB / 0.6236 GB, free: 94.79 MB / 0.5105 GB
Notification: Performance of setup shared object (initialization): time 8.497e-05/0.3673, allocations: 352.4 kB / 0.6239 GB, free: 94.46 MB / 0.5105 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001786/0.3691, allocations: 1.337 MB / 0.6252 GB, free: 94.02 MB / 0.5105 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001853/0.371, allocations: 1.656 MB / 0.6269 GB, free: 92.98 MB / 0.5105 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003477/0.3745, allocations: 3.092 MB / 0.6299 GB, free: 91.18 MB / 0.5105 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.222e-06/0.3745, allocations: 4.547 kB / 0.6299 GB, free: 91.18 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=191) (initialization): time 0.01042/0.3849, allocations: 5.619 MB / 0.6354 GB, free: 87.1 MB / 0.5105 GB
Notification: Performance of prepare postOptimizeDAE: time 2.59e-05/0.3849, allocations: 11.94 kB / 0.6354 GB, free: 87.09 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.682e-05/0.3849, allocations: 8 kB / 0.6354 GB, free: 87.08 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008872/0.3938, allocations: 2.84 MB / 0.6382 GB, free: 84.24 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007394/0.3945, allocations: 191.7 kB / 0.6383 GB, free: 84.05 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02852/0.4231, allocations: 24.93 MB / 0.6627 GB, free: 58.76 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001382/0.4244, allocations: 95.95 kB / 0.6628 GB, free: 58.66 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001323/0.4246, allocations: 56 kB / 0.6628 GB, free: 58.61 MB / 0.5105 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 39
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (99):
 * Single equations (assignments): 95
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 4 systems
   {(6,12,100.0%), (6,12,100.0%), (6,24,100.0%), (6,24,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0009777/0.4256, allocations: 0.7316 MB / 0.6635 GB, free: 57.87 MB / 0.5105 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003072/0.4259, allocations: 159.9 kB / 0.6637 GB, free: 57.71 MB / 0.5105 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.009669/0.4355, allocations: 5.318 MB / 0.6689 GB, free: 52.36 MB / 0.5105 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.544e-06/0.4355, allocations: 12 kB / 0.6689 GB, free: 52.35 MB / 0.5105 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.631e-05/0.4356, allocations: 20.62 kB / 0.6689 GB, free: 52.33 MB / 0.5105 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.01e-05/0.4356, allocations: 7.969 kB / 0.6689 GB, free: 52.32 MB / 0.5105 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01296/0.4485, allocations: 7.973 MB / 0.6767 GB, free: 44.29 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.315e-06/0.4485, allocations: 4 kB / 0.6767 GB, free: 44.28 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.000432/0.449, allocations: 117.8 kB / 0.6768 GB, free: 44.17 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.008596/0.4576, allocations: 2.688 MB / 0.6795 GB, free: 41.48 MB / 0.5105 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001777/0.4577, allocations: 24 kB / 0.6795 GB, free: 41.45 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02732/0.4851, allocations: 23.4 MB / 0.7023 GB, free: 17.67 MB / 0.5105 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.378e-06/0.4851, allocations: 7.984 kB / 0.7023 GB, free: 17.66 MB / 0.5105 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.009383/0.4944, allocations: 5.215 MB / 0.7074 GB, free: 12.37 MB / 0.5105 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.000884/0.4953, allocations: 423.1 kB / 0.7078 GB, free: 11.95 MB / 0.5105 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002723/0.4956, allocations: 31.94 kB / 0.7079 GB, free: 11.92 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009753/0.4966, allocations: 71.91 kB / 0.7079 GB, free: 11.85 MB / 0.5105 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005155/0.4971, allocations: 132.9 kB / 0.7081 GB, free: 11.72 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001077/0.4972, allocations: 36 kB / 0.7081 GB, free: 11.69 MB / 0.5105 GB
Notification: Performance of sorting global known variables: time 0.0007039/0.4979, allocations: 0.6278 MB / 0.7087 GB, free: 11.06 MB / 0.5105 GB
Notification: Performance of sort global known variables: time 8.1e-08/0.4979, allocations: 0 / 0.7087 GB, free: 11.06 MB / 0.5105 GB
Notification: Performance of remove unused functions: time 0.002272/0.5002, allocations: 0.7227 MB / 0.7094 GB, free: 10.34 MB / 0.5105 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 16
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (62):
 * Single equations (assignments): 58
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 4 systems
   {(6,12,100.0%), (6,24,100.0%), (6,6,100.0%), (10,14,84.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0002316/0.5004, allocations: 139.7 kB / 0.7095 GB, free: 10.19 MB / 0.5105 GB
Notification: Performance of simCode: created initialization part: time 0.005834/0.5062, allocations: 4.078 MB / 0.7135 GB, free: 6.07 MB / 0.5105 GB
Notification: Performance of simCode: created event and clocks part: time 5.791e-06/0.5063, allocations: 0 / 0.7135 GB, free: 6.07 MB / 0.5105 GB
Notification: Performance of simCode: created simulation system equations: time 0.00435/0.5106, allocations: 2.617 MB / 0.7161 GB, free: 3.41 MB / 0.5105 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001371/0.512, allocations: 221 kB / 0.7163 GB, free: 3.219 MB / 0.5105 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006218/0.5182, allocations: 4.107 MB / 0.7203 GB, free: 15.07 MB / 0.5261 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001888/0.5201, allocations: 1.117 MB / 0.7214 GB, free: 13.91 MB / 0.5261 GB
Notification: Performance of simCode: alias equations: time 0.0008501/0.5209, allocations: 320 kB / 0.7217 GB, free: 13.61 MB / 0.5261 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002289/0.5212, allocations: 56.48 kB / 0.7218 GB, free: 13.55 MB / 0.5261 GB
Notification: Performance of SimCode: time 1.323e-06/0.5212, allocations: 0 / 0.7218 GB, free: 13.55 MB / 0.5261 GB
Notification: Performance of Templates: time 0.0693/0.5905, allocations: 42.02 MB / 0.7628 GB, free: 3.953 MB / 0.5573 GB
"
[Timeout remaining time 659]
make -j1 -f BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC.makefile [Timeout 660]
(rm -f BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC.pipe ; mkfifo BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC.pipe ; head -c 1048576 < BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC.pipe >> ../files/BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC.sim & ./BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC  -abortSlowSimulation -alarm=480 -lv LOG_STATS > BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLineMatrix_RLC.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 6.732787729008123]