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