Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo", uses=false)
Using package OpenIPSL with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo)
Using package Modelica_Synchronous with version 0.93.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo)
Using package ModelicaServices with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+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 Complex with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Running command: "" <> buildModelFMU(OpenIPSL.Examples.IEEE9.IEEE_9_Buses,fileNamePrefix="OpenIPSL_2_0_0_OpenIPSL_Examples_IEEE9_IEEE_9_Buses",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Examples.IEEE9.IEEE_9_Buses,fileNamePrefix="OpenIPSL_2_0_0_OpenIPSL_Examples_IEEE9_IEEE_9_Buses",fmuType="me",version="2.0",platforms={"static"})
[<interactive>:1:1-1:32:writable] Error: Class GC_set_max_heap_size not found in scope <global scope> (looking for a function or record).
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002199/0.002199, allocations: 182.9 kB / 7.461 MB, free: 2.5 MB / 5.871 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.065/1.065, allocations: 205.2 MB / 213.1 MB, free: 13.91 MB / 186.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.0008678/0.0008678, allocations: 96.3 kB / 261.1 MB, free: 13.54 MB / 218.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo): time 0.05922/0.05922, allocations: 12.19 MB / 321.2 MB, free: 1.293 MB / 266.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.2692/0.2692, allocations: 78.05 MB / 449.9 MB, free: 3.656 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.2274/0.4966, allocations: 76.09 MB / 0.5137 GB, free: 30.34 MB / 330.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1124/0.609, allocations: 69.23 MB / 0.5813 GB, free: 9.211 MB / 378.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 0.3034/0.9125, allocations: 103.1 MB / 0.682 GB, free: 5.98 MB / 426.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0001735/0.9127, allocations: 74.27 kB / 0.682 GB, free: 5.941 MB / 426.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 457
 * Number of variables: 457
Notification: Performance of Generate backend data structure: time 0.1888/1.102, allocations: 71.76 MB / 0.7521 GB, free: 8.969 MB / 490.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.111e-05/1.102, allocations: 11.94 kB / 0.7521 GB, free: 8.957 MB / 490.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.007901/1.11, allocations: 1.221 MB / 0.7533 GB, free: 7.73 MB / 490.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004687/1.114, allocations: 1.753 MB / 0.755 GB, free: 5.914 MB / 490.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004766/1.115, allocations: 404.5 kB / 0.7554 GB, free: 5.457 MB / 490.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005528/1.115, allocations: 119.9 kB / 0.7555 GB, free: 5.34 MB / 490.7 MB
Notification: Performance of preOpt removeEqualFunctionCalls (simulation): time 0.003763/1.119, allocations: 1.87 MB / 0.7573 GB, free: 3.469 MB / 490.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003604/1.123, allocations: 1.901 MB / 0.7592 GB, free: 1.539 MB / 490.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.936e-05/1.123, allocations: 3.938 kB / 0.7592 GB, free: 1.535 MB / 490.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001833/1.123, allocations: 0 / 0.7592 GB, free: 1.535 MB / 490.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.639e-05/1.123, allocations: 44 kB / 0.7592 GB, free: 1.492 MB / 490.7 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.01733/1.14, allocations: 8.704 MB / 0.7677 GB, free: 8.477 MB / 0.4948 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002641/1.143, allocations: 1.427 MB / 0.7691 GB, free: 7.016 MB / 0.4948 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001892/1.145, allocations: 1.235 MB / 0.7703 GB, free: 5.773 MB / 0.4948 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002089/1.145, allocations: 41.95 kB / 0.7704 GB, free: 5.734 MB / 0.4948 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002268/1.147, allocations: 1.411 MB / 0.7718 GB, free: 4.301 MB / 0.4948 GB
Notification: Performance of pre-optimization done (n=236): time 4.248e-06/1.147, allocations: 0 / 0.7718 GB, free: 4.301 MB / 0.4948 GB
Notification: Performance of matching and sorting (n=236): time 0.02359/1.171, allocations: 7.486 MB / 0.7791 GB, free: 12.79 MB / 0.5105 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001094/1.171, allocations: 235.6 kB / 0.7793 GB, free: 12.51 MB / 0.5105 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003155/1.174, allocations: 1.867 MB / 0.7811 GB, free: 10.63 MB / 0.5105 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005882/1.175, allocations: 25.7 kB / 0.7811 GB, free: 10.6 MB / 0.5105 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004686/1.175, allocations: 0.6381 MB / 0.7818 GB, free: 9.949 MB / 0.5105 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001441/1.177, allocations: 0.7851 MB / 0.7825 GB, free: 9.152 MB / 0.5105 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001633/1.177, allocations: 0 / 0.7825 GB, free: 9.152 MB / 0.5105 GB
Notification: Performance of setup shared object (initialization): time 0.0001579/1.177, allocations: 398.9 kB / 0.7829 GB, free: 8.758 MB / 0.5105 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001823/1.179, allocations: 1.028 MB / 0.7839 GB, free: 7.727 MB / 0.5105 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001933/1.181, allocations: 1.275 MB / 0.7852 GB, free: 6.379 MB / 0.5105 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.00198/1.183, allocations: 1.15 MB / 0.7863 GB, free: 5.199 MB / 0.5105 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.047e-06/1.183, allocations: 4 kB / 0.7863 GB, free: 5.195 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=311) (initialization): time 0.02013/1.203, allocations: 6.716 MB / 0.7928 GB, free: 14.41 MB / 0.5261 GB
Notification: Performance of prepare postOptimizeDAE: time 2.851e-05/1.203, allocations: 4 kB / 0.7928 GB, free: 14.41 MB / 0.5261 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.213e-05/1.203, allocations: 8 kB / 0.7929 GB, free: 14.4 MB / 0.5261 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01127/1.215, allocations: 2.795 MB / 0.7956 GB, free: 11.58 MB / 0.5261 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002843/1.217, allocations: 471.4 kB / 0.796 GB, free: 11.12 MB / 0.5261 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02812/1.246, allocations: 15.23 MB / 0.8109 GB, free: 11.29 MB / 0.5417 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003977/1.25, allocations: 370 kB / 0.8113 GB, free: 10.92 MB / 0.5417 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005842/1.25, allocations: 88.03 kB / 0.8113 GB, free: 10.84 MB / 0.5417 GB
Warning: Assuming fixed start value for the following 9 variables:
         gen3.gen.w:VARIABLE(start = 1.0 )  "Rotor speed [pu]" type: Real 
         gen2.gen.w:VARIABLE(start = 1.0 )  "Rotor speed [pu]" type: Real 
         gen1.gen.w:VARIABLE(start = 1.0 )  "Rotor speed [pu]" type: Real 
         gen3.AVR.firstOrder2.y:VARIABLE(start = gen3.AVR.firstOrder2.y_start )  "Connector of Real output signal" type: Real 
         gen3.gen.e1d:VARIABLE(start = gen3.gen.e1d0 unit = "1" )  "d-axis transient voltage" type: Real 
         gen3.gen.e1q:VARIABLE(start = gen3.gen.e1q0 unit = "1" )  "q-axis transient voltage" type: Real 
         gen3.gen.delta:VARIABLE(start = gen3.gen.delta0 unit = "rad" )  "Rotor angle" type: Real 
         gen2.AVR.firstOrder2.y:VARIABLE(start = gen2.AVR.firstOrder2.y_start )  "Connector of Real output signal" type: Real 
         gen2.gen.e1d:VARIABLE(start = gen2.gen.e1d0 unit = "1" )  "d-axis transient voltage" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 22
 * Number of states: 0 ()
 * Number of discrete variables: 6 ($whenCondition5,$whenCondition6,$whenCondition3,$whenCondition4,$whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (237):
 * Single equations (assignments): 224
 * Array equations: 0
 * Algorithm blocks: 6
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 2
 * Torn equation systems: 5
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 2 {(2,100.0%),(2,100.0%)}
 * Non-linear Jacobian: 0
 * Without analytic Jacobian: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(1,100.0%) 1,(1,100.0%) 1}
 * Non-linear torn systems: 3 {4 2,30 32,1 4}
Notification: Performance of prepare postOptimizeDAE: time 0.0006432/1.251, allocations: 239.9 kB / 0.8116 GB, free: 10.58 MB / 0.5417 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008648/1.252, allocations: 292.3 kB / 0.8119 GB, free: 10.29 MB / 0.5417 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02348/1.275, allocations: 7.692 MB / 0.8194 GB, free: 2.559 MB / 0.5417 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.237e-05/1.275, allocations: 24 kB / 0.8194 GB, free: 2.535 MB / 0.5417 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.903e-06/1.275, allocations: 3.938 kB / 0.8194 GB, free: 2.531 MB / 0.5417 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.605e-05/1.275, allocations: 11.95 kB / 0.8194 GB, free: 2.52 MB / 0.5417 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02875/1.304, allocations: 11.21 MB / 0.8303 GB, free: 7.16 MB / 0.5573 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.985e-05/1.304, allocations: 0 / 0.8303 GB, free: 7.16 MB / 0.5573 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00171/1.306, allocations: 235.7 kB / 0.8306 GB, free: 6.93 MB / 0.5573 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01156/1.317, allocations: 3.324 MB / 0.8338 GB, free: 3.59 MB / 0.5573 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000565/1.318, allocations: 27.89 kB / 0.8338 GB, free: 3.562 MB / 0.5573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.03134/1.349, allocations: 16.04 MB / 0.8495 GB, free: 3.004 MB / 0.573 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.46e-06/1.349, allocations: 4 kB / 0.8495 GB, free: 3 MB / 0.573 GB
Notification: Performance of postOpt detectJacobianSparsePattern (simulation): time 0.1818/1.531, allocations: 9.356 MB / 0.8586 GB, free: 218 MB / 0.573 GB
Notification: Performance of postOpt generateSymbolicLinearization (simulation): time 0.02808/1.559, allocations: 11.4 MB / 0.8698 GB, free: 217.2 MB / 0.573 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001171/1.561, allocations: 438.6 kB / 0.8702 GB, free: 217 MB / 0.573 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0007822/1.561, allocations: 7.094 kB / 0.8702 GB, free: 217 MB / 0.573 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002618/1.564, allocations: 254.5 kB / 0.8704 GB, free: 217 MB / 0.573 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005441/1.565, allocations: 227 kB / 0.8707 GB, free: 217 MB / 0.573 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001913/1.565, allocations: 70.05 kB / 0.8707 GB, free: 217 MB / 0.573 GB
Notification: Performance of sort global known variables: time 0.00191/1.567, allocations: 1.388 MB / 0.8721 GB, free: 216.9 MB / 0.573 GB
Notification: Performance of remove unused functions: time 0.003465/1.57, allocations: 0.8403 MB / 0.8729 GB, free: 216.8 MB / 0.573 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 4
 * Number of states: 24 (gen1.gen.delta,gen1.gen.w,gen1.gen.e1q,gen1.gen.e1d,gen1.AVR.ExcitationSystem.x_scaled[1],gen1.AVR.derivativeBlock.x,gen1.AVR.firstOrder2.y,gen1.AVR.simpleLagLim.state,gen2.gen.delta,gen2.gen.w,gen2.gen.e1q,gen2.gen.e1d,gen2.AVR.ExcitationSystem.x_scaled[1],gen2.AVR.derivativeBlock.x,gen2.AVR.firstOrder2.y,gen2.AVR.simpleLagLim.state,gen3.gen.delta,gen3.gen.w,gen3.gen.e1q,gen3.gen.e1d,gen3.AVR.ExcitationSystem.x_scaled[1],gen3.AVR.derivativeBlock.x,gen3.AVR.firstOrder2.y,gen3.AVR.simpleLagLim.state)
 * Number of discrete variables: 6 ($whenCondition5,$whenCondition6,$whenCondition3,$whenCondition4,$whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (142):
 * Single equations (assignments): 135
 * Array equations: 0
 * Algorithm blocks: 3
 * Record equations: 0
 * 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 {41 45,1 6,1 6,1 6}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0004275/1.571, allocations: 153.6 kB / 0.8731 GB, free: 216.8 MB / 0.573 GB
Notification: Performance of simCode: created initialization part: time 0.009242/1.58, allocations: 4.599 MB / 0.8775 GB, free: 215.8 MB / 0.573 GB
Notification: Performance of simCode: created event and clocks part: time 7.504e-06/1.58, allocations: 0 / 0.8775 GB, free: 215.8 MB / 0.573 GB
Notification: Performance of simCode: created simulation system equations: time 0.008148/1.588, allocations: 3.168 MB / 0.8806 GB, free: 215.2 MB / 0.573 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001929/1.59, allocations: 207.6 kB / 0.8808 GB, free: 215.2 MB / 0.573 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01107/1.601, allocations: 4.371 MB / 0.8851 GB, free: 213.9 MB / 0.573 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001841/1.603, allocations: 1.999 MB / 0.8871 GB, free: 213.1 MB / 0.573 GB
Notification: Performance of SimCode: time 7.62e-07/1.603, allocations: 0 / 0.8871 GB, free: 213.1 MB / 0.573 GB
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.sim & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator  --tempDir=temp_OpenIPSL_2_0_0_OpenIPSL_Examples_IEEE9_IEEE_9_Buses_fmu --startTime=0 --stopTime=20 --timeout=50 --tolerance=1e-06 OpenIPSL_2_0_0_OpenIPSL_Examples_IEEE9_IEEE_9_Buses.fmu > OpenIPSL_2.0.0_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.pipe 2>&1)