Uploaded image for project: 'AdMaxLocal'
  1. AdMaxLocal
  2. AML-3093

"INVALID_BUDGET_DATE_RANGE" error on order deployment

    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>

        Attachments

          Activity

            People

            • Assignee:
              varshini.padmanabhan Varshini Padmanabhan (Inactive)
              Reporter:
              varshini.padmanabhan Varshini Padmanabhan (Inactive)
            • Votes:
              0 Vote for this issue
              Watchers:
              3 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved: