You are viewing a plain text version of this content. The canonical link for it is here.
Posted to commits@airflow.apache.org by "joshzana (via GitHub)" <gi...@apache.org> on 2023/06/08 16:39:08 UTC

[GitHub] [airflow] joshzana opened a new issue, #31799: "trigger end" being spuriously logged from triggerer_job_runner at level 100

joshzana opened a new issue, #31799:
URL: https://github.com/apache/airflow/issues/31799

   ### Apache Airflow version
   
   2.6.1
   
   ### What happened
   
   We just upgraded our Airflow instance from 2.5.2 to 2.6.1 and are now noticing errors in our monitoring system that just read "trigger end".  We use an async operator from Fivetran, and all of the reports have fivetran.provider_async.triggers.FivetranTrigger` as the logger name.
   
   I tracked the log down to this line:
   https://github.com/apache/airflow/blob/8d78f903ac9d25870c61ffe762da12796475dcaa/airflow/jobs/triggerer_job_runner.py#L630
   
   AFACIT, level=100 is a super high level that is even higher than critical ([source](https://docs.python.org/3/library/logging.html#levels))
   
   ### What you think should happen instead
   
   The level should be info
   ```
   trigger.log.lnfo"trigger end")
   ```
   
   Or else the log should be removed completely if this was added for debugging purposes.
   
   ### How to reproduce
   
   Make a DAG that uses the operator from https://github.com/astronomer/airflow-provider-fivetran-async
   Run that operator
   
   ### Operating System
   
   Ubuntu 20.04.3 LTS (Focal Fossa)
   
   ### Versions of Apache Airflow Providers
   
   apache-airflow-providers-airbyte==3.2.0
   apache-airflow-providers-amazon==8.0.0
   apache-airflow-providers-celery==3.1.0
   apache-airflow-providers-cncf-kubernetes==6.1.0
   apache-airflow-providers-common-sql==1.4.0
   apache-airflow-providers-docker==3.6.0
   apache-airflow-providers-elasticsearch==4.4.0
   apache-airflow-providers-ftp==3.3.1
   apache-airflow-providers-google==10.0.0
   apache-airflow-providers-grpc==3.1.0
   apache-airflow-providers-hashicorp==3.3.1
   apache-airflow-providers-http==4.3.0
   apache-airflow-providers-imap==3.1.1
   apache-airflow-providers-microsoft-azure==6.0.0
   apache-airflow-providers-mysql==5.0.0
   apache-airflow-providers-odbc==3.2.1
   apache-airflow-providers-postgres==5.4.0
   apache-airflow-providers-redis==3.1.0
   apache-airflow-providers-sendgrid==3.1.0
   apache-airflow-providers-sftp==4.2.4
   apache-airflow-providers-slack==7.2.0
   apache-airflow-providers-snowflake==4.0.5
   apache-airflow-providers-sqlite==3.3.2
   apache-airflow-providers-ssh==3.6.0
   
   ### Deployment
   
   Other Docker-based deployment
   
   ### Deployment details
   
   Kubernetes on AWS
   
   ### Anything else
   
   Problem seems to occur once per invocation of the Fivetran async operator.
   
   ### Are you willing to submit PR?
   
   - [X] Yes I am willing to submit a PR!
   
   ### Code of Conduct
   
   - [X] I agree to follow this project's [Code of Conduct](https://github.com/apache/airflow/blob/main/CODE_OF_CONDUCT.md)
   


-- 
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.

To unsubscribe, e-mail: commits-unsubscribe@airflow.apache.org.apache.org

For queries about this service, please contact Infrastructure at:
users@infra.apache.org


Re: [I] "trigger end" being spuriously logged from triggerer_job_runner at level 100 [airflow]

Posted by "github-actions[bot] (via GitHub)" <gi...@apache.org>.
github-actions[bot] commented on issue #31799:
URL: https://github.com/apache/airflow/issues/31799#issuecomment-2370357916

   This issue has been closed because it has not received response from the issue author.


-- 
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.

To unsubscribe, e-mail: commits-unsubscribe@airflow.apache.org

For queries about this service, please contact Infrastructure at:
users@infra.apache.org


[GitHub] [airflow] joshzana commented on issue #31799: "trigger end" being spuriously logged from triggerer_job_runner at level 100

Posted by "joshzana (via GitHub)" <gi...@apache.org>.
joshzana commented on issue #31799:
URL: https://github.com/apache/airflow/issues/31799#issuecomment-1587624212

   Just to follow up @dstandish I looked over [airflow_local_settings.py](https://github.com/apache/airflow/blob/v2-5-stable/airflow/config_templates/airflow_local_settings.py) and [sentry.py](https://github.com/apache/airflow/blob/v2-6-stable/airflow/sentry.py) and I don't see any recent changes in there that would prevent the "trigger end" log from bleeding out into sentry as an error log.
   
   Interestingly, if I directly tail the triggerer logs from stdout, I don't see the message.  It's only in sentry that this shows up.
   
   Any ideas?


-- 
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.

To unsubscribe, e-mail: commits-unsubscribe@airflow.apache.org

For queries about this service, please contact Infrastructure at:
users@infra.apache.org


[GitHub] [airflow] joshzana commented on issue #31799: "trigger end" being spuriously logged from triggerer_job_runner at level 100

Posted by "joshzana (via GitHub)" <gi...@apache.org>.
joshzana commented on issue #31799:
URL: https://github.com/apache/airflow/issues/31799#issuecomment-1584792040

   Ah interesting! I should have mentioned that we use sentry for error monitoring, using a slightly customized config from what airflow has out of the box. I can take a look at our customizations to see if anything needs to be updated in light of airflow 2.6.1, but as a general rule we get alerted on any logger.error in scheduler, worker, or triggerer. 


-- 
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.

To unsubscribe, e-mail: commits-unsubscribe@airflow.apache.org

For queries about this service, please contact Infrastructure at:
users@infra.apache.org


Re: [I] "trigger end" being spuriously logged from triggerer_job_runner at level 100 [airflow]

Posted by "github-actions[bot] (via GitHub)" <gi...@apache.org>.
github-actions[bot] closed issue #31799: "trigger end" being spuriously logged from triggerer_job_runner at level 100
URL: https://github.com/apache/airflow/issues/31799


-- 
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.

To unsubscribe, e-mail: commits-unsubscribe@airflow.apache.org

For queries about this service, please contact Infrastructure at:
users@infra.apache.org


Re: [I] "trigger end" being spuriously logged from triggerer_job_runner at level 100 [airflow]

Posted by "github-actions[bot] (via GitHub)" <gi...@apache.org>.
github-actions[bot] commented on issue #31799:
URL: https://github.com/apache/airflow/issues/31799#issuecomment-2255093293

   This issue has been automatically marked as stale because it has been open for 365 days without any activity. There has been several Airflow releases since last activity on this issue. Kindly asking to recheck the report against latest Airflow version and let us know if the issue is reproducible. The issue will be closed in next 30 days if no further activity occurs from the issue author.


-- 
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.

To unsubscribe, e-mail: commits-unsubscribe@airflow.apache.org

For queries about this service, please contact Infrastructure at:
users@infra.apache.org


[GitHub] [airflow] dstandish commented on issue #31799: "trigger end" being spuriously logged from triggerer_job_runner at level 100

Posted by "dstandish (via GitHub)" <gi...@apache.org>.
dstandish commented on issue #31799:
URL: https://github.com/apache/airflow/issues/31799#issuecomment-1583957342

   this log message is internal and only used to send a message to the trigger handler wrapper to close the handler if applicable.
   
   it is designed to be filtered out and not visible to users.
   
   are you seeing in the logs in your env?


-- 
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.

To unsubscribe, e-mail: commits-unsubscribe@airflow.apache.org

For queries about this service, please contact Infrastructure at:
users@infra.apache.org


Re: [I] "trigger end" being spuriously logged from triggerer_job_runner at level 100 [airflow]

Posted by "oku95 (via GitHub)" <gi...@apache.org>.
oku95 commented on issue #31799:
URL: https://github.com/apache/airflow/issues/31799#issuecomment-2514855063

   @joshzana  did you manage to filter this error from sentry? 


-- 
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.

To unsubscribe, e-mail: commits-unsubscribe@airflow.apache.org

For queries about this service, please contact Infrastructure at:
users@infra.apache.org