Mailing-List: contact chukwa-dev-help@hadoop.apache.org; run by ezmlm
Precedence: bulk
Reply-To: chukwa-dev@hadoop.apache.org
Message-ID: <1201205797.1245366367395.JavaMail.jira@brutus>
Date: Thu, 18 Jun 2009 16:06:07 -0700 (PDT)
From: "Cheng (JIRA)" <jira@apache.org>
To: chukwa-dev@hadoop.apache.org
Subject: [jira] Commented: (CHUKWA-314) START_TIME="0" for failed task
 throws SQL Exception
In-Reply-To: <1268062116.1245364327336.JavaMail.jira@brutus>
MIME-Version: 1.0
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 7bit


    [ https://issues.apache.org/jira/browse/CHUKWA-314?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12721541#action_12721541 ] 

Cheng commented on CHUKWA-314:
------------------------------

+1 Looks good. 


> START_TIME="0" for failed task throws SQL Exception
> ---------------------------------------------------
>
>                 Key: CHUKWA-314
>                 URL: https://issues.apache.org/jira/browse/CHUKWA-314
>             Project: Hadoop Chukwa
>          Issue Type: Bug
>            Reporter: Eric Yang
>         Attachments: CHUKWA-314.patch
>
>
> 2009-06-18 16:38:32,301 ERROR pool-1-thread-3 MetricDataLoader - java.sql.BatchUpdateException: Data truncation:
> Incorrect datetime value: '1970-01-01 00:00:00' for column 'start_time' at row 1
> java.sql.BatchUpdateException: Data truncation: Incorrect datetime value: '1970-01-01 00:00:00' for column 'start_time'
> at row 1
>         at com.mysql.jdbc.StatementImpl.executeBatch(StatementImpl.java:1007)
>         at org.apache.hadoop.chukwa.dataloader.MetricDataLoader.run(MetricDataLoader.java:484)
>         at org.apache.hadoop.chukwa.dataloader.MetricDataLoader.call(MetricDataLoader.java:561)
>         at org.apache.hadoop.chukwa.dataloader.MetricDataLoader.call(MetricDataLoader.java:50)
>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>         at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
>         at java.lang.Thread.run(Thread.java:619)
> 2009-06-18 16:38:32,301 ERROR pool-1-thread-3 MetricDataLoader - SQLException: Data truncation: Incorrect datetime
> value: '1970-01-01 00:00:00' for column 'start_time' at row 1
> The data in the sequence file contains:
> Key: 1245355200000/job_200906170135_0110/1245355568122
>          ===== Value =====
> Timestamp : 1245355568122
> [ERROR] :Error initializing
> attempt_200906170135_0110_m_000004_0:org\.apache\.hadoop\.util\.DiskChecker$DiskErrorException: Could not find any
> valid local directory for taskTracker/jobcache/job_200906170135_0110/job\.xmlat
> org\.apache\.hadoop\.fs\.LocalDirAllocator$AllocatorPerContext\.getLocalPathForWrite(LocalDirAllocator\.java:335)at
> org\.apache\.hadoop\.fs\.LocalDirAllocator\.getLocalPathForWrite(LocalDirAllocator\.java:124)at
> org\.apache\.hadoop\.mapred\.TaskTracker\.localizeJob(TaskTracker\.java:799)at
> org\.apache\.hadoop\.mapred\.TaskTracker\.startNewTask(TaskTracker\.java:1743)at
> org\.apache\.hadoop\.mapred\.TaskTracker\.access$1200(TaskTracker\.java:97)at
> org\.apache\.hadoop\.mapred\.TaskTracker$TaskLauncher\.run(TaskTracker\.java:1708)
> [FINISH_TIME] :1245268355013
> [HOSTNAME] :hostname
> [JOBID] :job_200906170135_0110
> [TASKID] :task_200906170135_0110_m_000004
> [TASK_ATTEMPT_ID] :attempt_200906170135_0110_m_000004_0
> [TASK_ATTEMPT_TIMES] :1
> [TASK_STATUS] :FAILED
> [TASK_TYPE] :MAP
> [capp]
> :/var/log/history/hostname_1245202542124_job_200906170135_0110_user_Chukwa-Demux_20090617_19_51
> [csource] :hostname
> [ctags] : cluster="chukwadev"
> Key: 1245355200000/job_200906170135_0110/1245355568122
>          ===== Value =====
> Timestamp : 1245355568122
> [HTTP_PORT] :50060
> [JOBID] :job_200906170135_0110
> [START_TIME] :0
> [TASKID] :task_200906170135_0110_m_000004
> [TASK_ATTEMPT_ID] :attempt_200906170135_0110_m_000004_0
> [TASK_ATTEMPT_TIMES] :1
> [TASK_TYPE] :MAP
> [TRACKER_NAME] :tracker_hostname:localhost/127\.0\.0\.1:57852
> [capp]
> :/var/log/history/hostname_1245202542124_job_200906170135_0110_user_Chukwa-Demux_20090617_19_51
> [csource] :hostname
> [ctags] : cluster="chukwadev"
> START_TIME is 0 which is out of SQL time range.
> mysql> INSERT INTO mr_task_2059_week SET type='map', task_id='attempt_200906170135_0110_m_000004_0',
> job_id='job_200906170135_0110', attempts=1, start_time="1970-01-01 00:00:00" ON DUPLICATE KEY UPDATE
> type='map', task_id='attempt_200906170135_0110_m_000004_0', job_id='job_200906170135_0110', attempts=1,
> start_time="1970-01-01 00:00:00";
> Query OK, 0 rows affected, 2 warnings (0.00 sec)
> mysql> show warnings;
> +---------+------+-----------------------------------------------------+
> | Level   | Code | Message                                             |
> +---------+------+-----------------------------------------------------+
> | Warning | 1264 | Out of range value for column 'start_time' at row 1 | 
> | Warning | 1264 | Out of range value for column 'start_time' at row 1 | 
> +---------+------+-----------------------------------------------------+
> 2 rows in set (0.01 sec)
> The error reported by MDL is correct from SQL prespective.  The map reduce job never had a chance to start due to
> error. start_time=0 is correct from the log file, however, this should be filtered in demux as part of invalid
> values.

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.