Job < > is terminated due to error <70401>

[former_member235282]

Dear Experts,

I have a situation with a JOB that runs everyday automatically.

Sometimes the JOB runs and finishes successfully, but most of the times it starts and in few seconds finishes with error, the workaround is run it again manually, when it runs manually finishes  successfully.

Some threads  talks about the issue but  in my case it works manually. Anyone let me know if has had this situation before.

I have Data Services 4.2  on Windows 2012R2 with SQL Anywhere.

These are the trace and error logs for further analysis,  in the last part is the trace log when it  runs and  finishes successfully. What else can I check?

I appreciate your comments,

Trace log

(14.2) 01-23-16 06:01:03 (3524:12096)      JOB: Reading job <23936284_ed52_4406_8d5f_948154d9a8cc> from the repository; Server version is <14.2.4.668>; Repository version is

                                                <14.2.4.0000>.

(14.2) 01-23-16 06:01:03 (3524:12096)      JOB: Current directory of job <23936284_ed52_4406_8d5f_948154d9a8cc> is <C:\Program Files (x86)\SAP BusinessObjects\Data

                                                Services\bin>.

(14.2) 01-23-16 06:01:05 (3524:12096)      JOB: Starting job on job server host <HACDSS>, port <3509>.

(14.2) 01-23-16 06:01:06 (3524:12096)      JOB: Job <01_Reglas_Clientes> of runid <20160123060105352412096> is initiated by user <sap-admin>.

(14.2) 01-23-16 06:01:06 (3524:12096)      JOB: Processing job <01_Reglas_Clientes>.

(14.2) 01-23-16 06:01:09 (3524:12096)      JOB: Optimizing job <01_Reglas_Clientes>.

(14.2) 01-23-16 06:01:09 (3524:12096)      JOB: Job <01_Reglas_Clientes> is started.

(14.2) 01-23-16 06:01:11 (12396:22012) DATAFLOW: Process to execute data flow <RC_Clientes_Tablas_01_DT> is started.

(14.2) 01-23-16 06:01:14 (12396:22012) DATAFLOW: The specified locale <spa_co.cp1252> has been coerced to <Unicode (UTF-16)> for data flow <RC_Clientes_Tablas_01_DT> because

                                                 the datastore <SAP_DS> obtains data in <Unicode (UTF-16)> codepage.

(14.2) 01-23-16 06:01:14 (12396:22012)      JOB: Initializing transcoder for datastore <SAP_DS> to transcode between engine codepage<Unicode (UTF-16)> and datastore codepage

                                                 <UTF-8>

(14.2) 01-23-16 06:01:14 (12396:22012)      JOB: Initializing transcoder for datastore <SD_O_HOM_ST> to transcode between engine codepage<Unicode (UTF-16)> and datastore

                                                 codepage <<DEFAULT>>

(14.2) 01-23-16 06:01:14 (12396:22012)      JOB: Initializing transcoder for datastore <AM_O_HOM> to transcode between engine codepage<Unicode (UTF-16)> and datastore codepage

                                                 <<DEFAULT>>

(14.2) 01-23-16 06:01:16 (12396:22012) DATAFLOW: Data flow <RC_Clientes_Tablas_01_DT> is started.

(14.2) 01-23-16 06:01:16 (12396:22012) DATAFLOW: Cache statistics determined that data flow <RC_Clientes_Tablas_01_DT> uses 0 caches with a total size of 0 bytes, which is less

                                                 than (or equal to) 3746562048 bytes available for caches in virtual memory. Data flow will use IN MEMORY cache type.

(14.2) 01-23-16 06:01:16 (12396:22012) DATAFLOW: Data flow <RC_Clientes_Tablas_01_DT> using IN MEMORY Cache.

(14.2) 01-23-16 06:01:31 (12396:22012) DATAFLOW: Data flow <RC_Clientes_Tablas_01_DT> is terminated due to error <70401>.

(14.2) 01-23-16 06:01:31 (12396:22012) DATAFLOW: Process to execute data flow <RC_Clientes_Tablas_01_DT> is completed.

(14.2) 01-23-16 06:01:34 (3524:12096)      JOB: Job <01_Reglas_Clientes> is terminated due to error <70401>.

***********************************************************************************************************************************************************************

Error log

(14.2) 01-23-16 06:01:21 (E) (12396:19236) DBS-070401: |Data flow RC_Clientes_Tablas_01_DT|Loader Query_1_Tabla_KNBW_1

                                                       ODBC data source <HACDSS> error message for operation <SQLExecute>: <[Sybase][ODBC Driver][SQL Anywhere]Table 'Tabla_KNBW_1'

                                                       not found>.

(14.2) 01-23-16 06:01:21 (E) (12396:19236) RUN-051005: |Data flow RC_Clientes_Tablas_01_DT|Loader Query_1_Tabla_KNBW_1

                                                       Execution of <Regular Load Operations> for target <Tabla_KNBW_1> failed. Possible causes: (1) Error in the SQL syntax; (2)

                                                       Database connection is broken; (3) Database related errors such as transaction log is full, etc.; (4) The user defined in the

                                                       datastore has insufficient privileges to execute the SQL. If the error is for preload or postload operation, or if it is for

                                                       regular load operation and load triggers are defined, check the SQL. Otherwise, for (3) and (4), contact your local DBA.

(14.2) 01-23-16 06:01:21 (12396:21192) DBS-070401: |Data flow RC_Clientes_Tablas_01_DT|Loader Query_2_SAP_SD_KNVV

(14.2) 01-23-16 06:01:21 (12396:21192) RUN-051005: |Data flow RC_Clientes_Tablas_01_DT|Loader Query_2_SAP_SD_KNVV

(14.2) 01-23-16 06:01:21 (12396:18364) DBS-070401: |Data flow RC_Clientes_Tablas_01_DT|Loader Query_SAP_SD_KNA1

(14.2) 01-23-16 06:01:21 (12396:18364) RUN-051005: |Data flow RC_Clientes_Tablas_01_DT|Loader Query_SAP_SD_KNA1

(14.2) 01-23-16 06:01:34 (E) (3524:12096) DBS-070401: |Data flow RC_Clientes_Tablas_01_DT|Loader Query_1_Tabla_KNBW_1

                                                      ODBC data source <HACDSS> error message for operation <SQLExecute>: <[Sybase][ODBC Driver][SQL Anywhere]Table 'Tabla_KNBW_1'

                                                      not found>.|<Regular Load Operations>|<Tabla_KNBW_1>

***********************************************************************************************************************************************************************

Trace log (When is completed successfully)

(14.2) 01-23-16 06:01:07 (7028:21632)      JOB: Reading job <23936284_ed52_4406_8d5f_948154d9a8cc> from the repository; Server version is <14.2.4.668>; Repository version is

                                                <14.2.4.0000>.

(14.2) 01-23-16 06:01:07 (7028:21632)      JOB: Current directory of job <23936284_ed52_4406_8d5f_948154d9a8cc> is <C:\Program Files (x86)\SAP BusinessObjects\Data

                                                Services\bin>.

(14.2) 01-23-16 06:01:09 (7028:21632)      JOB: Starting job on job server host <HACDSS>, port <3509>.

(14.2) 01-23-16 06:01:10 (7028:21632)      JOB: Job <01_Reglas_Clientes> of runid <20160123060109702821632> is initiated by user <sap-admin>.

(14.2) 01-23-16 06:01:10 (7028:21632)      JOB: Processing job <01_Reglas_Clientes>.

(14.2) 01-23-16 06:01:13 (7028:21632)      JOB: Optimizing job <01_Reglas_Clientes>.

(14.2) 01-23-16 06:01:13 (7028:21632)      JOB: Job <01_Reglas_Clientes> is started.

(14.2) 01-23-16 06:01:16 (13400:2992) DATAFLOW: Process to execute data flow <RC_Clientes_Tablas_01_DT> is started.

(14.2) 01-23-16 06:01:19 (13400:2992) DATAFLOW: The specified locale <spa_co.cp1252> has been coerced to <Unicode (UTF-16)> for data flow <RC_Clientes_Tablas_01_DT> because

                                                the datastore <SAP_DS> obtains data in <Unicode (UTF-16)> codepage.

(14.2) 01-23-16 06:01:19 (13400:2992)      JOB: Initializing transcoder for datastore <SAP_DS> to transcode between engine codepage<Unicode (UTF-16)> and datastore codepage

                                                <UTF-8>

(14.2) 01-23-16 06:01:19 (13400:2992)      JOB: Initializing transcoder for datastore <SD_O_HOM_ST> to transcode between engine codepage<Unicode (UTF-16)> and datastore

                                                codepage <<DEFAULT>>

(14.2) 01-23-16 06:01:19 (13400:2992)      JOB: Initializing transcoder for datastore <AM_O_HOM> to transcode between engine codepage<Unicode (UTF-16)> and datastore codepage

                                                <<DEFAULT>>

(14.2) 01-23-16 06:01:20 (13400:2992) DATAFLOW: Data flow <RC_Clientes_Tablas_01_DT> is started.

(14.2) 01-23-16 06:01:20 (13400:2992) DATAFLOW: Cache statistics determined that data flow <RC_Clientes_Tablas_01_DT> uses 0 caches with a total size of 0 bytes, which is less

                                                than (or equal to) 3746562048 bytes available for caches in virtual memory. Data flow will use IN MEMORY cache type.

(14.2) 01-23-16 06:01:20 (13400:2992) DATAFLOW: Data flow <RC_Clientes_Tablas_01_DT> using IN MEMORY Cache.

(14.2) 01-23-16 06:01:42 (13400:2992) DATAFLOW: Data flow <RC_Clientes_Tablas_01_DT> is completed successfully.

(14.2) 01-23-16 06:01:42 (13400:2992) DATAFLOW: Process to execute data flow <RC_Clientes_Tablas_01_DT> is completed.

(14.2) 01-23-16 06:01:48 (10616:1464) DATAFLOW: Process to execute data flow <RC_Clientes_01_KNA1_Def_DT> is started.

(14.2) 01-23-16 06:01:51 (10616:1464) DATAFLOW: The specified locale <spa_co.cp1252> has been coerced to <Unicode (UTF-16)> for data flow <RC_Clientes_01_KNA1_Def_DT> because

                                                the datastore <SAP_DS> obtains data in <Unicode (UTF-16)> codepage.

(14.2) 01-23-16 06:01:51 (10616:1464)      JOB: Initializing transcoder for datastore <BI4_CMS_DSN> to transcode between engine codepage<Unicode (UTF-16)> and datastore

                                                codepage <<DEFAULT>>

(14.2) 01-23-16 06:01:51 (10616:1464)      JOB: Initializing transcoder for datastore <SAP_DS> to transcode between engine codepage<Unicode (UTF-16)> and datastore codepage

                                                <UTF-8>

(14.2) 01-23-16 06:01:51 (10616:1464)      JOB: Initializing transcoder for datastore <AM_O_HOM> to transcode between engine codepage<Unicode (UTF-16)> and datastore codepage

                                                <<DEFAULT>>

(14.2) 01-23-16 06:01:53 (10616:1464) DATAFLOW: Data flow <RC_Clientes_01_KNA1_Def_DT> is started.

(14.2) 01-23-16 06:01:53 (10616:1464) DATAFLOW: Cache statistics for data flow <RC_Clientes_01_KNA1_Def_DT> are not available to be used for optimization. You must collect

                                                statistics before optimization can be done.

(14.2) 01-23-16 06:01:53 (10616:1464) DATAFLOW: Data flow <RC_Clientes_01_KNA1_Def_DT> using PAGEABLE Cache with <3548 MB> buffer pool.

(14.2) 01-23-16 06:20:39 (10616:1464) DATAFLOW: Data flow <RC_Clientes_01_KNA1_Def_DT> is completed successfully.

(14.2) 01-23-16 06:20:39 (10616:1464) DATAFLOW: Process to execute data flow <RC_Clientes_01_KNA1_Def_DT> is completed.

(14.2) 01-23-16 06:20:46 (19432:22236) DATAFLOW: Process to execute data flow <RC_Clientes_02_CampoBusqueda_DT> is started.

(14.2) 01-23-16 06:20:49 (19432:22236) DATAFLOW: Data flow <RC_Clientes_02_CampoBusqueda_DT> is started.

(14.2) 01-23-16 06:20:49 (19432:22236) DATAFLOW: Cache statistics determined that data flow <RC_Clientes_02_CampoBusqueda_DT> uses 0 caches with a total size of 0 bytes, which

                                                 is less than (or equal to) 3745513472 bytes available for caches in virtual memory. Data flow will use IN MEMORY cache type.

(14.2) 01-23-16 06:20:49 (19432:22236) DATAFLOW: Data flow <RC_Clientes_02_CampoBusqueda_DT> using IN MEMORY Cache.

(14.2) 01-23-16 06:20:50 (19432:22236) DATAFLOW: Data flow <RC_Clientes_02_CampoBusqueda_DT> is completed successfully.

(14.2) 01-23-16 06:20:50 (19432:22236) DATAFLOW: Process to execute data flow <RC_Clientes_02_CampoBusqueda_DT> is completed.

(14.2) 01-23-16 06:20:56 (2708:23184) DATAFLOW: Process to execute data flow <RC_Clientes_03_Idioma_DT> is started.

(14.2) 01-23-16 06:20:59 (2708:23184) DATAFLOW: Data flow <RC_Clientes_03_Idioma_DT> is started.

(14.2) 01-23-16 06:20:59 (2708:23184) DATAFLOW: Cache statistics determined that data flow <RC_Clientes_03_Idioma_DT> uses 0 caches with a total size of 0 bytes, which is less

                                                than (or equal to) 3754950656 bytes available for caches in virtual memory. Data flow will use IN MEMORY cache type.

(14.2) 01-23-16 06:20:59 (2708:23184) DATAFLOW: Data flow <RC_Clientes_03_Idioma_DT> using IN MEMORY Cache.

(14.2) 01-23-16 06:20:59 (2708:23184) DATAFLOW: Data flow <RC_Clientes_03_Idioma_DT> is completed successfully.

(14.2) 01-23-16 06:20:59 (2708:23184) DATAFLOW: Process to execute data flow <RC_Clientes_03_Idioma_DT> is completed.

(14.2) 01-23-16 06:21:06 (10008:21460) DATAFLOW: Process to execute data flow <RC_Clientes_04_Nombre3_4_DT> is started.

(14.2) 01-23-16 06:21:08 (10008:21460) DATAFLOW: Data flow <RC_Clientes_04_Nombre3_4_DT> is started.

(14.2) 01-23-16 06:21:08 (10008:21460) DATAFLOW: Cache statistics determined that data flow <RC_Clientes_04_Nombre3_4_DT> uses 0 caches with a total size of 0 bytes, which is

                                                 less than (or equal to) 3742367744 bytes available for caches in virtual memory. Data flow will use IN MEMORY cache type.

(14.2) 01-23-16 06:21:08 (10008:21460) DATAFLOW: Data flow <RC_Clientes_04_Nombre3_4_DT> using IN MEMORY Cache.

(14.2) 01-23-16 06:21:09 (10008:21460) DATAFLOW: Data flow <RC_Clientes_04_Nombre3_4_DT> is completed successfully.

(14.2) 01-23-16 06:21:09 (10008:21460) DATAFLOW: Process to execute data flow <RC_Clientes_04_Nombre3_4_DT> is completed.

(14.2) 01-23-16 06:21:15 (12880:16644) DATAFLOW: Process to execute data flow <RC_Clientes_05_Repetidos_DNAL1_DT> is started.

(14.2) 01-23-16 06:21:18 (12880:16644) DATAFLOW: Data flow <RC_Clientes_05_Repetidos_DNAL1_DT> is started.

(14.2) 01-23-16 06:21:18 (12880:16644) DATAFLOW: Cache statistics for data flow <RC_Clientes_05_Repetidos_DNAL1_DT> are not available to be used for optimization. You must

                                                 collect statistics before optimization can be done.

(14.2) 01-23-16 06:21:18 (12880:16644) DATAFLOW: Data flow <RC_Clientes_05_Repetidos_DNAL1_DT> using PAGEABLE Cache with <3162 MB> buffer pool.

(14.2) 01-23-16 06:21:19 (12880:16644) DATAFLOW: Data flow <RC_Clientes_05_Repetidos_DNAL1_DT> is completed successfully.

(14.2) 01-23-16 06:21:19 (12880:16644) DATAFLOW: Process to execute data flow <RC_Clientes_05_Repetidos_DNAL1_DT> is completed.

(14.2) 01-23-16 06:21:26 (8648:20272) DATAFLOW: Process to execute data flow <RC_Clientes_06_Repetidos_DNAL2_DT> is started.

(14.2) 01-23-16 06:21:28 (8648:20272) DATAFLOW: Data flow <RC_Clientes_06_Repetidos_DNAL2_DT> is started.

(14.2) 01-23-16 06:21:29 (8648:20272) DATAFLOW: Cache statistics for data flow <RC_Clientes_06_Repetidos_DNAL2_DT> are not available to be used for optimization. You must

                                                collect statistics before optimization can be done.

(14.2) 01-23-16 06:21:29 (8648:20272) DATAFLOW: Data flow <RC_Clientes_06_Repetidos_DNAL2_DT> using PAGEABLE Cache with <3569 MB> buffer pool.

(14.2) 01-23-16 06:21:31 (8648:20272) DATAFLOW: Data flow <RC_Clientes_06_Repetidos_DNAL2_DT> is completed successfully.

(14.2) 01-23-16 06:21:31 (8648:20272) DATAFLOW: Process to execute data flow <RC_Clientes_06_Repetidos_DNAL2_DT> is completed.

(14.2) 01-23-16 06:21:38 (1436:7200) DATAFLOW: Process to execute data flow <RC_Clientes_07_Impuestos_PN_DT> is started.

(14.2) 01-23-16 06:21:40 (1436:7200) DATAFLOW: Data flow <RC_Clientes_07_Impuestos_PN_DT> is started.

(14.2) 01-23-16 06:21:40 (1436:7200) DATAFLOW: Cache statistics determined that data flow <RC_Clientes_07_Impuestos_PN_DT> uses 0 caches with a total size of 0 bytes, which

                                               is less than (or equal to) 3754950656 bytes available for caches in virtual memory. Data flow will use IN MEMORY cache type.

(14.2) 01-23-16 06:21:40 (1436:7200) DATAFLOW: Data flow <RC_Clientes_07_Impuestos_PN_DT> using IN MEMORY Cache.

(14.2) 01-23-16 06:21:41 (1436:7200) DATAFLOW: Data flow <RC_Clientes_07_Impuestos_PN_DT> is completed successfully.

(14.2) 01-23-16 06:21:41 (1436:7200) DATAFLOW: Process to execute data flow <RC_Clientes_07_Impuestos_PN_DT> is completed.

(14.2) 01-23-16 06:21:47 (5484:16628) DATAFLOW: Process to execute data flow <RC_Clientes_08_TipoNIT_DT> is started.

(14.2) 01-23-16 06:21:49 (5484:16628) DATAFLOW: Data flow <RC_Clientes_08_TipoNIT_DT> is started.

(14.2) 01-23-16 06:21:50 (5484:16628) DATAFLOW: Cache statistics determined that data flow <RC_Clientes_08_TipoNIT_DT> uses 0 caches with a total size of 0 bytes, which is

                                                less than (or equal to) 3729784832 bytes available for caches in virtual memory. Data flow will use IN MEMORY cache type.

(14.2) 01-23-16 06:21:50 (5484:16628) DATAFLOW: Data flow <RC_Clientes_08_TipoNIT_DT> using IN MEMORY Cache.

(14.2) 01-23-16 06:21:51 (5484:16628) DATAFLOW: Data flow <RC_Clientes_08_TipoNIT_DT> is completed successfully.

(14.2) 01-23-16 06:21:52 (5484:16628) DATAFLOW: Process to execute data flow <RC_Clientes_08_TipoNIT_DT> is completed.

(14.2) 01-23-16 06:21:58 (17076:2632) DATAFLOW: Process to execute data flow <RC_Clientes_09_POBLAC_ZONATR> is started.

(14.2) 01-23-16 06:22:01 (17076:2632) DATAFLOW: Data flow <RC_Clientes_09_POBLAC_ZONATR> is started.

(14.2) 01-23-16 06:22:01 (17076:2632) DATAFLOW: Cache statistics determined that data flow <RC_Clientes_09_POBLAC_ZONATR> uses 0 caches with a total size of 0 bytes, which is

                                                less than (or equal to) 3754950656 bytes available for caches in virtual memory. Data flow will use IN MEMORY cache type.

(14.2) 01-23-16 06:22:01 (17076:2632) DATAFLOW: Data flow <RC_Clientes_09_POBLAC_ZONATR> using IN MEMORY Cache.

(14.2) 01-23-16 06:22:01 (17076:2632) DATAFLOW: Data flow <RC_Clientes_09_POBLAC_ZONATR> is completed successfully.

(14.2) 01-23-16 06:22:01 (17076:2632) DATAFLOW: Process to execute data flow <RC_Clientes_09_POBLAC_ZONATR> is completed.

(14.2) 01-23-16 06:22:08 (11460:10936) DATAFLOW: Process to execute data flow <RC_Clientes_10_Numero_NITS> is started.

(14.2) 01-23-16 06:22:10 (11460:10936) DATAFLOW: Data flow <RC_Clientes_10_Numero_NITS> is started.

(14.2) 01-23-16 06:22:10 (11460:10936) DATAFLOW: Cache statistics determined that data flow <RC_Clientes_10_Numero_NITS> uses 0 caches with a total size of 0 bytes, which is

                                                 less than (or equal to) 3757047808 bytes available for caches in virtual memory. Data flow will use IN MEMORY cache type.

(14.2) 01-23-16 06:22:10 (11460:10936) DATAFLOW: Data flow <RC_Clientes_10_Numero_NITS> using IN MEMORY Cache.

(14.2) 01-23-16 06:22:10 (11460:10936) DATAFLOW: Data flow <RC_Clientes_10_Numero_NITS> is completed successfully.

(14.2) 01-23-16 06:22:10 (11460:10936) DATAFLOW: Process to execute data flow <RC_Clientes_10_Numero_NITS> is completed.

(14.2) 01-23-16 06:22:13 (7028:21632)      JOB: Job <01_Reglas_Clientes> is completed successfully.

[former_member198401]

As I see from the error message

ODBC data source error message for operation SQLExecute Sybase ODBC Driver SQL Anywhere Table Not found.

It seems that the user which is trying to connect to Sybase SQL Anywhere is not having enough permissions on the table. Could you please check the owner of the table and its permissions.

Regards

Arun Sasi

[former_member235282]

Hi Arun

I finally found the root cause.

The issue was because the JOB was scheduled to start twice at the same time,  then in a interval  it was cancelled by  the system (According to my understanding). I have deleted one JOB  from Windows Task Scheduler and now the JOB starts and finishes well.

Thanks a lot

Regards,

DO