2016/01/26 13:21:54 - Kitchen - Logging is at level : Detailed logging
2016/01/26 13:21:54 - Kitchen - Start of run.
2016/01/26 13:21:54 - CargaArchivos - Start of job execution
2016/01/26 13:21:54 - CargaArchivos - exec(0, 0, START.0)
2016/01/26 13:21:54 - START - Starting job entry
2016/01/26 13:21:54 - CargaArchivos - Starting entry [Es GPL?]
2016/01/26 13:21:54 - CargaArchivos - exec(1, 0, Es GPL?.0)
2016/01/26 13:21:54 - Es GPL? - Starting job entry
2016/01/26 13:21:54 - Es GPL? - Value to evaluate is Fasttrack
2016/01/26 13:21:54 - CargaArchivos - Starting entry [Es WPL?]
2016/01/26 13:21:54 - CargaArchivos - exec(2, 0, Es WPL?.0)
2016/01/26 13:21:54 - Es GPL? - Starting job entry
2016/01/26 13:21:54 - Es GPL? - Value to evaluate is Fasttrack
2016/01/26 13:21:54 - CargaArchivos - Starting entry [Es Producto Servicio?]
2016/01/26 13:21:54 - CargaArchivos - exec(3, 0, Es Producto Servicio?.0)
2016/01/26 13:21:54 - Es GPL? - Starting job entry
2016/01/26 13:21:54 - Es GPL? - Value to evaluate is Fasttrack
2016/01/26 13:21:54 - CargaArchivos - Starting entry [Es Fasttrack?]
2016/01/26 13:21:54 - CargaArchivos - exec(4, 0, Es Fasttrack?.0)
2016/01/26 13:21:54 - Es GPL? - Starting job entry
2016/01/26 13:21:54 - Es GPL? - Value to evaluate is Fasttrack
2016/01/26 13:21:54 - CargaArchivos - Starting entry [Carga Fasttrack TMP]
2016/01/26 13:21:54 - CargaArchivos - exec(5, 0, Carga Fasttrack TMP.0)
2016/01/26 13:21:54 - Carga Fasttrack TMP - Starting job entry
2016/01/26 13:21:54 - Carga Fasttrack TMP - Opening transformation: [file:///home/admin_tecno/transformaciones/cargaFasttrackv3.ktr]
2016/01/26 13:21:54 - Carga Fasttrack TMP - Loading transformation from XML file [file:///home/admin_tecno/transformaciones/cargaFasttrackv3.ktr]
2016/01/26 13:21:54 - Carga Fasttrack TMP - Starting transformation...(file=${Internal.Job.Filename.Directory}/cargaFasttrackv3.ktr, name=Carga Fasttrack TMP, repinfo=null)
2016/01/26 13:21:54 - cargaFasttrackv3 - Transformation is pre-loaded.
2016/01/26 13:21:54 - cargaFasttrackv3 - Dispatching started for transformation [cargaFasttrackv3]
2016/01/26 13:21:54 - cargaFasttrackv3 - Nr of arguments detected:0 
2016/01/26 13:21:54 - cargaFasttrackv3 - This is not a replay transformation
2016/01/26 13:21:54 - cargaFasttrackv3 - I found 2 different steps to launch.
2016/01/26 13:21:54 - cargaFasttrackv3 - Allocating rowsets...
2016/01/26 13:21:54 - cargaFasttrackv3 -  Allocating rowsets for step 0 --> Microsoft Excel Input
2016/01/26 13:21:54 - cargaFasttrackv3 -   prevcopies = 1, nextcopies=1
2016/01/26 13:21:54 - cargaFasttrackv3 - Transformation allocated new rowset [Microsoft Excel Input.0 - Table output.0]
2016/01/26 13:21:54 - cargaFasttrackv3 -  Allocated 1 rowsets for step 0 --> Microsoft Excel Input  
2016/01/26 13:21:54 - cargaFasttrackv3 -  Allocating rowsets for step 1 --> Table output
2016/01/26 13:21:54 - cargaFasttrackv3 -  Allocated 1 rowsets for step 1 --> Table output  
2016/01/26 13:21:54 - cargaFasttrackv3 - Allocating Steps & StepData...
2016/01/26 13:21:54 - cargaFasttrackv3 -  Transformation is about to allocate step [Microsoft Excel Input] of type [ExcelInput]
2016/01/26 13:21:54 - Microsoft Excel Input.0 - Starting allocation of buffers & new threads...
2016/01/26 13:21:54 - Microsoft Excel Input.0 - Step info: nrinput=0 nroutput=1
2016/01/26 13:21:54 - Microsoft Excel Input.0 - output rel. is  1:1
2016/01/26 13:21:54 - Microsoft Excel Input.0 - Found output rowset [Microsoft Excel Input.0 - Table output.0]
2016/01/26 13:21:54 - Microsoft Excel Input.0 - Finished dispatching
2016/01/26 13:21:54 - cargaFasttrackv3 -  Transformation has allocated a new step: [Microsoft Excel Input].0
2016/01/26 13:21:54 - cargaFasttrackv3 -  Transformation is about to allocate step [Table output] of type [TableOutput]
2016/01/26 13:21:54 - Table output.0 - distribution activated
2016/01/26 13:21:54 - Table output.0 - Starting allocation of buffers & new threads...
2016/01/26 13:21:54 - Table output.0 - Step info: nrinput=1 nroutput=0
2016/01/26 13:21:54 - Table output.0 - Got previous step from [Table output] #0 --> Microsoft Excel Input
2016/01/26 13:21:54 - Table output.0 - input rel is 1:1
2016/01/26 13:21:54 - Table output.0 - Found input rowset [Microsoft Excel Input.0 - Table output.0]
2016/01/26 13:21:54 - Table output.0 - Finished dispatching
2016/01/26 13:21:54 - cargaFasttrackv3 -  Transformation has allocated a new step: [Table output].0
2016/01/26 13:21:54 - cargaFasttrackv3 - This transformation can be replayed with replay date: 2016/01/26 13:21:54
2016/01/26 13:21:54 - cargaFasttrackv3 - Initialising 2 steps...
2016/01/26 13:21:54 - Table output.0 - Running on slave server #0/1.
2016/01/26 13:21:54 - Microsoft Excel Input.0 - Running on slave server #0/1.
2016/01/26 13:21:54 - localPostgre - New database connection defined
2016/01/26 13:21:55 - localPostgre - Connected to database.
2016/01/26 13:21:55 - Table output.0 - Connected to database [localPostgre] (commit=1000)
2016/01/26 13:21:55 - localPostgre - Auto commit off
2016/01/26 13:21:55 - cargaFasttrackv3 - Step [Microsoft Excel Input.0] initialized flawlessly.
2016/01/26 13:21:55 - cargaFasttrackv3 - Step [Table output.0] initialized flawlessly.
2016/01/26 13:21:55 - cargaFasttrackv3 - Transformation has allocated 2 threads and 1 rowsets.
2016/01/26 13:21:55 - Microsoft Excel Input.0 - Starting to run...
2016/01/26 13:21:55 - Microsoft Excel Input.0 - Opening openFile #0 : /var/www/informes/datos/fasttrack/PP-Fast-150725-32283.xls
2016/01/26 13:21:55 - Table output.0 - Starting to run...
2016/01/26 13:21:55 - Table output.0 - Prepared statement : INSERT INTO "public".fastrack_tmp (partnumber, porcendcto, fechainicio, fechafin) VALUES ( ?,  ?,  ?,  ?)
2016/01/26 13:21:56 - Microsoft Excel Input.0 - No more files to be processed! (1 files done)
2016/01/26 13:21:56 - Microsoft Excel Input.0 - Finished processing (I=525, O=0, R=0, W=525, U=0, E=0)
2016/01/26 13:21:56 - localPostgre - Connection to database closed!
2016/01/26 13:21:56 - Table output.0 - Finished processing (I=0, O=525, R=525, W=525, U=0, E=0)
2016/01/26 13:21:56 - CargaArchivos - Starting entry [Existen datos ?]
2016/01/26 13:21:56 - CargaArchivos - exec(6, 0, Existen datos ?.0)
2016/01/26 13:21:56 - Es GPL? - Starting job entry
2016/01/26 13:21:56 - Es GPL? - Rows limit set to [1]
2016/01/26 13:21:56 - localPostgre - New database connection defined
2016/01/26 13:21:56 - localPostgre - Connected to database.
2016/01/26 13:21:56 - Es GPL? - We will run the following SQL statement : SELECT 1 as valor
2016/01/26 13:21:56 - Es GPL? - UNION
2016/01/26 13:21:56 - Es GPL? - select
2016/01/26 13:21:56 - Es GPL? - 2 as valor
2016/01/26 13:21:56 - Es GPL? - FROM fastrack_tmp
2016/01/26 13:21:56 - Es GPL? - We returned 2 row(s)
2016/01/26 13:21:56 - localPostgre - Connection to database closed!
2016/01/26 13:21:56 - CargaArchivos - Starting entry [Inserta Datos Fasttrack]
2016/01/26 13:21:56 - CargaArchivos - exec(7, 0, Inserta Datos Fasttrack.0)
2016/01/26 13:21:56 - Es GPL? - Starting job entry
2016/01/26 13:21:56 - localPostgre - New database connection defined
2016/01/26 13:21:56 - localPostgre - Connected to database.
2016/01/26 13:21:56 - Es GPL? - Running SQL :DELETE FROM fastrack WHERE partnumber_fas in (select partnumber from fastrack_tmp);
2016/01/26 13:21:56 - Es GPL? - 
2016/01/26 13:21:56 - Es GPL? - INSERT INTO fastrack (partnumber_fas,porcendcto_fas,fechainicio_fas,fechafin_fas)
2016/01/26 13:21:56 - Es GPL? - SELECT partnumber, cast(porcendcto as numeric(4,1)), cast(fechainicio as timestamp), cast(fechafin as timestamp)
2016/01/26 13:21:56 - Es GPL? - FROM fastrack_tmp;
2016/01/26 13:21:56 - localPostgre - launch DDL statement: 
2016/01/26 13:21:56 - localPostgre - DELETE FROM fastrack WHERE partnumber_fas in (select partnumber from fastrack_tmp)
2016/01/26 13:21:56 - localPostgre - launch DDL statement: 
2016/01/26 13:21:56 - localPostgre - INSERT INTO fastrack (partnumber_fas,porcendcto_fas,fechainicio_fas,fechafin_fas)
2016/01/26 13:21:56 - localPostgre - SELECT partnumber, cast(porcendcto as numeric(4,1)), cast(fechainicio as timestamp), cast(fechafin as timestamp)
2016/01/26 13:21:56 - localPostgre - FROM fastrack_tmp
2016/01/26 13:21:56 - localPostgre - 2 statements executed
2016/01/26 13:21:56 - localPostgre - Connection to database closed!
2016/01/26 13:21:56 - CargaArchivos - Starting entry [Inserción correcta 2]
2016/01/26 13:21:56 - CargaArchivos - exec(8, 0, Inserción correcta 2.0)
2016/01/26 13:21:56 - Es GPL? - Starting job entry
2016/01/26 13:21:56 - localPostgre - New database connection defined
2016/01/26 13:21:56 - localPostgre - Connected to database.
2016/01/26 13:21:56 - Es GPL? - Running SQL :update tipo_dato set validado = true, ingresado = false,
2016/01/26 13:21:56 - Es GPL? - observacion = 'Datos ingresados y validados de manera exitosa', fecha = now() where lower(nombre) = 'fasttrack';
2016/01/26 13:21:56 - localPostgre - launch DDL statement: 
2016/01/26 13:21:56 - localPostgre - update tipo_dato set validado = true, ingresado = false,
2016/01/26 13:21:56 - localPostgre - observacion = 'Datos ingresados y validados de manera exitosa', fecha = now() where lower(nombre) = 'fasttrack'
2016/01/26 13:21:56 - localPostgre - 1 statement executed
2016/01/26 13:21:56 - localPostgre - Connection to database closed!
2016/01/26 13:21:56 - CargaArchivos - Finished job entry [Inserción correcta 2] (result=[true])
2016/01/26 13:21:56 - CargaArchivos - Finished job entry [Inserta Datos Fasttrack] (result=[true])
2016/01/26 13:21:56 - CargaArchivos - Finished job entry [Existen datos ?] (result=[true])
2016/01/26 13:21:56 - CargaArchivos - Finished job entry [Carga Fasttrack TMP] (result=[true])
2016/01/26 13:21:56 - CargaArchivos - Finished job entry [Es Fasttrack?] (result=[true])
2016/01/26 13:21:56 - CargaArchivos - Finished job entry [Es Producto Servicio?] (result=[true])
2016/01/26 13:21:56 - CargaArchivos - Finished job entry [Es WPL?] (result=[true])
2016/01/26 13:21:56 - CargaArchivos - Finished job entry [Es GPL?] (result=[true])
2016/01/26 13:21:56 - CargaArchivos - Job execution finished