Filter log


The filter log file shows all of the filters that were checked during an operation. If a filter was executed, the log also provides a list of the operations performed.

By default, the log file is named arfilter.log

This log is especially useful for checking the interaction of your filters and for verifying whether a filter is performing correctly.

The filter log format is similar to the SQL log format, but the information in the filter log is displayed over multiple lines. The log has the following characteristics:

  • Each line starts with a tag that identifies it as being a filter logging line (<FLTR> ).
  • A time stamp line is written for every operation that triggers filters.
  • A line indicates that processing has started and the operation is being performed.
  • A line provides details about the workflow object mode, indicating whether the object is Unmodified, Overlay object, or Custom. This information helps in easily troubleshooting filter related issues. Additionally, the log specifies the filter stack level and the corresponding filter number within the stack.
  • As each filter is checked, a message is written that includes the name of the filter, its escalation order, an indication of whether the filter passed the qualification, and any action taken with their results.
  • The last line contains a closing time stamp.

Phases 1, 2, and 3 of filter processing appear in the filter log. As AR System checks filters, all notify and run process actions are deferred to phase 3. Phase 3 begins when phases 1 and 2 are completed, and the database has been updated. In phase 3, all deferred filter actions are run. This information enables you to track the filters that are being checked, the filters that are running, and the actions that are being performed.

For more information about filter processing, see Filter-processing.

The following example shows a portion of a filter log file:

<FLTR> <TrID: c6u0pPltSSm22MD2XzSK8w:0000001> <TID: 0000000497> <RPC ID: 0000105265> <Queue: Fast      > <Client-RPC: 390620   > <USER: Remedy Application Service                   > <Overlay-Group: 1         > /* 2025-04-18T17:02:38.547+0000 */    Start filter processing (phase 1) -- Operation - GET on AR System Currency Codes - 000000000000001
<FLTR> <TrID: c6u0pPltSSm22MD2XzSK8w:0000001> <TID: 0000000497> <RPC ID: 0000105265> <Queue: Fast      > <Client-RPC: 390620   > <USER: Remedy Application Service                   > <Overlay-Group: 1         > /* 2025-04-18T17:02:38.547+0000 */    End of filter processing (phase 1) -- Operation - GET on AR System Currency Codes - 000000000000001
<FLTR> <TrID: Wvm-l6rdRoaTM430ySg1mQ:0000002> <TID: 0000004250> <RPC ID: 0000104673> <Queue: Escalation> <Client-RPC: 390603   > <USER: AR_ESCALATOR                                 > <Overlay-Group: 1         > /* 2025-04-18T17:02:38.797+0000 */    Start filter processing (phase 1) -- Operation - SET on AR System Job - 000000000000001
<FLTR> <TrID: Wvm-l6rdRoaTM430ySg1mQ:0000002> <TID: 0000004250> <RPC ID: 0000104673> <Queue: Escalation> <Client-RPC: 390603   > <USER: AR_ESCALATOR                                 > <Overlay-Group: 1         > /* 2025-04-18T17:02:38.797+0000 */ <Filter Level:1 Number Of Filters:0> Checking "ASJ:GetNextCollectionTime" (500)" [Unmodified]
<FLTR> <TrID: Wvm-l6rdRoaTM430ySg1mQ:0000002> <TID: 0000004250> <RPC ID: 0000104673> <Queue: Escalation> <Client-RPC: 390603   > <USER: AR_ESCALATOR                                 > <Overlay-Group: 1         > /* 2025-04-18T17:02:38.797+0000 */    --> Failed qualification
<FLTR> <TrID: Wvm-l6rdRoaTM430ySg1mQ:0000002> <TID: 0000004250> <RPC ID: 0000104673> <Queue: Escalation> <Client-RPC: 390603   > <USER: AR_ESCALATOR                                 > <Overlay-Group: 1         > /* 2025-04-18T17:02:38.797+0000 */    End of filter processing (phase 1) -- Operation - SET on AR System Job - 000000000000001
<FLTR> <TrID: Bn29lotiTLmCdgjLmxts7g:0000001> <TID: 0000004250> <RPC ID: 0000000000> <Queue: Init      > <Client-RPC: 10       > <USER: ARSERVER                                     > <Overlay-Group: 1         > /* 2025-04-18T17:02:38.798+0000 */    Start filter processing (phase 1) -- Operation - MERGE on AR Escalation Statistics - 000000000000168
<FLTR> <TrID: Bn29lotiTLmCdgjLmxts7g:0000001> <TID: 0000004250> <RPC ID: 0000000000> <Queue: Init      > <Client-RPC: 10       > <USER: ARSERVER                                     > <Overlay-Group: 1         > /* 2025-04-18T17:02:38.798+0000 */    End of filter processing (phase 1) -- Operation - MERGE on AR Escalation Statistics - 000000000000168
<FLTR> <TrID: WbjjThemR0a1m0XaYyszQQ:0000001> <TID: 0000004250> <RPC ID: 0000000000> <Queue: Escalation> <Client-RPC: 390603   > <USER: ARSERVER                                     > <Overlay-Group: 1         > /* 2025-04-18T17:02:39.802+0000 */    Start filter processing (phase 1) -- Operation - MERGE on AR Escalation Statistics - 000000000000168
<FLTR> <TrID: WbjjThemR0a1m0XaYyszQQ:0000001> <TID: 0000004250> <RPC ID: 0000000000> <Queue: Escalation> <Client-RPC: 390603   > <USER: ARSERVER                                     > <Overlay-Group: 1         > /* 2025-04-18T17:02:39.802+0000 */    End of filter processing (phase 1) -- Operation - MERGE on AR Escalation Statistics - 000000000000168

The logs in the example show several filter processing cycles during phase 1 for different operations like GET, SET, and MERGE on various records. One filter checks a condition but fails qualification, so it takes no action.

When the log indicates a failed qualification, it does not mean that the filter malfunctioned. It means that the qualification was not met for this operation, so the else action runs, if present.

Tracking filter overlays

When the server executes a filter overlay, the filter log uses the real name of the overlay (<filterObjectName__o>). It also records the following message:

skipping overlaid object <filterObjectName>

When overlays are disabled on the server and the server executes an overlaid filter object, it records  the following message:

skipping overlay object <filterObjectName__o>

For information about overlay objects, see Customizing-applications-using-overlays-and-custom-objects.

 

 

Tip: For faster searching, add an asterisk to the end of your partial query. Example: cert*