2015/05/05 10:57:25 - Kitchen - Logging is at level : Detailed logging 2015/05/05 10:57:25 - Kitchen - Start of run. 2015/05/05 10:57:25 - CargaArchivos - Start of job execution 2015/05/05 10:57:25 - CargaArchivos - exec(0, 0, START.0) 2015/05/05 10:57:25 - START - Starting job entry 2015/05/05 10:57:25 - CargaArchivos - Starting entry [Es GPL?] 2015/05/05 10:57:25 - CargaArchivos - exec(1, 0, Es GPL?.0) 2015/05/05 10:57:25 - Es GPL? - Starting job entry 2015/05/05 10:57:25 - Es GPL? - Value to evaluate is WPL 2015/05/05 10:57:25 - CargaArchivos - Starting entry [Es WPL?] 2015/05/05 10:57:25 - CargaArchivos - exec(2, 0, Es WPL?.0) 2015/05/05 10:57:25 - Es GPL? - Starting job entry 2015/05/05 10:57:25 - Es GPL? - Value to evaluate is WPL 2015/05/05 10:57:25 - CargaArchivos - Starting entry [Drop Index y Vacia Tablas WPL] 2015/05/05 10:57:25 - CargaArchivos - exec(3, 0, Drop Index y Vacia Tablas WPL.0) 2015/05/05 10:57:25 - Es GPL? - Starting job entry 2015/05/05 10:57:25 - localPostgre - New database connection defined 2015/05/05 10:57:26 - localPostgre - Connected to database. 2015/05/05 10:57:26 - Es GPL? - Running SQL :DROP INDEX IF EXISTS partnumberwpl_idx; 2015/05/05 10:57:26 - Es GPL? - TRUNCATE TABLE wholesale_price_list_tmp; 2015/05/05 10:57:26 - Es GPL? - update tipo_dato set validado = false, ingresado = true, 2015/05/05 10:57:26 - Es GPL? - observacion = 'A la espera de valicación de datos' where nombre = 'WPL'; 2015/05/05 10:57:26 - localPostgre - launch DDL statement: 2015/05/05 10:57:26 - localPostgre - DROP INDEX IF EXISTS partnumberwpl_idx 2015/05/05 10:57:26 - localPostgre - launch DDL statement: 2015/05/05 10:57:26 - localPostgre - TRUNCATE TABLE wholesale_price_list_tmp 2015/05/05 10:57:26 - localPostgre - launch DDL statement: 2015/05/05 10:57:26 - localPostgre - update tipo_dato set validado = false, ingresado = true, 2015/05/05 10:57:26 - localPostgre - observacion = 'A la espera de valicación de datos' where nombre = 'WPL' 2015/05/05 10:57:26 - localPostgre - 3 statements executed 2015/05/05 10:57:26 - localPostgre - Connection to database closed! 2015/05/05 10:57:26 - CargaArchivos - Starting entry [CargaWPL] 2015/05/05 10:57:26 - CargaArchivos - exec(4, 0, CargaWPL.0) 2015/05/05 10:57:26 - CargaWPL - Starting job entry 2015/05/05 10:57:26 - CargaWPL - Opening transformation: [file:///home/admin_tecno/transformaciones/LecturaWPL.ktr] 2015/05/05 10:57:26 - CargaWPL - Loading transformation from XML file [file:///home/admin_tecno/transformaciones/LecturaWPL.ktr] 2015/05/05 10:57:26 - CargaWPL - Starting transformation...(file=${Internal.Job.Filename.Directory}/LecturaWPL.ktr, name=CargaWPL, repinfo=null) 2015/05/05 10:57:26 - LecturaWPL - Transformation is pre-loaded. 2015/05/05 10:57:26 - LecturaWPL - Dispatching started for transformation [LecturaWPL] 2015/05/05 10:57:26 - LecturaWPL - Nr of arguments detected:0 2015/05/05 10:57:26 - LecturaWPL - This is not a replay transformation 2015/05/05 10:57:26 - LecturaWPL - I found 7 different steps to launch. 2015/05/05 10:57:26 - LecturaWPL - Allocating rowsets... 2015/05/05 10:57:26 - LecturaWPL - Allocating rowsets for step 0 --> Text file input 2015/05/05 10:57:26 - LecturaWPL - prevcopies = 1, nextcopies=1 2015/05/05 10:57:26 - LecturaWPL - Transformation allocated new rowset [Text file input.0 - Modified Java Script Value 2.0] 2015/05/05 10:57:26 - LecturaWPL - Allocated 1 rowsets for step 0 --> Text file input 2015/05/05 10:57:26 - LecturaWPL - Allocating rowsets for step 1 --> Modified Java Script Value 2 2015/05/05 10:57:26 - LecturaWPL - prevcopies = 1, nextcopies=1 2015/05/05 10:57:26 - LecturaWPL - Transformation allocated new rowset [Modified Java Script Value 2.0 - Filter rows 2.0] 2015/05/05 10:57:26 - LecturaWPL - Allocated 2 rowsets for step 1 --> Modified Java Script Value 2 2015/05/05 10:57:26 - LecturaWPL - Allocating rowsets for step 2 --> Filter rows 2015/05/05 10:57:26 - LecturaWPL - prevcopies = 1, nextcopies=1 2015/05/05 10:57:26 - LecturaWPL - Transformation allocated new rowset [Filter rows.0 - Table output.0] 2015/05/05 10:57:26 - LecturaWPL - prevcopies = 1, nextcopies=1 2015/05/05 10:57:26 - LecturaWPL - Transformation allocated new rowset [Filter rows.0 - Dummy (do nothing).0] 2015/05/05 10:57:26 - LecturaWPL - Allocated 4 rowsets for step 2 --> Filter rows 2015/05/05 10:57:26 - LecturaWPL - Allocating rowsets for step 3 --> Table output 2015/05/05 10:57:26 - LecturaWPL - Allocated 4 rowsets for step 3 --> Table output 2015/05/05 10:57:26 - LecturaWPL - Allocating rowsets for step 4 --> Dummy (do nothing) 2015/05/05 10:57:26 - LecturaWPL - Allocated 4 rowsets for step 4 --> Dummy (do nothing) 2015/05/05 10:57:26 - LecturaWPL - Allocating rowsets for step 5 --> Filter rows 2 2015/05/05 10:57:26 - LecturaWPL - prevcopies = 1, nextcopies=1 2015/05/05 10:57:26 - LecturaWPL - Transformation allocated new rowset [Filter rows 2.0 - Filter rows.0] 2015/05/05 10:57:26 - LecturaWPL - prevcopies = 1, nextcopies=1 2015/05/05 10:57:26 - LecturaWPL - Transformation allocated new rowset [Filter rows 2.0 - Abort.0] 2015/05/05 10:57:26 - LecturaWPL - Allocated 6 rowsets for step 5 --> Filter rows 2 2015/05/05 10:57:26 - LecturaWPL - Allocating rowsets for step 6 --> Abort 2015/05/05 10:57:26 - LecturaWPL - Allocated 6 rowsets for step 6 --> Abort 2015/05/05 10:57:26 - LecturaWPL - Allocating Steps & StepData... 2015/05/05 10:57:26 - LecturaWPL - Transformation is about to allocate step [Text file input] of type [TextFileInput] 2015/05/05 10:57:26 - Text file input.0 - Starting allocation of buffers & new threads... 2015/05/05 10:57:26 - Text file input.0 - Step info: nrinput=0 nroutput=1 2015/05/05 10:57:26 - Text file input.0 - output rel. is 1:1 2015/05/05 10:57:26 - Text file input.0 - Found output rowset [Text file input.0 - Modified Java Script Value 2.0] 2015/05/05 10:57:26 - Text file input.0 - Finished dispatching 2015/05/05 10:57:26 - LecturaWPL - Transformation has allocated a new step: [Text file input].0 2015/05/05 10:57:26 - LecturaWPL - Transformation is about to allocate step [Modified Java Script Value 2] of type [ScriptValueMod] 2015/05/05 10:57:26 - Modified Java Script Value 2.0 - distribution activated 2015/05/05 10:57:26 - Modified Java Script Value 2.0 - Starting allocation of buffers & new threads... 2015/05/05 10:57:26 - Modified Java Script Value 2.0 - Step info: nrinput=1 nroutput=1 2015/05/05 10:57:26 - Modified Java Script Value 2.0 - Got previous step from [Modified Java Script Value 2] #0 --> Text file input 2015/05/05 10:57:26 - Modified Java Script Value 2.0 - input rel is 1:1 2015/05/05 10:57:26 - Modified Java Script Value 2.0 - Found input rowset [Text file input.0 - Modified Java Script Value 2.0] 2015/05/05 10:57:26 - Modified Java Script Value 2.0 - output rel. is 1:1 2015/05/05 10:57:26 - Modified Java Script Value 2.0 - Found output rowset [Modified Java Script Value 2.0 - Filter rows 2.0] 2015/05/05 10:57:26 - Modified Java Script Value 2.0 - Finished dispatching 2015/05/05 10:57:26 - LecturaWPL - Transformation has allocated a new step: [Modified Java Script Value 2].0 2015/05/05 10:57:26 - LecturaWPL - Transformation is about to allocate step [Filter rows] of type [FilterRows] 2015/05/05 10:57:26 - Filter rows.0 - distribution activated 2015/05/05 10:57:26 - Filter rows.0 - Starting allocation of buffers & new threads... 2015/05/05 10:57:26 - Filter rows.0 - Step info: nrinput=1 nroutput=2 2015/05/05 10:57:26 - Filter rows.0 - Got previous step from [Filter rows] #0 --> Filter rows 2 2015/05/05 10:57:26 - Filter rows.0 - input rel is 1:1 2015/05/05 10:57:26 - Filter rows.0 - Found input rowset [Filter rows 2.0 - Filter rows.0] 2015/05/05 10:57:26 - Filter rows.0 - output rel. is 1:1 2015/05/05 10:57:26 - Filter rows.0 - Found output rowset [Filter rows.0 - Table output.0] 2015/05/05 10:57:26 - Filter rows.0 - output rel. is 1:1 2015/05/05 10:57:26 - Filter rows.0 - Found output rowset [Filter rows.0 - Dummy (do nothing).0] 2015/05/05 10:57:26 - Filter rows.0 - Finished dispatching 2015/05/05 10:57:26 - LecturaWPL - Transformation has allocated a new step: [Filter rows].0 2015/05/05 10:57:26 - LecturaWPL - Transformation is about to allocate step [Table output] of type [TableOutput] 2015/05/05 10:57:26 - Table output.0 - distribution activated 2015/05/05 10:57:26 - Table output.0 - Starting allocation of buffers & new threads... 2015/05/05 10:57:26 - Table output.0 - Step info: nrinput=1 nroutput=0 2015/05/05 10:57:26 - Table output.0 - Got previous step from [Table output] #0 --> Filter rows 2015/05/05 10:57:26 - Table output.0 - input rel is 1:1 2015/05/05 10:57:26 - Table output.0 - Found input rowset [Filter rows.0 - Table output.0] 2015/05/05 10:57:26 - Table output.0 - Finished dispatching 2015/05/05 10:57:26 - LecturaWPL - Transformation has allocated a new step: [Table output].0 2015/05/05 10:57:26 - LecturaWPL - Transformation is about to allocate step [Dummy (do nothing)] of type [Dummy] 2015/05/05 10:57:26 - Dummy (do nothing).0 - distribution activated 2015/05/05 10:57:26 - Dummy (do nothing).0 - Starting allocation of buffers & new threads... 2015/05/05 10:57:26 - Dummy (do nothing).0 - Step info: nrinput=1 nroutput=0 2015/05/05 10:57:26 - Dummy (do nothing).0 - Got previous step from [Dummy (do nothing)] #0 --> Filter rows 2015/05/05 10:57:26 - Dummy (do nothing).0 - input rel is 1:1 2015/05/05 10:57:26 - Dummy (do nothing).0 - Found input rowset [Filter rows.0 - Dummy (do nothing).0] 2015/05/05 10:57:26 - Dummy (do nothing).0 - Finished dispatching 2015/05/05 10:57:26 - LecturaWPL - Transformation has allocated a new step: [Dummy (do nothing)].0 2015/05/05 10:57:26 - LecturaWPL - Transformation is about to allocate step [Filter rows 2] of type [FilterRows] 2015/05/05 10:57:26 - Filter rows 2.0 - distribution activated 2015/05/05 10:57:26 - Filter rows 2.0 - Starting allocation of buffers & new threads... 2015/05/05 10:57:26 - Filter rows 2.0 - Step info: nrinput=1 nroutput=2 2015/05/05 10:57:26 - Filter rows 2.0 - Got previous step from [Filter rows 2] #0 --> Modified Java Script Value 2 2015/05/05 10:57:26 - Filter rows 2.0 - input rel is 1:1 2015/05/05 10:57:26 - Filter rows 2.0 - Found input rowset [Modified Java Script Value 2.0 - Filter rows 2.0] 2015/05/05 10:57:26 - Filter rows 2.0 - output rel. is 1:1 2015/05/05 10:57:26 - Filter rows 2.0 - Found output rowset [Filter rows 2.0 - Filter rows.0] 2015/05/05 10:57:26 - Filter rows 2.0 - output rel. is 1:1 2015/05/05 10:57:26 - Filter rows 2.0 - Found output rowset [Filter rows 2.0 - Abort.0] 2015/05/05 10:57:26 - Filter rows 2.0 - Finished dispatching 2015/05/05 10:57:26 - LecturaWPL - Transformation has allocated a new step: [Filter rows 2].0 2015/05/05 10:57:26 - LecturaWPL - Transformation is about to allocate step [Abort] of type [Abort] 2015/05/05 10:57:26 - Abort.0 - distribution activated 2015/05/05 10:57:26 - Abort.0 - Starting allocation of buffers & new threads... 2015/05/05 10:57:26 - Abort.0 - Step info: nrinput=1 nroutput=0 2015/05/05 10:57:26 - Abort.0 - Got previous step from [Abort] #0 --> Filter rows 2 2015/05/05 10:57:26 - Abort.0 - input rel is 1:1 2015/05/05 10:57:26 - Abort.0 - Found input rowset [Filter rows 2.0 - Abort.0] 2015/05/05 10:57:26 - Abort.0 - Finished dispatching 2015/05/05 10:57:26 - LecturaWPL - Transformation has allocated a new step: [Abort].0 2015/05/05 10:57:26 - LecturaWPL - This transformation can be replayed with replay date: 2015/05/05 10:57:26 2015/05/05 10:57:26 - LecturaWPL - Initialising 7 steps... 2015/05/05 10:57:26 - Filter rows.0 - Running on slave server #0/1. 2015/05/05 10:57:26 - Filter rows 2.0 - Running on slave server #0/1. 2015/05/05 10:57:26 - Dummy (do nothing).0 - Running on slave server #0/1. 2015/05/05 10:57:26 - Table output.0 - Running on slave server #0/1. 2015/05/05 10:57:26 - Modified Java Script Value 2.0 - Running on slave server #0/1. 2015/05/05 10:57:26 - Text file input.0 - Running on slave server #0/1. 2015/05/05 10:57:27 - Abort.0 - Running on slave server #0/1. 2015/05/05 10:57:27 - localPostGres - New database connection defined 2015/05/05 10:57:27 - localPostGres - Connected to database. 2015/05/05 10:57:27 - Table output.0 - Connected to database [localPostGres] (commit=1000) 2015/05/05 10:57:27 - localPostGres - Auto commit off 2015/05/05 10:57:27 - LecturaWPL - Step [Text file input.0] initialized flawlessly. 2015/05/05 10:57:27 - LecturaWPL - Step [Modified Java Script Value 2.0] initialized flawlessly. 2015/05/05 10:57:27 - LecturaWPL - Step [Filter rows.0] initialized flawlessly. 2015/05/05 10:57:27 - LecturaWPL - Step [Table output.0] initialized flawlessly. 2015/05/05 10:57:27 - LecturaWPL - Step [Dummy (do nothing).0] initialized flawlessly. 2015/05/05 10:57:27 - LecturaWPL - Step [Filter rows 2.0] initialized flawlessly. 2015/05/05 10:57:27 - LecturaWPL - Step [Abort.0] initialized flawlessly. 2015/05/05 10:57:27 - Text file input.0 - Starting to run... 2015/05/05 10:57:27 - Filter rows.0 - Starting to run... 2015/05/05 10:57:27 - Modified Java Script Value 2.0 - Starting to run... 2015/05/05 10:57:27 - Table output.0 - Starting to run... 2015/05/05 10:57:27 - Dummy (do nothing).0 - Starting to run... 2015/05/05 10:57:27 - Filter rows 2.0 - Starting to run... 2015/05/05 10:57:27 - Text file input.0 - Opening file: file:///var/www/informes/datos/wpl/PP-Fast70983-120728.xls 2015/05/05 10:57:27 - LecturaWPL - Transformation has allocated 7 threads and 6 rowsets. 2015/05/05 10:57:27 - Abort.0 - Starting to run... 2015/05/05 10:57:27 - Modified Java Script Value 2.0 - Value #0 - [Field1] is used in the script. 2015/05/05 10:57:27 - Modified Java Script Value 2.0 - Value #6 - [Field7] is used in the script. 2015/05/05 10:57:27 - Modified Java Script Value 2.0 - This script is using 2 values from the input stream(s) 2015/05/05 10:57:27 - Text file input.0 - Finished processing (I=1195, O=0, R=0, W=1195, U=1, E=0) 2015/05/05 10:57:27 - Modified Java Script Value 2.0 - Optimization level set to 9. 2015/05/05 10:57:27 - Modified Java Script Value 2.0 - No starting Script found! 2015/05/05 10:57:27 - Modified Java Script Value 2.0 - No tran_Status found. Transformation status checking not available. 2015/05/05 10:57:27 - Abort.0 - ERROR (version 4.4.0-stable, build 17588 from 2012-11-21 16.02.21 by buildguy) : Row nr 1 causing abort : [������>�� ������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������������� T8�������\p!Alvaro Roberto Sotomayor Miqueles B�a�=���=xx�I*08X@�"��1��Calibri1��Calibri1��Calibri1��Calibri1��Calibri1� �Calibri1��Calibri1�4�Calibri1� �Calibri1�4�Calibri1,8�Calibri1�8�Calibri1�>�Calibri1��Calibri1�<�Calibri1�?�Calibri1�], [null], [null], [null], [null], [null], [null], [null], [null], [null], [null], [null], [null], [null], [], [0], [0], [1] 2015/05/05 10:57:27 - Abort.0 - ERROR (version 4.4.0-stable, build 17588 from 2012-11-21 16.02.21 by buildguy) : Aborting after having seen 1 rows. 2015/05/05 10:57:27 - Abort.0 - Finished processing (I=0, O=0, R=1, W=1, U=0, E=1) 2015/05/05 10:57:27 - Filter rows 2.0 - Finished processing (I=0, O=0, R=1, W=1, U=0, E=0) 2015/05/05 10:57:27 - LecturaWPL - LecturaWPL 2015/05/05 10:57:27 - LecturaWPL - LecturaWPL 2015/05/05 10:57:27 - LecturaWPL - ERROR (version 4.4.0-stable, build 17588 from 2012-11-21 16.02.21 by buildguy) : Errors detected! 2015/05/05 10:57:27 - LecturaWPL - ERROR (version 4.4.0-stable, build 17588 from 2012-11-21 16.02.21 by buildguy) : Errors detected! 2015/05/05 10:57:27 - Modified Java Script Value 2.0 - Finished processing (I=0, O=0, R=26, W=25, U=0, E=0) 2015/05/05 10:57:27 - Dummy (do nothing).0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0) 2015/05/05 10:57:27 - Filter rows.0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0) 2015/05/05 10:57:27 - localPostGres - Connection to database closed! 2015/05/05 10:57:27 - Table output.0 - Finished processing (I=0, O=0, R=0, W=0, U=0, E=0) 2015/05/05 10:57:27 - CargaArchivos - Starting entry [Formato incorrecto archivo WPL] 2015/05/05 10:57:27 - CargaArchivos - exec(5, 0, Formato incorrecto archivo WPL.0) 2015/05/05 10:57:27 - Es GPL? - Starting job entry 2015/05/05 10:57:27 - localPostgre - New database connection defined 2015/05/05 10:57:27 - localPostgre - Connected to database. 2015/05/05 10:57:27 - Es GPL? - Running SQL :update tipo_dato set validado = false, ingresado = false, 2015/05/05 10:57:27 - Es GPL? - observacion = 'No se ha podido cargar el archivo debido a que el formato es incorrecto' where lower(nombre) = 'wpl'; 2015/05/05 10:57:27 - localPostgre - launch DDL statement: 2015/05/05 10:57:27 - localPostgre - update tipo_dato set validado = false, ingresado = false, 2015/05/05 10:57:27 - localPostgre - observacion = 'No se ha podido cargar el archivo debido a que el formato es incorrecto' where lower(nombre) = 'wpl' 2015/05/05 10:57:27 - localPostgre - 1 statement executed 2015/05/05 10:57:27 - localPostgre - Connection to database closed! 2015/05/05 10:57:27 - CargaArchivos - Finished job entry [Formato incorrecto archivo WPL] (result=[true]) 2015/05/05 10:57:27 - CargaArchivos - Finished job entry [CargaWPL] (result=[true]) 2015/05/05 10:57:27 - CargaArchivos - Finished job entry [Drop Index y Vacia Tablas WPL] (result=[true]) 2015/05/05 10:57:27 - CargaArchivos - Finished job entry [Es WPL?] (result=[true]) 2015/05/05 10:57:27 - CargaArchivos - Finished job entry [Es GPL?] (result=[true]) 2015/05/05 10:57:27 - CargaArchivos - Job execution finished