2016/01/26 12:51:28 - Kitchen - Logging is at level : Detailed logging
2016/01/26 12:51:28 - Kitchen - Start of run.
2016/01/26 12:51:28 - CargaArchivos - Start of job execution
2016/01/26 12:51:28 - CargaArchivos - exec(0, 0, START.0)
2016/01/26 12:51:28 - START - Starting job entry
2016/01/26 12:51:28 - CargaArchivos - Starting entry [Es GPL?]
2016/01/26 12:51:28 - CargaArchivos - exec(1, 0, Es GPL?.0)
2016/01/26 12:51:28 - Es GPL? - Starting job entry
2016/01/26 12:51:28 - Es GPL? - Value to evaluate is WPL
2016/01/26 12:51:28 - CargaArchivos - Starting entry [Es WPL?]
2016/01/26 12:51:28 - CargaArchivos - exec(2, 0, Es WPL?.0)
2016/01/26 12:51:28 - Es GPL? - Starting job entry
2016/01/26 12:51:28 - Es GPL? - Value to evaluate is WPL
2016/01/26 12:51:28 - CargaArchivos - Starting entry [Drop Index y Vacia Tablas WPL]
2016/01/26 12:51:28 - CargaArchivos - exec(3, 0, Drop Index y Vacia Tablas WPL.0)
2016/01/26 12:51:28 - Es GPL? - Starting job entry
2016/01/26 12:51:28 - localPostgre - New database connection defined
2016/01/26 12:51:29 - localPostgre - Connected to database.
2016/01/26 12:51:29 - Es GPL? - Running SQL :DROP INDEX IF EXISTS partnumberwpl_idx;
2016/01/26 12:51:29 - Es GPL? - TRUNCATE TABLE wholesale_price_list_tmp;
2016/01/26 12:51:29 - Es GPL? - update tipo_dato set validado = false, ingresado = true,
2016/01/26 12:51:29 - Es GPL? - observacion = 'A la espera de valicación de datos' where nombre = 'WPL';
2016/01/26 12:51:29 - localPostgre - launch DDL statement: 
2016/01/26 12:51:29 - localPostgre - DROP INDEX IF EXISTS partnumberwpl_idx
2016/01/26 12:51:29 - localPostgre - launch DDL statement: 
2016/01/26 12:51:29 - localPostgre - TRUNCATE TABLE wholesale_price_list_tmp
2016/01/26 12:51:29 - localPostgre - launch DDL statement: 
2016/01/26 12:51:29 - localPostgre - update tipo_dato set validado = false, ingresado = true,
2016/01/26 12:51:29 - localPostgre - observacion = 'A la espera de valicación de datos' where nombre = 'WPL'
2016/01/26 12:51:29 - localPostgre - 3 statements executed
2016/01/26 12:51:29 - localPostgre - Connection to database closed!
2016/01/26 12:51:29 - CargaArchivos - Starting entry [CargaWPL]
2016/01/26 12:51:29 - CargaArchivos - exec(4, 0, CargaWPL.0)
2016/01/26 12:51:29 - CargaWPL - Starting job entry
2016/01/26 12:51:29 - CargaWPL - Opening transformation: [file:///home/admin_tecno/transformaciones/LecturaWPL.ktr]
2016/01/26 12:51:29 - CargaWPL - Loading transformation from XML file [file:///home/admin_tecno/transformaciones/LecturaWPL.ktr]
2016/01/26 12:51:29 - CargaWPL - Starting transformation...(file=${Internal.Job.Filename.Directory}/LecturaWPL.ktr, name=CargaWPL, repinfo=null)
2016/01/26 12:51:29 - LecturaWPL - Transformation is pre-loaded.
2016/01/26 12:51:29 - LecturaWPL - Dispatching started for transformation [LecturaWPL]
2016/01/26 12:51:29 - LecturaWPL - Nr of arguments detected:0 
2016/01/26 12:51:29 - LecturaWPL - This is not a replay transformation
2016/01/26 12:51:29 - LecturaWPL - I found 7 different steps to launch.
2016/01/26 12:51:29 - LecturaWPL - Allocating rowsets...
2016/01/26 12:51:29 - LecturaWPL -  Allocating rowsets for step 0 --> Text file input
2016/01/26 12:51:29 - LecturaWPL -   prevcopies = 1, nextcopies=1
2016/01/26 12:51:29 - LecturaWPL - Transformation allocated new rowset [Text file input.0 - Modified Java Script Value 2.0]
2016/01/26 12:51:29 - LecturaWPL -  Allocated 1 rowsets for step 0 --> Text file input  
2016/01/26 12:51:29 - LecturaWPL -  Allocating rowsets for step 1 --> Modified Java Script Value 2
2016/01/26 12:51:29 - LecturaWPL -   prevcopies = 1, nextcopies=1
2016/01/26 12:51:29 - LecturaWPL - Transformation allocated new rowset [Modified Java Script Value 2.0 - Filter rows 2.0]
2016/01/26 12:51:29 - LecturaWPL -  Allocated 2 rowsets for step 1 --> Modified Java Script Value 2  
2016/01/26 12:51:29 - LecturaWPL -  Allocating rowsets for step 2 --> Filter rows
2016/01/26 12:51:29 - LecturaWPL -   prevcopies = 1, nextcopies=1
2016/01/26 12:51:29 - LecturaWPL - Transformation allocated new rowset [Filter rows.0 - Table output.0]
2016/01/26 12:51:29 - LecturaWPL -   prevcopies = 1, nextcopies=1
2016/01/26 12:51:29 - LecturaWPL - Transformation allocated new rowset [Filter rows.0 - Dummy (do nothing).0]
2016/01/26 12:51:29 - LecturaWPL -  Allocated 4 rowsets for step 2 --> Filter rows  
2016/01/26 12:51:29 - LecturaWPL -  Allocating rowsets for step 3 --> Table output
2016/01/26 12:51:29 - LecturaWPL -  Allocated 4 rowsets for step 3 --> Table output  
2016/01/26 12:51:29 - LecturaWPL -  Allocating rowsets for step 4 --> Dummy (do nothing)
2016/01/26 12:51:29 - LecturaWPL -  Allocated 4 rowsets for step 4 --> Dummy (do nothing)  
2016/01/26 12:51:29 - LecturaWPL -  Allocating rowsets for step 5 --> Filter rows 2
2016/01/26 12:51:29 - LecturaWPL -   prevcopies = 1, nextcopies=1
2016/01/26 12:51:29 - LecturaWPL - Transformation allocated new rowset [Filter rows 2.0 - Filter rows.0]
2016/01/26 12:51:29 - LecturaWPL -   prevcopies = 1, nextcopies=1
2016/01/26 12:51:29 - LecturaWPL - Transformation allocated new rowset [Filter rows 2.0 - Abort.0]
2016/01/26 12:51:29 - LecturaWPL -  Allocated 6 rowsets for step 5 --> Filter rows 2  
2016/01/26 12:51:29 - LecturaWPL -  Allocating rowsets for step 6 --> Abort
2016/01/26 12:51:29 - LecturaWPL -  Allocated 6 rowsets for step 6 --> Abort  
2016/01/26 12:51:29 - LecturaWPL - Allocating Steps & StepData...
2016/01/26 12:51:29 - LecturaWPL -  Transformation is about to allocate step [Text file input] of type [TextFileInput]
2016/01/26 12:51:29 - Text file input.0 - Starting allocation of buffers & new threads...
2016/01/26 12:51:29 - Text file input.0 - Step info: nrinput=0 nroutput=1
2016/01/26 12:51:29 - Text file input.0 - output rel. is  1:1
2016/01/26 12:51:29 - Text file input.0 - Found output rowset [Text file input.0 - Modified Java Script Value 2.0]
2016/01/26 12:51:29 - Text file input.0 - Finished dispatching
2016/01/26 12:51:29 - LecturaWPL -  Transformation has allocated a new step: [Text file input].0
2016/01/26 12:51:29 - LecturaWPL -  Transformation is about to allocate step [Modified Java Script Value 2] of type [ScriptValueMod]
2016/01/26 12:51:29 - Modified Java Script Value 2.0 - distribution activated
2016/01/26 12:51:29 - Modified Java Script Value 2.0 - Starting allocation of buffers & new threads...
2016/01/26 12:51:29 - Modified Java Script Value 2.0 - Step info: nrinput=1 nroutput=1
2016/01/26 12:51:29 - Modified Java Script Value 2.0 - Got previous step from [Modified Java Script Value 2] #0 --> Text file input
2016/01/26 12:51:29 - Modified Java Script Value 2.0 - input rel is 1:1
2016/01/26 12:51:29 - Modified Java Script Value 2.0 - Found input rowset [Text file input.0 - Modified Java Script Value 2.0]
2016/01/26 12:51:29 - Modified Java Script Value 2.0 - output rel. is  1:1
2016/01/26 12:51:29 - Modified Java Script Value 2.0 - Found output rowset [Modified Java Script Value 2.0 - Filter rows 2.0]
2016/01/26 12:51:29 - Modified Java Script Value 2.0 - Finished dispatching
2016/01/26 12:51:29 - LecturaWPL -  Transformation has allocated a new step: [Modified Java Script Value 2].0
2016/01/26 12:51:29 - LecturaWPL -  Transformation is about to allocate step [Filter rows] of type [FilterRows]
2016/01/26 12:51:29 - Filter rows.0 - distribution activated
2016/01/26 12:51:29 - Filter rows.0 - Starting allocation of buffers & new threads...
2016/01/26 12:51:29 - Filter rows.0 - Step info: nrinput=1 nroutput=2
2016/01/26 12:51:29 - Filter rows.0 - Got previous step from [Filter rows] #0 --> Filter rows 2
2016/01/26 12:51:29 - Filter rows.0 - input rel is 1:1
2016/01/26 12:51:29 - Filter rows.0 - Found input rowset [Filter rows 2.0 - Filter rows.0]
2016/01/26 12:51:29 - Filter rows.0 - output rel. is  1:1
2016/01/26 12:51:29 - Filter rows.0 - Found output rowset [Filter rows.0 - Table output.0]
2016/01/26 12:51:29 - Filter rows.0 - output rel. is  1:1
2016/01/26 12:51:29 - Filter rows.0 - Found output rowset [Filter rows.0 - Dummy (do nothing).0]
2016/01/26 12:51:29 - Filter rows.0 - Finished dispatching
2016/01/26 12:51:29 - LecturaWPL -  Transformation has allocated a new step: [Filter rows].0
2016/01/26 12:51:29 - LecturaWPL -  Transformation is about to allocate step [Table output] of type [TableOutput]
2016/01/26 12:51:30 - Table output.0 - distribution activated
2016/01/26 12:51:30 - Table output.0 - Starting allocation of buffers & new threads...
2016/01/26 12:51:30 - Table output.0 - Step info: nrinput=1 nroutput=0
2016/01/26 12:51:30 - Table output.0 - Got previous step from [Table output] #0 --> Filter rows
2016/01/26 12:51:30 - Table output.0 - input rel is 1:1
2016/01/26 12:51:30 - Table output.0 - Found input rowset [Filter rows.0 - Table output.0]
2016/01/26 12:51:30 - Table output.0 - Finished dispatching
2016/01/26 12:51:30 - LecturaWPL -  Transformation has allocated a new step: [Table output].0
2016/01/26 12:51:30 - LecturaWPL -  Transformation is about to allocate step [Dummy (do nothing)] of type [Dummy]
2016/01/26 12:51:30 - Dummy (do nothing).0 - distribution activated
2016/01/26 12:51:30 - Dummy (do nothing).0 - Starting allocation of buffers & new threads...
2016/01/26 12:51:30 - Dummy (do nothing).0 - Step info: nrinput=1 nroutput=0
2016/01/26 12:51:30 - Dummy (do nothing).0 - Got previous step from [Dummy (do nothing)] #0 --> Filter rows
2016/01/26 12:51:30 - Dummy (do nothing).0 - input rel is 1:1
2016/01/26 12:51:30 - Dummy (do nothing).0 - Found input rowset [Filter rows.0 - Dummy (do nothing).0]
2016/01/26 12:51:30 - Dummy (do nothing).0 - Finished dispatching
2016/01/26 12:51:30 - LecturaWPL -  Transformation has allocated a new step: [Dummy (do nothing)].0
2016/01/26 12:51:30 - LecturaWPL -  Transformation is about to allocate step [Filter rows 2] of type [FilterRows]
2016/01/26 12:51:30 - Filter rows 2.0 - distribution activated
2016/01/26 12:51:30 - Filter rows 2.0 - Starting allocation of buffers & new threads...
2016/01/26 12:51:30 - Filter rows 2.0 - Step info: nrinput=1 nroutput=2
2016/01/26 12:51:30 - Filter rows 2.0 - Got previous step from [Filter rows 2] #0 --> Modified Java Script Value 2
2016/01/26 12:51:30 - Filter rows 2.0 - input rel is 1:1
2016/01/26 12:51:30 - Filter rows 2.0 - Found input rowset [Modified Java Script Value 2.0 - Filter rows 2.0]
2016/01/26 12:51:30 - Filter rows 2.0 - output rel. is  1:1
2016/01/26 12:51:30 - Filter rows 2.0 - Found output rowset [Filter rows 2.0 - Filter rows.0]
2016/01/26 12:51:30 - Filter rows 2.0 - output rel. is  1:1
2016/01/26 12:51:30 - Filter rows 2.0 - Found output rowset [Filter rows 2.0 - Abort.0]
2016/01/26 12:51:30 - Filter rows 2.0 - Finished dispatching
2016/01/26 12:51:30 - LecturaWPL -  Transformation has allocated a new step: [Filter rows 2].0
2016/01/26 12:51:30 - LecturaWPL -  Transformation is about to allocate step [Abort] of type [Abort]
2016/01/26 12:51:30 - Abort.0 - distribution activated
2016/01/26 12:51:30 - Abort.0 - Starting allocation of buffers & new threads...
2016/01/26 12:51:30 - Abort.0 - Step info: nrinput=1 nroutput=0
2016/01/26 12:51:30 - Abort.0 - Got previous step from [Abort] #0 --> Filter rows 2
2016/01/26 12:51:30 - Abort.0 - input rel is 1:1
2016/01/26 12:51:30 - Abort.0 - Found input rowset [Filter rows 2.0 - Abort.0]
2016/01/26 12:51:30 - Abort.0 - Finished dispatching
2016/01/26 12:51:30 - LecturaWPL -  Transformation has allocated a new step: [Abort].0
2016/01/26 12:51:30 - LecturaWPL - This transformation can be replayed with replay date: 2016/01/26 12:51:30
2016/01/26 12:51:30 - LecturaWPL - Initialising 7 steps...
2016/01/26 12:51:30 - Text file input.0 - Running on slave server #0/1.
2016/01/26 12:51:30 - Dummy (do nothing).0 - Running on slave server #0/1.
2016/01/26 12:51:30 - Table output.0 - Running on slave server #0/1.
2016/01/26 12:51:30 - Filter rows.0 - Running on slave server #0/1.
2016/01/26 12:51:30 - Modified Java Script Value 2.0 - Running on slave server #0/1.
2016/01/26 12:51:30 - Abort.0 - Running on slave server #0/1.
2016/01/26 12:51:30 - localPostGres - New database connection defined
2016/01/26 12:51:30 - Filter rows 2.0 - Running on slave server #0/1.
2016/01/26 12:51:30 - localPostGres - Connected to database.
2016/01/26 12:51:30 - Table output.0 - Connected to database [localPostGres] (commit=1000)
2016/01/26 12:51:30 - localPostGres - Auto commit off
2016/01/26 12:51:30 - LecturaWPL - Step [Text file input.0] initialized flawlessly.
2016/01/26 12:51:30 - LecturaWPL - Step [Modified Java Script Value 2.0] initialized flawlessly.
2016/01/26 12:51:30 - LecturaWPL - Step [Filter rows.0] initialized flawlessly.
2016/01/26 12:51:30 - LecturaWPL - Step [Table output.0] initialized flawlessly.
2016/01/26 12:51:30 - LecturaWPL - Step [Dummy (do nothing).0] initialized flawlessly.
2016/01/26 12:51:30 - LecturaWPL - Step [Filter rows 2.0] initialized flawlessly.
2016/01/26 12:51:30 - LecturaWPL - Step [Abort.0] initialized flawlessly.
2016/01/26 12:51:30 - Text file input.0 - Starting to run...
2016/01/26 12:51:30 - Modified Java Script Value 2.0 - Starting to run...
2016/01/26 12:51:30 - Filter rows.0 - Starting to run...
2016/01/26 12:51:30 - Table output.0 - Starting to run...
2016/01/26 12:51:30 - Dummy (do nothing).0 - Starting to run...
2016/01/26 12:51:30 - Filter rows 2.0 - Starting to run...
2016/01/26 12:51:30 - LecturaWPL - Transformation has allocated 7 threads and 6 rowsets.
2016/01/26 12:51:30 - Abort.0 - Starting to run...
2016/01/26 12:51:30 - Text file input.0 - Opening file: file:///var/www/informes/datos/wpl/wic2.web
2016/01/26 12:51:30 - Modified Java Script Value 2.0 - Value #0 -  [Field1] is used in the script.
2016/01/26 12:51:30 - Modified Java Script Value 2.0 - Value #6 -  [Field7] is used in the script.
2016/01/26 12:51:30 - Modified Java Script Value 2.0 - This script is using 2 values from the input stream(s)
2016/01/26 12:51:30 - Modified Java Script Value 2.0 - Optimization level set to 9.
2016/01/26 12:51:30 - Text file input.0 - Finished processing (I=2581, O=0, R=0, W=2581, U=1, E=0)
2016/01/26 12:51:30 - Modified Java Script Value 2.0 - No starting Script found!
2016/01/26 12:51:30 - Modified Java Script Value 2.0 - No tran_Status found. Transformation status checking not available.
2016/01/26 12:51:30 - Table output.0 - Prepared statement : INSERT INTO "public".wholesale_price_list_tmp (partnumber_wpl, descripcion_wpl, preciousd_wpl) VALUES ( ?,  ?,  ?)
2016/01/26 12:51:30 - Modified Java Script Value 2.0 - No end Script found!
2016/01/26 12:51:30 - Modified Java Script Value 2.0 - Finished processing (I=0, O=0, R=2581, W=2581, U=0, E=0)
2016/01/26 12:51:30 - Filter rows 2.0 - Finished processing (I=0, O=0, R=2581, W=2581, U=0, E=0)
2016/01/26 12:51:30 - Filter rows.0 - Finished processing (I=0, O=0, R=2581, W=2581, U=0, E=0)
2016/01/26 12:51:30 - Abort.0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0)
2016/01/26 12:51:30 - Dummy (do nothing).0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0)
2016/01/26 12:51:31 - localPostGres - Connection to database closed!
2016/01/26 12:51:31 - Table output.0 - Finished processing (I=0, O=2581, R=2581, W=2581, U=0, E=0)
2016/01/26 12:51:31 - CargaArchivos - Starting entry [Existen datos ? WPL]
2016/01/26 12:51:31 - CargaArchivos - exec(5, 0, Existen datos ? WPL.0)
2016/01/26 12:51:31 - Es GPL? - Starting job entry
2016/01/26 12:51:31 - Es GPL? - Rows limit set to [1]
2016/01/26 12:51:31 - localPostgre - New database connection defined
2016/01/26 12:51:31 - localPostgre - Connected to database.
2016/01/26 12:51:31 - Es GPL? - We will run the following SQL statement : SELECT 1 as valor
2016/01/26 12:51:31 - Es GPL? - UNION
2016/01/26 12:51:31 - Es GPL? - SELECT 2 as valor
2016/01/26 12:51:31 - Es GPL? - FROM
2016/01/26 12:51:31 - Es GPL? - (
2016/01/26 12:51:31 - Es GPL? - SELECT partnumber_wpl, descripcion_wpl , preciousd_wpl
2016/01/26 12:51:31 - Es GPL? - from wholesale_price_list_tmp
2016/01/26 12:51:31 - Es GPL? - WHERE UPPER(partnumber_wpl) NOT LIKE 'CON-%'
2016/01/26 12:51:31 - Es GPL? - group by partnumber_wpl, descripcion_wpl , preciousd_wpl
2016/01/26 12:51:31 - Es GPL? - ) b
2016/01/26 12:51:31 - Es GPL? - We returned 2 row(s)
2016/01/26 12:51:31 - localPostgre - Connection to database closed!
2016/01/26 12:51:31 - CargaArchivos - Starting entry [Inserta en WPL y Borra WPL TMP]
2016/01/26 12:51:31 - CargaArchivos - exec(6, 0, Inserta en WPL y Borra WPL TMP.0)
2016/01/26 12:51:31 - Es GPL? - Starting job entry
2016/01/26 12:51:31 - localPostgre - New database connection defined
2016/01/26 12:51:31 - localPostgre - Connected to database.
2016/01/26 12:51:31 - Es GPL? - Running SQL :TRUNCATE TABLE wholesale_price_list;
2016/01/26 12:51:31 - Es GPL? - CREATE INDEX partnumberwpl_idx ON wholesale_price_list_tmp (partnumber_wpl);
2016/01/26 12:51:31 - Es GPL? - 
2016/01/26 12:51:31 - Es GPL? - INSERT INTO wholesale_price_list (partnumber_wpl, descripcion_wpl , preciousd_wpl)
2016/01/26 12:51:31 - Es GPL? - SELECT partnumber_wpl, descripcion_wpl , preciousd_wpl
2016/01/26 12:51:31 - Es GPL? - from wholesale_price_list_tmp
2016/01/26 12:51:31 - Es GPL? - WHERE UPPER(partnumber_wpl) NOT LIKE 'CON-%'
2016/01/26 12:51:31 - Es GPL? - group by partnumber_wpl, descripcion_wpl , preciousd_wpl;
2016/01/26 12:51:31 - Es GPL? - 
2016/01/26 12:51:31 - Es GPL? - TRUNCATE TABLE wholesale_price_list_tmp;
2016/01/26 12:51:31 - Es GPL? - 
2016/01/26 12:51:31 - Es GPL? - update tipo_dato set validado = true, ingresado = false,
2016/01/26 12:51:31 - Es GPL? - observacion = 'Datos ingresados y validados de manera exitosa', fecha = now() where nombre = 'WPL';
2016/01/26 12:51:31 - localPostgre - launch DDL statement: 
2016/01/26 12:51:31 - localPostgre - TRUNCATE TABLE wholesale_price_list
2016/01/26 12:51:32 - localPostgre - launch DDL statement: 
2016/01/26 12:51:32 - localPostgre - CREATE INDEX partnumberwpl_idx ON wholesale_price_list_tmp (partnumber_wpl)
2016/01/26 12:51:32 - localPostgre - launch DDL statement: 
2016/01/26 12:51:32 - localPostgre - INSERT INTO wholesale_price_list (partnumber_wpl, descripcion_wpl , preciousd_wpl)
2016/01/26 12:51:32 - localPostgre - SELECT partnumber_wpl, descripcion_wpl , preciousd_wpl
2016/01/26 12:51:32 - localPostgre - from wholesale_price_list_tmp
2016/01/26 12:51:32 - localPostgre - WHERE UPPER(partnumber_wpl) NOT LIKE 'CON-%'
2016/01/26 12:51:32 - localPostgre - group by partnumber_wpl, descripcion_wpl , preciousd_wpl
2016/01/26 12:51:32 - localPostgre - launch DDL statement: 
2016/01/26 12:51:32 - localPostgre - TRUNCATE TABLE wholesale_price_list_tmp
2016/01/26 12:51:32 - localPostgre - launch DDL statement: 
2016/01/26 12:51:32 - localPostgre - update tipo_dato set validado = true, ingresado = false,
2016/01/26 12:51:32 - localPostgre - observacion = 'Datos ingresados y validados de manera exitosa', fecha = now() where nombre = 'WPL'
2016/01/26 12:51:32 - localPostgre - 5 statements executed
2016/01/26 12:51:32 - localPostgre - Connection to database closed!
2016/01/26 12:51:32 - CargaArchivos - Finished job entry [Inserta en WPL y Borra WPL TMP] (result=[true])
2016/01/26 12:51:32 - CargaArchivos - Finished job entry [Existen datos ? WPL] (result=[true])
2016/01/26 12:51:32 - CargaArchivos - Finished job entry [CargaWPL] (result=[true])
2016/01/26 12:51:32 - CargaArchivos - Finished job entry [Drop Index y Vacia Tablas WPL] (result=[true])
2016/01/26 12:51:32 - CargaArchivos - Finished job entry [Es WPL?] (result=[true])
2016/01/26 12:51:32 - CargaArchivos - Finished job entry [Es GPL?] (result=[true])
2016/01/26 12:51:32 - CargaArchivos - Job execution finished