Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Tests.ThreePhase.IEEE4.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo): time 0.08271/0.08271, allocations: 12.77 MB / 29.12 MB, free: 220 kB / 19.63 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001317/0.001317, allocations: 119.5 kB / 34.91 MB, free: 1.199 MB / 26.18 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.389/1.389, allocations: 207.6 MB / 248.2 MB, free: 10.22 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.002075/0.002075, allocations: 188.4 kB / 303.1 MB, free: 5.578 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.4407/0.4407, allocations: 79.71 MB / 437.6 MB, free: 3.961 MB / 302.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Using package Modelica_Synchronous with version 0.93.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo)
Running command: "" <> buildModelFMU(OpenIPSL.Tests.ThreePhase.IEEE4,fileNamePrefix="OpenIPSL_2_0_0_OpenIPSL_Tests_ThreePhase_IEEE4",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Tests.ThreePhase.IEEE4,fileNamePrefix="OpenIPSL_2_0_0_OpenIPSL_Tests_ThreePhase_IEEE4",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Modelica_Synchronous requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: OpenIPSL requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.003863/0.003863, allocations: 147.8 kB / 0.5827 GB, free: 7.602 MB / 382.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1465/0.1503, allocations: 72.01 MB / 0.6531 GB, free: 15.5 MB / 462.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.ThreePhase.IEEE4): time 0.006676/0.157, allocations: 5.564 MB / 0.6585 GB, free: 9.898 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.006063/0.1631, allocations: 2.894 MB / 0.6613 GB, free: 6.992 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0008933/0.164, allocations: 8 kB / 0.6613 GB, free: 6.984 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0005801/0.1646, allocations: 174.5 kB / 0.6615 GB, free: 6.812 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006246/0.1708, allocations: 3.952 MB / 0.6654 GB, free: 2.852 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0004454/0.1712, allocations: 155.8 kB / 0.6655 GB, free: 2.699 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002158/0.1734, allocations: 1.453 MB / 0.6669 GB, free: 1.238 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006094/0.174, allocations: 317.9 kB / 0.6672 GB, free: 0.9102 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001065/0.1751, allocations: 0.6931 MB / 0.6679 GB, free: 220 kB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001949/0.177, allocations: 1.883 MB / 0.6697 GB, free: 14.33 MB / 478.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004954/0.1775, allocations: 244 kB / 0.67 GB, free: 14.09 MB / 478.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.01369/0.1912, allocations: 12.31 MB / 0.682 GB, free: 1.758 MB / 478.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001324/0.1925, allocations: 1.118 MB / 0.6831 GB, free: 0.6367 MB / 478.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001741/0.1943, allocations: 1.473 MB / 0.6845 GB, free: 15.15 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01264/0.2069, allocations: 9.957 MB / 0.6943 GB, free: 5.172 MB / 494.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.281e-06/0.2069, allocations: 0 / 0.6943 GB, free: 5.172 MB / 494.1 MB
Notification: Performance of FrontEnd: time 1.613e-06/0.2069, allocations: 4 kB / 0.6943 GB, free: 5.168 MB / 494.1 MB
Notification: Performance of Transformations before backend: time 8.893e-05/0.207, allocations: 0 / 0.6943 GB, free: 5.168 MB / 494.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 291
 * Number of variables: 291
Notification: Performance of Generate backend data structure: time 0.0109/0.2179, allocations: 4.353 MB / 0.6985 GB, free: 0.75 MB / 494.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.054e-05/0.218, allocations: 15.98 kB / 0.6985 GB, free: 0.7344 MB / 494.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0003458/0.2183, allocations: 173.4 kB / 0.6987 GB, free: 0.5547 MB / 494.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0006651/0.219, allocations: 306.9 kB / 0.699 GB, free: 260 kB / 494.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.2917/0.5107, allocations: 2.232 MB / 0.7012 GB, free: 40.51 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001075/0.5108, allocations: 62.66 kB / 0.7012 GB, free: 40.51 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006057/0.5114, allocations: 80.84 kB / 0.7013 GB, free: 40.51 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004235/0.5157, allocations: 2.677 MB / 0.7039 GB, free: 40.2 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.459e-05/0.5157, allocations: 1.312 kB / 0.7039 GB, free: 40.2 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001482/0.5158, allocations: 35.95 kB / 0.7039 GB, free: 40.17 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002206/0.5161, allocations: 160.8 kB / 0.7041 GB, free: 40.16 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.009164/0.5252, allocations: 5.12 MB / 0.7091 GB, free: 39.12 MB / 494.1 MB
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.02025/0.5455, allocations: 11.32 MB / 0.7201 GB, free: 33.52 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002503/0.548, allocations: 1.85 MB / 0.722 GB, free: 32.51 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001665/0.5496, allocations: 1.32 MB / 0.7232 GB, free: 31.93 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.000128/0.5498, allocations: 14.28 kB / 0.7233 GB, free: 31.93 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.187e-05/0.5498, allocations: 38.91 kB / 0.7233 GB, free: 31.88 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=84): time 3.176e-06/0.5498, allocations: 0 / 0.7233 GB, free: 31.88 MB / 494.1 MB
Notification: Performance of matching and sorting (n=84): time 0.0306/0.5804, allocations: 12.41 MB / 0.7354 GB, free: 22.61 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.457e-05/0.5804, allocations: 60.06 kB / 0.7355 GB, free: 22.54 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004961/0.5854, allocations: 3.981 MB / 0.7394 GB, free: 18.55 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0003913/0.5858, allocations: 37.7 kB / 0.7394 GB, free: 18.51 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0007083/0.5865, allocations: 1.247 MB / 0.7406 GB, free: 17.32 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003801/0.5869, allocations: 303.1 kB / 0.7409 GB, free: 17.07 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004131/0.5873, allocations: 198.4 kB / 0.7411 GB, free: 16.88 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 6.108e-05/0.5873, allocations: 341.9 kB / 0.7414 GB, free: 16.54 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001297/0.5886, allocations: 1.334 MB / 0.7427 GB, free: 15.2 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001342/0.59, allocations: 1.539 MB / 0.7442 GB, free: 13.59 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002663/0.5926, allocations: 2.941 MB / 0.7471 GB, free: 10.57 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.476e-06/0.5927, allocations: 4 kB / 0.7471 GB, free: 10.57 MB / 494.1 MB
Notification: Performance of matching and sorting (n=120) (initialization): time 0.01118/0.6038, allocations: 6.303 MB / 0.7533 GB, free: 4.246 MB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.481e-05/0.6039, allocations: 12 kB / 0.7533 GB, free: 4.234 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.827e-05/0.6039, allocations: 8 kB / 0.7533 GB, free: 4.227 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01703/0.6209, allocations: 5.889 MB / 0.759 GB, free: 14.33 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006493/0.6216, allocations: 119.8 kB / 0.7591 GB, free: 14.21 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00974/0.6313, allocations: 5.217 MB / 0.7642 GB, free: 8.98 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002198/0.6335, allocations: 168.6 kB / 0.7644 GB, free: 8.836 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002589/0.6338, allocations: 43.92 kB / 0.7644 GB, free: 8.793 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 25
 * 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 (67):
 * Single equations (assignments): 66
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * 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): 1 system
   {(26,26)}
Notification: Performance of prepare postOptimizeDAE: time 0.001483/0.6352, allocations: 0.7501 MB / 0.7652 GB, free: 8.039 MB / 0.4981 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000932/0.6362, allocations: 262.2 kB / 0.7654 GB, free: 7.781 MB / 0.4981 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01537/0.6515, allocations: 6.831 MB / 0.7721 GB, free: 0.9297 MB / 0.4981 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.399e-05/0.6516, allocations: 12 kB / 0.7721 GB, free: 0.918 MB / 0.4981 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.049e-05/0.6516, allocations: 4 kB / 0.7721 GB, free: 0.9141 MB / 0.4981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.055e-05/0.6516, allocations: 3.984 kB / 0.7721 GB, free: 0.9102 MB / 0.4981 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0227/0.6743, allocations: 10.3 MB / 0.7822 GB, free: 6.551 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.35e-05/0.6743, allocations: 0 / 0.7822 GB, free: 6.551 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004233/0.6747, allocations: 55.95 kB / 0.7822 GB, free: 6.496 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01854/0.6933, allocations: 5.475 MB / 0.7876 GB, free: 1.008 MB / 0.5137 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0004501/0.6937, allocations: 15.88 kB / 0.7876 GB, free: 0.9961 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.009569/0.7033, allocations: 5.188 MB / 0.7926 GB, free: 11.79 MB / 0.5294 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.055e-06/0.7033, allocations: 0 / 0.7926 GB, free: 11.79 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01674/0.72, allocations: 6.927 MB / 0.7994 GB, free: 4.828 MB / 0.5294 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001856/0.7219, allocations: 0.6306 MB / 0.8 GB, free: 4.184 MB / 0.5294 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001216/0.7231, allocations: 15.94 kB / 0.8 GB, free: 4.168 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002085/0.7252, allocations: 99.89 kB / 0.8001 GB, free: 4.07 MB / 0.5294 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004451/0.7256, allocations: 40.59 kB / 0.8002 GB, free: 4.031 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002093/0.7258, allocations: 23.98 kB / 0.8002 GB, free: 4.008 MB / 0.5294 GB
Notification: Performance of sorting global known variables: time 0.004877/0.7307, allocations: 2.88 MB / 0.803 GB, free: 1.125 MB / 0.5294 GB
Notification: Performance of sort global known variables: time 9e-08/0.7307, allocations: 3.938 kB / 0.803 GB, free: 1.121 MB / 0.5294 GB
Notification: Performance of remove unused functions: time 0.004425/0.7351, allocations: 1.198 MB / 0.8042 GB, free: 15.92 MB / 0.545 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * 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 (31):
 * Single equations (assignments): 30
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * 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): 1 system
   {(26,26)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.02001/0.7552, allocations: 7.843 MB / 0.8118 GB, free: 8.035 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.008204/0.7634, allocations: 3.718 MB / 0.8155 GB, free: 4.324 MB / 0.545 GB
Notification: Performance of simCode: created event and clocks part: time 3.907e-06/0.7634, allocations: 448 / 0.8155 GB, free: 4.324 MB / 0.545 GB
Notification: Performance of simCode: created simulation system equations: time 0.005691/0.7691, allocations: 2.235 MB / 0.8177 GB, free: 2.09 MB / 0.545 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001714/0.7708, allocations: 192.3 kB / 0.8178 GB, free: 1.902 MB / 0.545 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.06068/0.8314, allocations: 31.19 MB / 0.8483 GB, free: 2.422 MB / 0.5762 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001812/0.8333, allocations: 1.716 MB / 0.85 GB, free: 0.6758 MB / 0.5762 GB
Notification: Performance of simCode: alias equations: time 0.001304/0.8346, allocations: 441.8 kB / 0.8504 GB, free: 248 kB / 0.5762 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001677/0.8362, allocations: 1.011 MB / 0.8514 GB, free: 15.23 MB / 0.5919 GB
Notification: Performance of SimCode: time 1.553e-06/0.8362, allocations: 0 / 0.8514 GB, free: 15.23 MB / 0.5919 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1525/0.9887, allocations: 79.78 MB / 0.9293 GB, free: 15.52 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate platform static: time 17.15/18.14, allocations: 4.875 kB / 0.9293 GB, free: 15.52 MB / 0.67 GB
"
[Timeout remaining time 642]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Tests.ThreePhase.IEEE4.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Tests.ThreePhase.IEEE4.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Tests.ThreePhase.IEEE4.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Tests.ThreePhase.IEEE4.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_OpenIPSL_2_0_0_OpenIPSL_Tests_ThreePhase_IEEE4_fmu --startTime=0 --stopTime=0.1 --stepSize=0.0002 --timeout=50 --tolerance=1e-06 OpenIPSL_2_0_0_OpenIPSL_Tests_ThreePhase_IEEE4.fmu > OpenIPSL_2.0.0_OpenIPSL.Tests.ThreePhase.IEEE4.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 21.469846399966627]
Failed to read output from testmodel.py, exit status != 0:
18.262828398961574 18.284201609 18.073360644
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f336b5a6020>