Skip to main content

Hi all, I'm working on an FME workbench for a client that will be run on their FME Server.

Some background:

  • They are running 32-bit Server Engine on a 64-bit Windows box, so I know that it's limited to 4GB RAM
  • The datasets we're using are pretty large, with around 3-3.5 million records being read in between the 5 different readers (the largest reader takes in 1.8 million)
  • All but 63.5k of the total are Esri point geometries with some attributes
  • All datasets must be read in their entirety by nature of the workflow (we can't break it up into smaller datasets with sub callers)
  • I have FME_TEMP set to a folder in a drive with 58GB of free space
  • Speed is secondary - we need it to run, fast can happen later
  • I don't have any user permissions on the FME Server box - I can't edit files, I can't rename files, delete files, move files, NOTHING, so I'll have to pass any recommended changes through my boss who does have full permissions.

I'm getting two major problems (when it's not one, it's the other):

The first and causal error (from what I can gather) is this one: I get the old "Insufficient memory available -- error code was 2 - please read the FME Help section 'Adjusting Memory Resources' for workarounds. " I've already tinkered with the settings of FME_MEMORY_REDLINE to no avail.

As soon as that flashes across the screen and the process terminates, though, I'm unable to make use of the logs. FME Server seems to delete them without backing up once the job fails, so I'm unable to dig in and find more detail. They should be in C:/ProgramData/Safe Software/FME Server/resources/logs/engine/<current or old>/jobs/0... right? I get this error when I try to inspect the logs in the server web view, and that folder is empty when I check after the run:

 

Here's the really weird thing: I watched that folder mid-run last time, and noticed two strange behaviors.

In the FME Server web dashboard, the start time would change every 15 or so minutes, and the log would show what seemed to be the workbench restarting without throwing an error. The only place this is captured is in the status pane, since the log is gone, and it doesn't show that for the first 15 minutes, the start time showed the same as the Submitted time, then updated around 18:45, then updated again at 19:00:

When that change occurred, I got on the FME Server box to check the log folder, and found this (as expected):

Then inside the job_93 folder, there was this:

There was a sub folder and another log file during the run, just after the start time change and apparent restart in the log, but when the job terminated (reason unknown, presumably memory issues), it wiped the log without backing up into the /old folder.

I'm unsure how much of this apparent restart/log wiping behavior is some form of expected behavior, but it's making debugging quite difficult.

As for running out of memory, does anyone have advice besides adjusting FME_MEMORY_REDLINE? And, with so much space in the FME_TEMP folder, why is it not caching locally and skipping straight to running out of memory?

 

UPDATE:

After a call with @hollyatsafe and some others from Safe, we seem to have it working! The issue of logs going missing seems to have been a fluke, and hasn't happened since. Memory issues may still present problems, but we've figured out ways to mitigate that by running spatially clipped subsections of the datasets with feature readers (rather than plain old readers).

 

 

I'll resurrect this thread if these issues persist, but for now we seem to have it under control. Thanks to those who posted answers, and to the Safe folks for the direct help!

Hi @ttaylor43

 

 

What version of FME Server are you using? In more recent versions of FME, altering the redline does next to nothing for controlling memory usage.

 

 

If the job fails (like it is for you) the engine will re-submit it, in the folder for job_93 you should see three logs with the suffix _0 _1 _2 for each resubmit. Then the view job should show the final log.

 

 

One place you could check would be the fmeserver.log file and the processmonitorengine.log - do those have any clues in there?

Hi @ttaylor43,

Jobs are only resubmitted on FME Server if the translation ends unexpectedly, when the connection to an Engine is lost. This is most commonly due to an engine crash, therefore checking the fmeprocessmonitorengine.log as Jen suggested would confirm this, looking for errors similar to 'process engine_1 ended unexpectedly.'

In these circumstances the default resubmit value is 3, so this is why you are seeing a change in the timestamps. There should be a log created for each job run, located in the job id folder. I suspect the Web UI is looking for the final job log, and since there is only a log for the first job (_0) in this folder this is why Web UI says the job log does not exist, however since the timestamps are evenly 15 minutes apart this would suggest the memory is always peaking in the same place so reviewing the Job93_0.log should provide a good indication of where in the translation fmeengine is crashing.

As some of the logs do appear to be missing I'd recommend checking the FME Server clean up tasks to make sure these aren't being removed. If you'd prefer you can also change the FME Server configuration to stop resubmits altogether by editing the MAX_FAILED_TRANSACTION_REQUEST_RETRIES in the fmeserverconfig.txt (http://docs.safe.com/fme/html/FME_Server_Documentation/AdminGuide/Job_Recovery.htm).

Lastly if this is due to an engine crash you could also take a look in Windows Event Viewer over this timestamp which might provide some additional information.

As for the memory error, I actually encountered these exact same symptoms last week, unfortunately I was only able to resolve the issue by increasing the RAM.


Thank you for your responses! We are continuing to investigate the issue and will follow-up with a resolution once one is found.


Reply