Details
-
Type: Bug
-
Status: Closed
-
Priority: Major
-
Resolution: Fixed
-
Affects Version/s: Matador
-
Fix Version/s: Matador
-
Component/s: API
-
Labels:None
-
Environment:
Bidmgr: bidmgr-distribution-2.6.1-20140908.130648-18-bin.tar.gz
Core: smb-ear-2.6.1-SNAPSHOT-t20140820-173837.ear
Sapi: aml-sapi-2.6.1-20140819.190258-35.war
-
Sprint:Sprint 3
Description
I see the following error when I deploy an order. The order gets successfully deployed. I am able to edit/delete the order successfully.
Listener log,
2014-09-11 09:42:07.193 (2) [P7T1]: 1 Errors found
2014-09-11 09:42:07.193 (2) [P7T1]: - com.thesearchagency.searchengines.google.GoogleAdWordsAPIReseller$ResellerApiError: BudgetOrderError.INVALID_BUDGET_DATE_RANGE: Error Type=RESELLER field=operations[0].operand.startDateTime, trigger=Start date cannot be in the past
2014-09-11 09:42:07.193 (2) [P7T1]: com.thesearchagency.searchengines.google.GoogleAdWordsAPIReseller$ResellerApiError: ApiError: fieldpath=operations[0].operand.startDateTime, trigger=Start date cannot be in the past apierrortype=RESELLER, reason=BudgetOrderError.INVALID_BUDGET_DATE_RANGE
2014-09-11 09:42:07.193 (3) [P7T1]: Not retrying Google error. message1=[BudgetOrderError.INVALID_BUDGET_DATE_RANGE @ operations[0].operand.startDateTime; trigger:'Start date cannot be in the past']
2014-09-11 09:42:07.193 (3) [P7T1]: Caught other/unrecoverable API Exception: "com.google.api.ads.adwords.axis.v201406.cm.ApiException"
2014-09-11 09:42:07.193 (4) [P7T1]: sesystem: SQL->[select 1]
2014-09-11 09:42:07.195 (4) [P7T1]: time=0.0020
2014-09-11 09:42:07.195 (4) [P7T1]: Query executed in 0.002s
2014-09-11 09:42:07.195 (4) [P7T1]: sesystem: SQL->[update `sesystem`.`searchEngineConnections` set `holder`="",`inUse`="false",`holderIdentifier`="",`timeStamp`="2014-09-11 09:42:07" where ((`sesystem`.`searchEngineConnections`.`distributionID`=3) and (`sesystem`.`searchEngineConnections`.`inUse`="true") and (`sesystem`.`searchEngineConnections`.`holder`="addBudget") and (`sesystem`.`searchEngineConnections`.`holderIdentifier`="null") and (`sesystem`.`searchEngineConnections`.`searchEngineUserID`=0)) limit 1], genflags=no
2014-09-11 09:42:07.196 (4) [P7T1]: time=0.0010
2014-09-11 09:42:07.196 (4) [P7T1]: Query executed in 0.001s, 1 row(s) affected
2014-09-11 09:42:07.196 (4) [P7T1]: null released searchEngineConnection row
2014-09-11 09:42:07.196 (4) [P7T1]: sesystem: SQL->[select 1]
2014-09-11 09:42:07.197 (4) [P7T1]: time=0.0010
2014-09-11 09:42:07.197 (4) [P7T1]: Query executed in 0.001s
2014-09-11 09:42:07.197 (4) [P7T1]: sesystem: SQL->[select * from `sesystem`.`searchEngineResources` where ((`distributionID`=3) and (`operation`="addBudget")) limit 1]
2014-09-11 09:42:07.198 (4) [P7T1]: time=0.0010
2014-09-11 09:42:07.198 (4) [P7T1]: Query executed in 0.001s
2014-09-11 09:42:07.199 (4) [P7T1]: Record Loaded
2014-09-11 09:42:07.199 (4) [P7T1]: sesystem: SQL->[insert into `sesystem`.`searchEngineResourceUsage` (`sesystem`.`searchEngineResourceUsage`.`resourceID`,`sesystem`.`searchEngineResourceUsage`.`periodStart`,`sesystem`.`searchEngineResourceUsage`.`holderID`,`sesystem`.`searchEngineResourceUsage`.`lastUsage`,`sesystem`.`searchEngineResourceUsage`.`opCount`) values (213,"2014-09-11 07:00:00","null",NOW(),1) on duplicate key update `sesystem`.`searchEngineResourceUsage`.`opCount`=`sesystem`.`searchEngineResourceUsage`.`opCount`+1,`sesystem`.`searchEngineResourceUsage`.`lastUsage`=NOW()], genflags=no
2014-09-11 09:42:07.200 (4) [P7T1]: time=0.0010
2014-09-11 09:42:07.200 (4) [P7T1]: Query executed in 0.001s, 1 row(s) affected
2014-09-11 09:42:07.200 (2) [P7T1]: sendAlert: executing command: [/usr/local/tsa/send_alarm.sh, WARNING, TSAOPS, AdMax Application [GoogleContentEditor]: Service - GCE: TSA Account [239]: Unable to addBudget for customerID 3584950063]
2014-09-11 09:42:07.221 (4) [P7T1]: DataCache: Found key global-sesystem
2014-09-11 09:42:07.222 (4) [P7T1]: Took Existing Object from Pool
2014-09-11 09:42:07.222 (4) [P7T1]: sesystem: SQL->[select @@version]
2014-09-11 09:42:07.223 (4) [P7T1]: time=0.0010
2014-09-11 09:42:07.223 (4) [P7T1]: Query executed in 0.001s
2014-09-11 09:42:07.223 (4) [P7T1]: test query succeeded, returned "5.5.36"
2014-09-11 09:42:07.223 (4) [P7T1]: sesystem: SQL->[SELECT RELEASE_LOCK('com.thesearchagency.searchengines.google.contenteditor.dao.account.createAccountsLock')]
2014-09-11 09:42:07.224 (4) [P7T1]: time=0.0010
2014-09-11 09:42:07.224 (4) [P7T1]: Query executed in 0.001s
2014-09-11 09:42:07.225 (4) [P7T1]: Returning Object to Pool
2014-09-11 09:42:07.225 (4) [P7T1]: Exception [handling exception thrown by google...]:com.thesearchagency.searchengines.SearchEngineCommandException:
at com.thesearchagency.searchengines.google.GoogleAdWordsAPIReseller.addBudget(GoogleAdWordsAPIReseller.java:374)
at com.thesearchagency.searchengines.google.contenteditor.changerequest.GoogleAccountChangeRequest.addBudget(GoogleAccountChangeRequest.java:295)
at com.thesearchagency.searchengines.google.contenteditor.changerequest.GoogleAccountChangeRequest.addBudgets(GoogleAccountChangeRequest.java:273)
at com.thesearchagency.searchengines.google.contenteditor.changerequest.GoogleAccountChangeRequest.postRequestAction(GoogleAccountChangeRequest.java:191)
at com.thesearchagency.searchengines.google.contenteditor.changerequest.GoogleAccountChangeRequest.postRequestAction(GoogleAccountChangeRequest.java:43)
at com.thesearchagency.searchengines.google.contenteditor.changerequest.BaseChangeRequest.postRequest(BaseChangeRequest.java:589)
at com.thesearchagency.searchengines.google.contenteditor.changerequest.BaseChangeRequest.makeRequest(BaseChangeRequest.java:213)
at com.thesearchagency.searchengines.google.contenteditor.changerequest.GoogleAccountChangeRequest.makeRequest(GoogleAccountChangeRequest.java:95)
at com.thesearchagency.searchengines.google.contenteditor.GoogleContentEditor$AccountWorker.createAccounts(GoogleContentEditor.java:978)
at com.thesearchagency.searchengines.google.contenteditor.GoogleContentEditor$AccountWorker.call(GoogleContentEditor.java:953)
at com.thesearchagency.searchengines.google.contenteditor.GoogleContentEditor$AccountWorker.call(GoogleContentEditor.java:941)
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:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
Caused by:
at com.thesearchagency.searchengines.google.GoogleAdWordsAPIReseller.convertToNonResellerException(GoogleAdWordsAPIReseller.java:547)
at com.thesearchagency.searchengines.google.GoogleAdWordsAPIReseller.addBudget(GoogleAdWordsAPIReseller.java:358)
... 15 more
2014-09-11 09:42:07.225 (4) [P7T1]: DB AutoCommit -> true
2014-09-11 09:42:07.226 (4) [P7T1]: spike: SQL->[delete from `content_editor_google`.`gce_googleAccountError` where (`googleAccountID` in (234))], genflags=no
2014-09-11 09:42:07.227 (4) [P7T1]: time=0.0010
2014-09-11 09:42:07.227 (4) [P7T1]: Query executed in 0.001s, 0 row(s) affected
2014-09-11 09:42:07.227 (4) [P7T1]: DB AutoCommit -> false
2014-09-11 09:42:07.227 (4) [P7T1]: preparing SQL statement [insert into `gce_googleAccountError` (`googleAccountID`,`errorCode`,`errorText`,`reason`,`policyName`,`field`,`trigger`,`isExemptable`) VALUES (?,?,?,?,?,?,?,?)]
2014-09-11 09:42:07.228 (4) [P7T1]: spike: SQL->[start transaction]
2014-09-11 09:42:07.229 (4) [P7T1]: time=0.0010
2014-09-11 09:42:07.229 (4) [P7T1]: Query executed in 0.001s
2014-09-11 09:42:07.229 (4) [P7T1]: spike: commitBatch
2014-09-11 09:42:07.230 (4) [P7T1]: spike: SQL->[commit]
2014-09-11 09:42:07.230 (4) [P7T1]: time=0.0
2014-09-11 09:42:07.230 (4) [P7T1]: Query executed in 0.000s
2014-09-11 09:42:07.230 (4) [P7T1]: spike: SQL->[start transaction]
2014-09-11 09:42:07.231 (4) [P7T1]: time=0.0010
2014-09-11 09:42:07.231 (4) [P7T1]: Query executed in 0.001s
2014-09-11 09:42:07.231 (4) [P7T1]: time=0.0020
2014-09-11 09:42:07.231 (4) [P7T1]: Query executed in 0.002s
2014-09-11 09:42:07.231 (4) [P7T1]: DB AutoCommit -> true
2014-09-11 09:42:07.232 (4) [P7T1]: spike: SQL->[update `content_editor_google`.`gce_googleAccountPendingChange` set `isFailed`="true",`tryCount`=`gce_googleAccountPendingChange`.`tryCount` + 1 where (`googleAccountID` in (234))], genflags=no
2014-09-11 09:42:07.233 (4) [P7T1]: time=0.0010
2014-09-11 09:42:07.233 (4) [P7T1]: Query executed in 0.001s, 0 row(s) affected
2014-09-11 09:42:07.233 (4) [P7T1]: DB AutoCommit -> true
2014-09-11 09:42:07.233 (3) [P7T1]: GoogleAccountDAO(accountID 239): Inserted 1 Errors: Updated 0 pending changes, cleared 0 existing errors.
2014-09-11 09:42:07.233 (4) [P7T1]: spike: SQL->[select `content_editor_google`.`gce_googleAccount`.*,`content_editor_google`.`gce_googleAccountPendingChange`.*,`gce_googleAccountPendingUpdate`.`descriptiveName` AS `descriptiveNameChange` from `content_editor_google`.`gce_googleAccount` join `gce_googleAccountPendingChange` on ((`content_editor_google`.`gce_googleAccountPendingChange`.`googleAccountID`=`content_editor_google`.`gce_googleAccount`.`id`)) inner join `gce_googleAccountPendingUpdate` on ((`gce_googleAccountPendingUpdate`.`googleAccountID`=`gce_googleAccount`.`id`)) where ((((((`isFailed`="true") and (`tryCount`<10))) or ((`isFailed`="false")))) and (`type`="update") and (`accountID`=239))]
2014-09-11 09:42:07.235 (4) [P7T1]: time=0.0020
2014-09-11 09:42:07.235 (4) [P7T1]: Query executed in 0.002s
2014-09-11 09:42:07.235 (4) [P7T1]: spike: SQL->[select `content_editor_google`.`gce_googleAccount`.*,`content_editor_google`.`gce_googleAccountPendingChange`.* from `content_editor_google`.`gce_googleAccount` join `gce_googleAccountPendingChange` on ((`content_editor_google`.`gce_googleAccountPendingChange`.`googleAccountID`=`content_editor_google`.`gce_googleAccount`.`id`)) where ((((((`isFailed`="true") and (`tryCount`<10))) or ((`isFailed`="false")))) and (`type`="delete") and (`accountID`=239))]
2014-09-11 09:42:07.236 (4) [P7T1]: time=0.0010
2014-09-11 09:42:07.236 (4) [P7T1]: Query executed in 0.001s
2014-09-11 09:42:07.236 (4) [P7T1]: Returning Object to Pool
2014-09-11 09:42:07.236 (4) [P7T1]: DAO released connection to pool
2014-09-11 09:42:07.238 (2) [P7T1]: Google Request Errors for accountID 239(GoogleAccountChangeRequest)
id=234, code=0: RESELLER(reason: BudgetOrderError.INVALID_BUDGET_DATE_RANGE)(isExemptable: false), trigger=Start date cannot be in the past, field=operations[0].operand.startDateTime
2014-09-11 09:42:07.239 (4) [pool-3-thread-4]: closed [jdbc:mysql://tsacommon-01-write/tsacommon?useUnicode=true&zeroDateTimeBehavior=convertToNull&dontTrackOpenResources=true&jdbcCompliantTruncation=false&useServerPrepStmts=false&rewriteBatchedStatements=true]
2014-09-11 09:42:07.240 (3) [pool-3-thread-4]: ------------Google Content Editor done
2014-09-11 09:42:07.240 (4) [pool-3-thread-4]: DataCache: Found key acct-content-google 239
2014-09-11 09:42:07.240 (4) [pool-3-thread-4]: DataCache: Found key acct-content-google-1
2014-09-11 09:42:07.240 (4) [pool-3-thread-4]: Took Existing Object from Pool
2014-09-11 09:42:07.240 (4) [pool-3-thread-4]: spike: SQL->[select @@version]
2014-09-11 09:42:07.241 (4) [pool-3-thread-4]: time=0.0010
Soap log,
<?xml version="1.0" encoding="UTF-8"?>
<soapenv:Envelope xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/" xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance">
<soapenv:Header>
<ns1:RequestHeader xmlns:ns1="https://adwords.google.com/api/adwords/billing/v201406" soapenv:mustUnderstand="0">
<ns2:clientCustomerId xmlns:ns2="https://adwords.google.com/api/adwords/cm/v201406">2983994275</ns2:clientCustomerId>
<ns3:developerToken xmlns:ns3="https://adwords.google.com/api/adwords/cm/v201406">ojCnjZaM6RD1h0yt_DOJZg</ns3:developerToken>
<ns4:userAgent xmlns:ns4="https://adwords.google.com/api/adwords/cm/v201406">AdMaxLocal AdWords Software TSA QA (AwApi-Java, AdWords-Axis/1.31.0, Common-Java/1.31.0, Axis/1.4, Java/1.6.0_27, mav
en)</ns4:userAgent>
<ns5:validateOnly xmlns:ns5="https://adwords.google.com/api/adwords/cm/v201406">false</ns5:validateOnly>
<ns6:partialFailure xmlns:ns6="https://adwords.google.com/api/adwords/cm/v201406">false</ns6:partialFailure>
</ns1:RequestHeader>
</soapenv:Header>
<soapenv:Body>
<mutate xmlns="https://adwords.google.com/api/adwords/billing/v201406">
<operations>
<ns7:operator xmlns:ns7="https://adwords.google.com/api/adwords/cm/v201406">ADD</ns7:operator>
<operand>
<billingAccountId>3491813825</billingAccountId>
<spendingLimit>
<ns8:microAmount xmlns:ns8="https://adwords.google.com/api/adwords/cm/v201406">900000000000000</ns8:microAmount>
</spendingLimit>
<startDateTime>20140911 064717 America/New_York</startDateTime>
<endDateTime>20371230 235959 America/Los_Angeles</endDateTime>
</operand>
</operations>
</mutate>
</soapenv:Body>
</soapenv:Envelope>
***********
2014-09-11 10:47:18,093 WARN AdsServiceLoggers:
***********
SOAP Response:
<?xml version="1.0" encoding="UTF-8"?>
<soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
<soap:Header>
<ResponseHeader xmlns:ns2="https://adwords.google.com/api/adwords/cm/v201406" xmlns="https://adwords.google.com/api/adwords/billing/v201406">
<ns2:requestId>000502c7fad7cb380aec26869a002692</ns2:requestId>
<ns2:serviceName>BudgetOrderService</ns2:serviceName>
<ns2:methodName>mutate</ns2:methodName>
<ns2:operations>0</ns2:operations>
<ns2:responseTime>331</ns2:responseTime>
</ResponseHeader>
</soap:Header>
<soap:Body>
<soap:Fault>
<faultcode>soap:Server</faultcode>
<faultstring>[BudgetOrderError.INVALID_BUDGET_DATE_RANGE @ operations[0].operand.startDateTime; trigger:'Start date cannot be in the past']</faultstring>
<detail>
<ApiExceptionFault xmlns="https://adwords.google.com/api/adwords/billing/v201406" xmlns:ns2="https://adwords.google.com/api/adwords/cm/v201406">
<ns2:message>[BudgetOrderError.INVALID_BUDGET_DATE_RANGE @ operations[0].operand.startDateTime; trigger:'Start date cannot be in the past']</ns2:message>
<ns2:ApplicationException.Type>ApiException</ns2:ApplicationException.Type>
<ns2:errors xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="BudgetOrderError">
<ns2:fieldPath>operations[0].operand.startDateTime</ns2:fieldPath>
<ns2:trigger>Start date cannot be in the past</ns2:trigger>
<ns2:errorString>BudgetOrderError.INVALID_BUDGET_DATE_RANGE</ns2:errorString>
<ns2:ApiError.Type>BudgetOrderError</ns2:ApiError.Type>
<reason>INVALID_BUDGET_DATE_RANGE</reason>
</ns2:errors>
</ApiExceptionFault>
</detail>
</soap:Fault>
</soap:Body>
</soap:Envelope>