2017/06/08 17:58:12 - Kitchen - Logging is at level : Detailed logging
2017/06/08 17:58:12 - Kitchen - Start of run.
2017/06/08 17:58:12 - CargaArchivos - Start of job execution
2017/06/08 17:58:12 - CargaArchivos - exec(0, 0, START.0)
2017/06/08 17:58:12 - START - Starting job entry
2017/06/08 17:58:12 - CargaArchivos - Starting entry [Es GPL?]
2017/06/08 17:58:12 - CargaArchivos - exec(1, 0, Es GPL?.0)
2017/06/08 17:58:12 - Es GPL? - Starting job entry
2017/06/08 17:58:12 - Es GPL? - Value to evaluate is GPL
2017/06/08 17:58:12 - CargaArchivos - Starting entry [Drop Index y Vacia Tablas]
2017/06/08 17:58:12 - CargaArchivos - exec(2, 0, Drop Index y Vacia Tablas.0)
2017/06/08 17:58:12 - Es GPL? - Starting job entry
2017/06/08 17:58:12 - localPostgre - New database connection defined
2017/06/08 17:58:12 - localPostgre - Connected to database.
2017/06/08 17:58:12 - Es GPL? - Running SQL :DROP INDEX IF EXISTS partnumbergpl_idx;
2017/06/08 17:58:12 - Es GPL? - TRUNCATE TABLE global_price_list_tmp;
2017/06/08 17:58:12 - Es GPL? - update tipo_dato set validado = false, ingresado = true,
2017/06/08 17:58:12 - Es GPL? - observacion = 'A la espera de valicación de datos' where nombre = 'GPL';
2017/06/08 17:58:12 - localPostgre - launch DDL statement: 
2017/06/08 17:58:12 - localPostgre - DROP INDEX IF EXISTS partnumbergpl_idx
2017/06/08 17:58:12 - localPostgre - launch DDL statement: 
2017/06/08 17:58:12 - localPostgre - TRUNCATE TABLE global_price_list_tmp
2017/06/08 17:58:12 - localPostgre - launch DDL statement: 
2017/06/08 17:58:12 - localPostgre - update tipo_dato set validado = false, ingresado = true,
2017/06/08 17:58:12 - localPostgre - observacion = 'A la espera de valicación de datos' where nombre = 'GPL'
2017/06/08 17:58:12 - localPostgre - 3 statements executed
2017/06/08 17:58:12 - localPostgre - Connection to database closed!
2017/06/08 17:58:12 - CargaArchivos - Starting entry [CargaGPL]
2017/06/08 17:58:12 - CargaArchivos - exec(3, 0, CargaGPL.0)
2017/06/08 17:58:12 - CargaGPL - Starting job entry
2017/06/08 17:58:12 - CargaGPL - Opening transformation: [file:///home/admin_tecno/transformaciones/LecturaGPL.ktr]
2017/06/08 17:58:12 - CargaGPL - Loading transformation from XML file [file:///home/admin_tecno/transformaciones/LecturaGPL.ktr]
2017/06/08 17:58:12 - CargaGPL - Starting transformation...(file=${Internal.Job.Filename.Directory}/LecturaGPL.ktr, name=CargaGPL, repinfo=null)
2017/06/08 17:58:12 - LecturaGPL - Transformation is pre-loaded.
2017/06/08 17:58:12 - LecturaGPL - Dispatching started for transformation [LecturaGPL]
2017/06/08 17:58:12 - LecturaGPL - Nr of arguments detected:4 
2017/06/08 17:58:12 - LecturaGPL - This is not a replay transformation
2017/06/08 17:58:12 - LecturaGPL - I found 8 different steps to launch.
2017/06/08 17:58:12 - LecturaGPL - Allocating rowsets...
2017/06/08 17:58:12 - LecturaGPL -  Allocating rowsets for step 0 --> Text file input
2017/06/08 17:58:12 - LecturaGPL -   prevcopies = 1, nextcopies=1
2017/06/08 17:58:12 - LecturaGPL - Transformation allocated new rowset [Text file input.0 - Modified Java Script Value.0]
2017/06/08 17:58:12 - LecturaGPL -  Allocated 1 rowsets for step 0 --> Text file input  
2017/06/08 17:58:12 - LecturaGPL -  Allocating rowsets for step 1 --> Modified Java Script Value
2017/06/08 17:58:12 - LecturaGPL -   prevcopies = 1, nextcopies=1
2017/06/08 17:58:12 - LecturaGPL - Transformation allocated new rowset [Modified Java Script Value.0 - Filter rows 2.0]
2017/06/08 17:58:12 - LecturaGPL -  Allocated 2 rowsets for step 1 --> Modified Java Script Value  
2017/06/08 17:58:12 - LecturaGPL -  Allocating rowsets for step 2 --> Filter rows
2017/06/08 17:58:12 - LecturaGPL -   prevcopies = 1, nextcopies=1
2017/06/08 17:58:12 - LecturaGPL - Transformation allocated new rowset [Filter rows.0 - Table output.0]
2017/06/08 17:58:12 - LecturaGPL -   prevcopies = 1, nextcopies=1
2017/06/08 17:58:12 - LecturaGPL - Transformation allocated new rowset [Filter rows.0 - Dummy (do nothing).0]
2017/06/08 17:58:12 - LecturaGPL -  Allocated 4 rowsets for step 2 --> Filter rows  
2017/06/08 17:58:12 - LecturaGPL -  Allocating rowsets for step 3 --> Table output
2017/06/08 17:58:12 - LecturaGPL -   prevcopies = 1, nextcopies=1
2017/06/08 17:58:12 - LecturaGPL - Transformation allocated new rowset [Table output.0 - Dummy (do nothing) 2.0]
2017/06/08 17:58:12 - LecturaGPL -  Allocated 5 rowsets for step 3 --> Table output  
2017/06/08 17:58:12 - LecturaGPL -  Allocating rowsets for step 4 --> Dummy (do nothing)
2017/06/08 17:58:12 - LecturaGPL -   prevcopies = 1, nextcopies=1
2017/06/08 17:58:12 - LecturaGPL - Transformation allocated new rowset [Dummy (do nothing).0 - Dummy (do nothing) 2.0]
2017/06/08 17:58:12 - LecturaGPL -  Allocated 6 rowsets for step 4 --> Dummy (do nothing)  
2017/06/08 17:58:12 - LecturaGPL -  Allocating rowsets for step 5 --> Dummy (do nothing) 2
2017/06/08 17:58:12 - LecturaGPL -  Allocated 6 rowsets for step 5 --> Dummy (do nothing) 2  
2017/06/08 17:58:12 - LecturaGPL -  Allocating rowsets for step 6 --> Filter rows 2
2017/06/08 17:58:12 - LecturaGPL -   prevcopies = 1, nextcopies=1
2017/06/08 17:58:12 - LecturaGPL - Transformation allocated new rowset [Filter rows 2.0 - Filter rows.0]
2017/06/08 17:58:12 - LecturaGPL -   prevcopies = 1, nextcopies=1
2017/06/08 17:58:12 - LecturaGPL - Transformation allocated new rowset [Filter rows 2.0 - Abort.0]
2017/06/08 17:58:12 - LecturaGPL -  Allocated 8 rowsets for step 6 --> Filter rows 2  
2017/06/08 17:58:12 - LecturaGPL -  Allocating rowsets for step 7 --> Abort
2017/06/08 17:58:12 - LecturaGPL -  Allocated 8 rowsets for step 7 --> Abort  
2017/06/08 17:58:12 - LecturaGPL - Allocating Steps & StepData...
2017/06/08 17:58:12 - LecturaGPL -  Transformation is about to allocate step [Text file input] of type [TextFileInput]
2017/06/08 17:58:12 - Text file input.0 - Starting allocation of buffers & new threads...
2017/06/08 17:58:12 - Text file input.0 - Step info: nrinput=0 nroutput=1
2017/06/08 17:58:12 - Text file input.0 - output rel. is  1:1
2017/06/08 17:58:12 - Text file input.0 - Found output rowset [Text file input.0 - Modified Java Script Value.0]
2017/06/08 17:58:12 - Text file input.0 - Finished dispatching
2017/06/08 17:58:12 - LecturaGPL -  Transformation has allocated a new step: [Text file input].0
2017/06/08 17:58:12 - LecturaGPL -  Transformation is about to allocate step [Modified Java Script Value] of type [ScriptValueMod]
2017/06/08 17:58:12 - Modified Java Script Value.0 - distribution activated
2017/06/08 17:58:12 - Modified Java Script Value.0 - Starting allocation of buffers & new threads...
2017/06/08 17:58:12 - Modified Java Script Value.0 - Step info: nrinput=1 nroutput=1
2017/06/08 17:58:12 - Modified Java Script Value.0 - Got previous step from [Modified Java Script Value] #0 --> Text file input
2017/06/08 17:58:12 - Modified Java Script Value.0 - input rel is 1:1
2017/06/08 17:58:12 - Modified Java Script Value.0 - Found input rowset [Text file input.0 - Modified Java Script Value.0]
2017/06/08 17:58:12 - Modified Java Script Value.0 - output rel. is  1:1
2017/06/08 17:58:12 - Modified Java Script Value.0 - Found output rowset [Modified Java Script Value.0 - Filter rows 2.0]
2017/06/08 17:58:12 - Modified Java Script Value.0 - Finished dispatching
2017/06/08 17:58:12 - LecturaGPL -  Transformation has allocated a new step: [Modified Java Script Value].0
2017/06/08 17:58:12 - LecturaGPL -  Transformation is about to allocate step [Filter rows] of type [FilterRows]
2017/06/08 17:58:12 - Filter rows.0 - distribution activated
2017/06/08 17:58:12 - Filter rows.0 - Starting allocation of buffers & new threads...
2017/06/08 17:58:12 - Filter rows.0 - Step info: nrinput=1 nroutput=2
2017/06/08 17:58:12 - Filter rows.0 - Got previous step from [Filter rows] #0 --> Filter rows 2
2017/06/08 17:58:12 - Filter rows.0 - input rel is 1:1
2017/06/08 17:58:12 - Filter rows.0 - Found input rowset [Filter rows 2.0 - Filter rows.0]
2017/06/08 17:58:12 - Filter rows.0 - output rel. is  1:1
2017/06/08 17:58:12 - Filter rows.0 - Found output rowset [Filter rows.0 - Table output.0]
2017/06/08 17:58:12 - Filter rows.0 - output rel. is  1:1
2017/06/08 17:58:12 - Filter rows.0 - Found output rowset [Filter rows.0 - Dummy (do nothing).0]
2017/06/08 17:58:12 - Filter rows.0 - Finished dispatching
2017/06/08 17:58:12 - LecturaGPL -  Transformation has allocated a new step: [Filter rows].0
2017/06/08 17:58:12 - LecturaGPL -  Transformation is about to allocate step [Table output] of type [TableOutput]
2017/06/08 17:58:12 - Table output.0 - distribution activated
2017/06/08 17:58:12 - Table output.0 - Starting allocation of buffers & new threads...
2017/06/08 17:58:12 - Table output.0 - Step info: nrinput=1 nroutput=1
2017/06/08 17:58:12 - Table output.0 - Got previous step from [Table output] #0 --> Filter rows
2017/06/08 17:58:12 - Table output.0 - input rel is 1:1
2017/06/08 17:58:12 - Table output.0 - Found input rowset [Filter rows.0 - Table output.0]
2017/06/08 17:58:12 - Table output.0 - output rel. is  1:1
2017/06/08 17:58:12 - Table output.0 - Found output rowset [Table output.0 - Dummy (do nothing) 2.0]
2017/06/08 17:58:12 - Table output.0 - Finished dispatching
2017/06/08 17:58:12 - LecturaGPL -  Transformation has allocated a new step: [Table output].0
2017/06/08 17:58:12 - LecturaGPL -  Transformation is about to allocate step [Dummy (do nothing)] of type [Dummy]
2017/06/08 17:58:12 - Dummy (do nothing).0 - distribution activated
2017/06/08 17:58:12 - Dummy (do nothing).0 - Starting allocation of buffers & new threads...
2017/06/08 17:58:12 - Dummy (do nothing).0 - Step info: nrinput=1 nroutput=1
2017/06/08 17:58:12 - Dummy (do nothing).0 - Got previous step from [Dummy (do nothing)] #0 --> Filter rows
2017/06/08 17:58:12 - Dummy (do nothing).0 - input rel is 1:1
2017/06/08 17:58:12 - Dummy (do nothing).0 - Found input rowset [Filter rows.0 - Dummy (do nothing).0]
2017/06/08 17:58:12 - Dummy (do nothing).0 - output rel. is  1:1
2017/06/08 17:58:12 - Dummy (do nothing).0 - Found output rowset [Dummy (do nothing).0 - Dummy (do nothing) 2.0]
2017/06/08 17:58:12 - Dummy (do nothing).0 - Finished dispatching
2017/06/08 17:58:12 - LecturaGPL -  Transformation has allocated a new step: [Dummy (do nothing)].0
2017/06/08 17:58:12 - LecturaGPL -  Transformation is about to allocate step [Dummy (do nothing) 2] of type [Dummy]
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - distribution activated
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - Starting allocation of buffers & new threads...
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - Step info: nrinput=2 nroutput=0
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - Got previous step from [Dummy (do nothing) 2] #0 --> Table output
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - input rel is 1:1
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - Found input rowset [Table output.0 - Dummy (do nothing) 2.0]
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - Got previous step from [Dummy (do nothing) 2] #1 --> Dummy (do nothing)
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - input rel is 1:1
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - Found input rowset [Dummy (do nothing).0 - Dummy (do nothing) 2.0]
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - Finished dispatching
2017/06/08 17:58:12 - LecturaGPL -  Transformation has allocated a new step: [Dummy (do nothing) 2].0
2017/06/08 17:58:12 - LecturaGPL -  Transformation is about to allocate step [Filter rows 2] of type [FilterRows]
2017/06/08 17:58:12 - Filter rows 2.0 - distribution activated
2017/06/08 17:58:12 - Filter rows 2.0 - Starting allocation of buffers & new threads...
2017/06/08 17:58:12 - Filter rows 2.0 - Step info: nrinput=1 nroutput=2
2017/06/08 17:58:12 - Filter rows 2.0 - Got previous step from [Filter rows 2] #0 --> Modified Java Script Value
2017/06/08 17:58:12 - Filter rows 2.0 - input rel is 1:1
2017/06/08 17:58:12 - Filter rows 2.0 - Found input rowset [Modified Java Script Value.0 - Filter rows 2.0]
2017/06/08 17:58:12 - Filter rows 2.0 - output rel. is  1:1
2017/06/08 17:58:12 - Filter rows 2.0 - Found output rowset [Filter rows 2.0 - Filter rows.0]
2017/06/08 17:58:12 - Filter rows 2.0 - output rel. is  1:1
2017/06/08 17:58:12 - Filter rows 2.0 - Found output rowset [Filter rows 2.0 - Abort.0]
2017/06/08 17:58:12 - Filter rows 2.0 - Finished dispatching
2017/06/08 17:58:12 - LecturaGPL -  Transformation has allocated a new step: [Filter rows 2].0
2017/06/08 17:58:12 - LecturaGPL -  Transformation is about to allocate step [Abort] of type [Abort]
2017/06/08 17:58:12 - Abort.0 - distribution activated
2017/06/08 17:58:12 - Abort.0 - Starting allocation of buffers & new threads...
2017/06/08 17:58:12 - Abort.0 - Step info: nrinput=1 nroutput=0
2017/06/08 17:58:12 - Abort.0 - Got previous step from [Abort] #0 --> Filter rows 2
2017/06/08 17:58:12 - Abort.0 - input rel is 1:1
2017/06/08 17:58:12 - Abort.0 - Found input rowset [Filter rows 2.0 - Abort.0]
2017/06/08 17:58:12 - Abort.0 - Finished dispatching
2017/06/08 17:58:12 - LecturaGPL -  Transformation has allocated a new step: [Abort].0
2017/06/08 17:58:12 - LecturaGPL - This transformation can be replayed with replay date: 2017/06/08 17:58:12
2017/06/08 17:58:12 - LecturaGPL - Initialising 8 steps...
2017/06/08 17:58:12 - Text file input.0 - Running on slave server #0/1.
2017/06/08 17:58:12 - Dummy (do nothing).0 - Running on slave server #0/1.
2017/06/08 17:58:12 - Filter rows.0 - Running on slave server #0/1.
2017/06/08 17:58:12 - Filter rows 2.0 - Running on slave server #0/1.
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - Running on slave server #0/1.
2017/06/08 17:58:12 - Table output.0 - Running on slave server #0/1.
2017/06/08 17:58:12 - Abort.0 - Running on slave server #0/1.
2017/06/08 17:58:12 - Modified Java Script Value.0 - Running on slave server #0/1.
2017/06/08 17:58:12 - localPostGres - New database connection defined
2017/06/08 17:58:12 - localPostGres - Connected to database.
2017/06/08 17:58:12 - Table output.0 - Connected to database [localPostGres] (commit=1000)
2017/06/08 17:58:12 - localPostGres - Auto commit off
2017/06/08 17:58:12 - LecturaGPL - Step [Text file input.0] initialized flawlessly.
2017/06/08 17:58:12 - LecturaGPL - Step [Modified Java Script Value.0] initialized flawlessly.
2017/06/08 17:58:12 - LecturaGPL - Step [Filter rows.0] initialized flawlessly.
2017/06/08 17:58:12 - LecturaGPL - Step [Table output.0] initialized flawlessly.
2017/06/08 17:58:12 - LecturaGPL - Step [Dummy (do nothing).0] initialized flawlessly.
2017/06/08 17:58:12 - LecturaGPL - Step [Dummy (do nothing) 2.0] initialized flawlessly.
2017/06/08 17:58:12 - LecturaGPL - Step [Filter rows 2.0] initialized flawlessly.
2017/06/08 17:58:12 - LecturaGPL - Step [Abort.0] initialized flawlessly.
2017/06/08 17:58:12 - Text file input.0 - Starting to run...
2017/06/08 17:58:12 - LecturaGPL - Transformation has allocated 8 threads and 8 rowsets.
2017/06/08 17:58:12 - Modified Java Script Value.0 - Starting to run...
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - Starting to run...
2017/06/08 17:58:12 - Filter rows.0 - Starting to run...
2017/06/08 17:58:12 - Dummy (do nothing).0 - Starting to run...
2017/06/08 17:58:12 - Text file input.0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0)
2017/06/08 17:58:12 - Abort.0 - Starting to run...
2017/06/08 17:58:12 - Filter rows 2.0 - Starting to run...
2017/06/08 17:58:12 - Table output.0 - Starting to run...
2017/06/08 17:58:12 - Modified Java Script Value.0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0)
2017/06/08 17:58:12 - Filter rows 2.0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0)
2017/06/08 17:58:12 - Abort.0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0)
2017/06/08 17:58:12 - Filter rows.0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0)
2017/06/08 17:58:12 - Dummy (do nothing).0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0)
2017/06/08 17:58:12 - Dummy (do nothing) 2.0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0)
2017/06/08 17:58:12 - localPostGres - Connection to database closed!
2017/06/08 17:58:12 - Table output.0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0)
2017/06/08 17:58:12 - CargaArchivos - Starting entry [Existen datos ? GPL]
2017/06/08 17:58:12 - CargaArchivos - exec(4, 0, Existen datos ? GPL.0)
2017/06/08 17:58:12 - Es GPL? - Starting job entry
2017/06/08 17:58:12 - Es GPL? - Rows limit set to [1]
2017/06/08 17:58:12 - localPostgre - New database connection defined
2017/06/08 17:58:12 - localPostgre - Connected to database.
2017/06/08 17:58:12 - Es GPL? - We will run the following SQL statement : SELECT 1 as valor
2017/06/08 17:58:12 - Es GPL? - UNION
2017/06/08 17:58:12 - Es GPL? - SELECT 2 as valor
2017/06/08 17:58:12 - Es GPL? - FROM
2017/06/08 17:58:12 - Es GPL? - (
2017/06/08 17:58:12 - Es GPL? - SELECT
2017/06/08 17:58:12 - Es GPL? - a.partnumber_gpl,
2017/06/08 17:58:12 - Es GPL? - (SELECT descripcion_gpl 
2017/06/08 17:58:12 - Es GPL? - from global_price_list_tmp 
2017/06/08 17:58:12 - Es GPL? - where partnumber_gpl = a.partnumber_gpl
2017/06/08 17:58:12 - Es GPL? - limit 1) as descripcion_gpl,
2017/06/08 17:58:12 - Es GPL? - (SELECT preciousd_gpl
2017/06/08 17:58:12 - Es GPL? - from global_price_list_tmp 
2017/06/08 17:58:12 - Es GPL? - where partnumber_gpl = a.partnumber_gpl
2017/06/08 17:58:12 - Es GPL? - limit 1) as preciousd_gpl
2017/06/08 17:58:12 - Es GPL? - FROM (
2017/06/08 17:58:12 - Es GPL? - SELECT partnumber_gpl
2017/06/08 17:58:12 - Es GPL? - from global_price_list_tmp
2017/06/08 17:58:12 - Es GPL? - WHERE UPPER(partnumber_gpl) NOT LIKE 'CON-%'
2017/06/08 17:58:12 - Es GPL? - group by partnumber_gpl
2017/06/08 17:58:12 - Es GPL? - ) a
2017/06/08 17:58:12 - Es GPL? - ) b
2017/06/08 17:58:12 - Es GPL? - We returned 1 row(s)
2017/06/08 17:58:12 - localPostgre - Connection to database closed!
2017/06/08 17:58:12 - CargaArchivos - Starting entry [No existen datos en GPL a cargar]
2017/06/08 17:58:12 - CargaArchivos - exec(5, 0, No existen datos en GPL a cargar.0)
2017/06/08 17:58:12 - Es GPL? - Starting job entry
2017/06/08 17:58:12 - localPostgre - New database connection defined
2017/06/08 17:58:12 - localPostgre - Connected to database.
2017/06/08 17:58:12 - Es GPL? - Running SQL :update tipo_dato set validado = false, ingresado = false,
2017/06/08 17:58:12 - Es GPL? - observacion = 'No existen datos a cargar, favor revise los datos' where lower(nombre) = 'gpl';
2017/06/08 17:58:12 - localPostgre - launch DDL statement: 
2017/06/08 17:58:12 - localPostgre - update tipo_dato set validado = false, ingresado = false,
2017/06/08 17:58:12 - localPostgre - observacion = 'No existen datos a cargar, favor revise los datos' where lower(nombre) = 'gpl'
2017/06/08 17:58:12 - localPostgre - 1 statement executed
2017/06/08 17:58:12 - localPostgre - Connection to database closed!
2017/06/08 17:58:12 - CargaArchivos - Finished job entry [No existen datos en GPL a cargar] (result=[true])
2017/06/08 17:58:12 - CargaArchivos - Finished job entry [Existen datos ? GPL] (result=[true])
2017/06/08 17:58:12 - CargaArchivos - Finished job entry [CargaGPL] (result=[true])
2017/06/08 17:58:12 - CargaArchivos - Finished job entry [Drop Index y Vacia Tablas] (result=[true])
2017/06/08 17:58:12 - CargaArchivos - Finished job entry [Es GPL?] (result=[true])
2017/06/08 17:58:12 - CargaArchivos - Job execution finished