data61 / anonlink-entity-service

Privacy Preserving Record Linkage Service
Apache License 2.0
26 stars 8 forks source link

Too many database connections #385

Closed hardbyte closed 5 years ago

hardbyte commented 5 years ago

This log shows a failure case where the flask api dies when the database refuses connections:

 2019-06-08T23:44:18.462994Z [debug    ] Added data providers           [entityservice.models.project] 
2019-06-08T23:44:18.463172Z [debug    ] Committing transaction         [entityservice.models.project] 
2019-06-08T23:46:05.971327Z [debug    ] Connecting to redis            [entityservice.cache] 
2019-06-08T23:46:05.971698Z [debug    ] Looking up read only redis slave using sentinel protocol [entityservice.cache] 
2019-06-08T23:46:05.986124Z [debug    ] Saving the service status to redis cache [entityservice.cache] 
2019-06-08T23:46:05.986555Z [debug    ] Connecting to redis            [entityservice.cache] 
2019-06-08T23:46:05.986765Z [debug    ] Looking up redis master using sentinel protocol [entityservice.cache] 
2019-06-08T23:46:14.926295Z [debug    ] checking authorization token to update data [entityservice.views.auth_checks] 
2019-06-08T23:46:14.930537Z [warning  ] fallback connecting to default postgres db [entityservice.database.util] database=postgres host=june-testing-anonlink-db user=postgres
2019-06-08T23:46:14.934326Z [warning  ] Can't connect to database      [entityservice.database.util] 
[2019-06-08 23:46:14 +0000] [10] [ERROR] Socket error processing request.
Traceback (most recent call last):
  File "/var/www/entityservice/database/util.py", line 38, in connect_db
    conn = psycopg2.connect(database=db, user=user, password=pw, host=host)
  File "/usr/lib/python3.6/site-packages/psycopg2/__init__.py", line 130, in connect
    conn = _connect(dsn, connection_factory=connection_factory, **kwasync)
psycopg2.OperationalError: FATAL:  sorry, too many clients already 

The postgres container logs aren't very interesting:

 2019-06-08 23:42:04.435 GMT [4671] FATAL:  sorry, too many clients already
2019-06-08 23:42:05.976 GMT [4672] FATAL:  sorry, too many clients already
2019-06-08 23:42:14.423 GMT [4688] FATAL:  sorry, too many clients already
...
2019-06-08 23:51:31.811 GMT [5497] FATAL:  sorry, too many clients already
2019-06-08 23:51:34.459 GMT [5504] FATAL:  sorry, too many clients already 
hardbyte commented 5 years ago

For the flask app in backend/database/util.py we have a single point where we get a database connection. In theory being attached to the application context it shares one db connection for a whole request, but perhaps we should be using a connection pool here. See example gist

Some links on connection pooling:

Example using psycopg2:

    import psycopg2
    from psycopg2 import pool
    try:
        threaded_postgreSQL_pool = psycopg2.pool.ThreadedConnectionPool(5, 20,user = "postgres",
                                                  password = "pass@#29",
                                                  host = "127.0.0.1",
                                                  port = "5432",
                                                  database = "postgres_db")
        if(threaded_postgreSQL_pool):
            print("Connection pool created successfully using ThreadedConnectionPool")
        # Use getconn() method to Get Connection from connection pool
        ps_connection  = threaded_postgreSQL_pool.getconn()
        if(ps_connection):
            print("successfully recived connection from connection pool ")
            ps_cursor = ps_connection.cursor()
            ps_cursor.execute("select * from mobile")
            mobile_records = ps_cursor.fetchmany(2)
            print ("Displaying rows from mobile table")
            for row in mobile_records:
                print (row)
            ps_cursor.close()
            #Use this method to release the connection object and send back ti connection pool
            threaded_postgreSQL_pool.putconn(ps_connection)
            print("Put away a PostgreSQL connection")
    except (Exception, psycopg2.DatabaseError) as error :
        print ("Error while connecting to PostgreSQL", error)
    finally:
        #closing database connection.
        # use closeall method to close all the active connection if you want to turn of the application
        if (threaded_postgreSQL_pool):
            threaded_postgreSQL_pool.closeall
        print("Threaded PostgreSQL connection pool is closed")
hardbyte commented 5 years ago
select  * from
     (select count(*) used from pg_stat_activity) q1,
     (select setting::int res_for_super from pg_settings where name=$$superuser_reserved_connections$$) q2,
     (select setting::int max_conn from pg_settings where name=$$max_connections$$) q3
usedres_for_supermax_conn
63100
gusmith commented 5 years ago

Really interesting. I ran the big benchmark on my machine with a "small" deployment (via docker-compose) form the master branch. It ran quite well up to a point where it failed badly. I was observing the number of open connections to the database using the previous command, and it started at 6 before starting the benchmark, and oscillated between 6 and 18 during the benchmarks. I had 12 fork-workers, so 18 sounds a reasonably good number.

But at one point, the number of connection grew really fast to around to 100ish and failed. The logs are the following around the moment it failed:

worker_1   | [2019-08-21 04:40:55,839: INFO/ForkPoolWorker-3] 2019-08-21T04:40:55.839621Z [info     ] Unpacking stream of encodings with size 128 - packed as 128 [entityservice.serialization] 
backend_1  | 2019-08-21T04:40:55.841419Z [debug    ] Connecting to redis            [entityservice.cache] 
backend_1  | 2019-08-21T04:40:55.850088Z [debug    ] Closing database connection    [entityservice] request=1b3faa18
nginx_1    | [200] - 172.23.0.1 - "GET /api/v1/projects/da243b04799f724216163014808c20ef05745fba80572edf/runs/5495cdb4084f63702445d76b928900ce62f7300205fd2dd7/status HTTP/1.1" 333 942 395 0.026 "-" "python-requests/2.21.0" "-"
worker_1   | [2019-08-21 04:40:55,857: INFO/ForkPoolWorker-6] 2019-08-21T04:40:55.857616Z [info     ] Unpacking stream of encodings with size 128 - packed as 128 [entityservice.serialization] 
worker_1   | [2019-08-21 04:40:56,367: INFO/ForkPoolWorker-3] 2019-08-21T04:40:56.367011Z [info     ] Unpacking stream of encodings with size 128 - packed as 128 [entityservice.serialization] 
worker_1   | [2019-08-21 04:40:56,377: INFO/ForkPoolWorker-6] 2019-08-21T04:40:56.377673Z [info     ] Unpacking stream of encodings with size 128 - packed as 128 [entityservice.serialization] 
worker_1   | [2019-08-21 04:40:56,546: DEBUG/ForkPoolWorker-10] 2019-08-21T04:40:56.546253Z [debug    ] Progress. Compared 302476104 CLKS [entityservice.cache] run_id=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
worker_1   | [2019-08-21 04:40:56,546: DEBUG/ForkPoolWorker-10] 2019-08-21T04:40:56.546519Z [debug    ] Connecting to redis            [entityservice.cache] 
worker_1   | [2019-08-21 04:40:56,565: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[7d12c5b2-23f5-46b8-a4b9-ebcabf2f3f88] succeeded in 8.055208439000126s: (103, 2476, 'similarity-scores/a7ce4c06ffaaf6b8ed305907.bin')
worker_1   | [2019-08-21 04:40:56,732: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[3b89c871-c111-4cc4-8d9e-6c42cb7f9e55]  
worker_1   | [2019-08-21 04:40:56,748: INFO/ForkPoolWorker-2] 2019-08-21T04:40:56.748166Z [info     ] Unpacking stream of encodings with size 128 - packed as 128 [entityservice.serialization] 
backend_1  | 2019-08-21T04:40:56.860479Z [debug    ] request run status             [entityservice.views.run.status] pid=da243b04799f724216163014808c20ef05745fba80572edf rid=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
backend_1  | 2019-08-21T04:40:56.866417Z [debug    ] checking if provided authorization is a results_token [entityservice.views.auth_checks] 
backend_1  | 2019-08-21T04:40:56.868219Z [debug    ] Run status authorized using result_token token [entityservice.views.run.status] pid=da243b04799f724216163014808c20ef05745fba80572edf rid=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
backend_1  | 2019-08-21T04:40:56.873052Z [debug    ] Connecting to redis            [entityservice.cache] 
backend_1  | 2019-08-21T04:40:56.879672Z [debug    ] Closing database connection    [entityservice] request=15bcf09a
nginx_1    | [200] - 172.23.0.1 - "GET /api/v1/projects/da243b04799f724216163014808c20ef05745fba80572edf/runs/5495cdb4084f63702445d76b928900ce62f7300205fd2dd7/status HTTP/1.1" 333 942 395 0.027 "-" "python-requests/2.21.0" "-"
backend_1  | 2019-08-21T04:40:56.894649Z [warning  ] delete on runs is not implemented! Help! [entityservice.views.run.description] pid=da243b04799f724216163014808c20ef05745fba80572edf rid=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
nginx_1    | [200] - 172.23.0.1 - "DELETE /api/v1/projects/da243b04799f724216163014808c20ef05745fba80572edf/runs/5495cdb4084f63702445d76b928900ce62f7300205fd2dd7 HTTP/1.1" 348 154 0 0.005 "-" "python-requests/2.21.0" "-"
backend_1  | 2019-08-21T04:40:56.904013Z [info     ] Request to delete project      [entityservice.views.project] pid=da243b04799f724216163014808c20ef05745fba80572edf
backend_1  | 2019-08-21T04:40:56.911563Z [debug    ] checking authorization of 'result_token' [entityservice.views.auth_checks] 
backend_1  | 2019-08-21T04:40:56.912538Z [info     ] Marking project for deletion   [entityservice.views.project] pid=da243b04799f724216163014808c20ef05745fba80572edf
backend_1  | 2019-08-21T04:40:56.914442Z [info     ] Queuing authorized request to delete project resources [entityservice.views.project] pid=da243b04799f724216163014808c20ef05745fba80572edf
backend_1  | 2019-08-21T04:40:56.918827Z [debug    ] Closing database connection    [entityservice] request=b4566e44
nginx_1    | [204] - 172.23.0.1 - "DELETE /api/v1/projects/da243b04799f724216163014808c20ef05745fba80572edf HTTP/1.1" 294 143 0 0.020 "-" "python-requests/2.21.0" "-"
worker_1   | [2019-08-21 04:40:57,208: INFO/ForkPoolWorker-2] 2019-08-21T04:40:57.208434Z [info     ] Unpacking stream of encodings with size 128 - packed as 128 [entityservice.serialization] 
worker_1   | [2019-08-21 04:40:58,223: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[3389936a-9f0b-421c-bc41-d726e517a7f1]  
worker_1   | [2019-08-21 04:40:58,236: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[3389936a-9f0b-421c-bc41-d726e517a7f1] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:40:58,691: DEBUG/ForkPoolWorker-7] 2019-08-21T04:40:58.691718Z [debug    ] Progress. Compared 302458863 CLKS [entityservice.cache] run_id=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
worker_1   | [2019-08-21 04:40:58,692: DEBUG/ForkPoolWorker-7] 2019-08-21T04:40:58.692056Z [debug    ] Connecting to redis            [entityservice.cache] 
worker_1   | [2019-08-21 04:40:58,711: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[cac72ebf-3894-457f-b21a-28be45e1ff68] succeeded in 6.80035532400143s: (114, 2740, 'similarity-scores/213e706f08f2ccbf23555931.bin')
worker_1   | [2019-08-21 04:40:58,733: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[84fa3372-e796-41d0-9813-bed3a6df18b2]  
worker_1   | [2019-08-21 04:40:58,744: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[84fa3372-e796-41d0-9813-bed3a6df18b2] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:40:59,632: DEBUG/ForkPoolWorker-11] 2019-08-21T04:40:59.632155Z [debug    ] Progress. Compared 302476104 CLKS [entityservice.cache] run_id=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
worker_1   | [2019-08-21 04:40:59,632: DEBUG/ForkPoolWorker-11] 2019-08-21T04:40:59.632394Z [debug    ] Connecting to redis            [entityservice.cache] 
worker_1   | [2019-08-21 04:40:59,648: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[d0048da8-7ea9-44fb-8523-e986081fde74] succeeded in 6.451652248997561s: (92, 2212, 'similarity-scores/805c16f3440980c8bf0c949a.bin')
worker_1   | [2019-08-21 04:40:59,938: DEBUG/ForkPoolWorker-4] 2019-08-21T04:40:59.938827Z [debug    ] Progress. Compared 302493648 CLKS [entityservice.cache] run_id=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
worker_1   | [2019-08-21 04:40:59,939: DEBUG/ForkPoolWorker-4] 2019-08-21T04:40:59.939066Z [debug    ] Connecting to redis            [entityservice.cache] 
worker_1   | [2019-08-21 04:40:59,953: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[033f1d8f-1f53-4fa6-8baa-e69639f3d262] succeeded in 6.025452882000536s: (93, 2236, 'similarity-scores/7179c24f67347b15ab907923.bin')
worker_1   | [2019-08-21 04:41:00,662: DEBUG/ForkPoolWorker-5] 2019-08-21T04:41:00.662531Z [debug    ] Progress. Compared 302476104 CLKS [entityservice.cache] run_id=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
worker_1   | [2019-08-21 04:41:00,662: DEBUG/ForkPoolWorker-5] 2019-08-21T04:41:00.662758Z [debug    ] Connecting to redis            [entityservice.cache] 
worker_1   | [2019-08-21 04:41:00,674: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[8f929494-bf81-45f7-8a6f-1dcb56ebb09b] succeeded in 7.945221181002125s: (113, 2716, 'similarity-scores/4e5f0e583b102773e7071487.bin')
worker_1   | [2019-08-21 04:41:00,715: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[be44a6bb-d87b-4f01-afa6-4467abb9c8bd]  
worker_1   | [2019-08-21 04:41:00,717: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[18a59a43-37e4-45c3-8883-167b38709752]  
worker_1   | [2019-08-21 04:41:00,726: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[18a59a43-37e4-45c3-8883-167b38709752] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:00,726: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[be44a6bb-d87b-4f01-afa6-4467abb9c8bd] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:00,886: DEBUG/ForkPoolWorker-8] 2019-08-21T04:41:00.886607Z [debug    ] Progress. Compared 302493648 CLKS [entityservice.cache] run_id=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
worker_1   | [2019-08-21 04:41:00,886: DEBUG/ForkPoolWorker-8] 2019-08-21T04:41:00.886823Z [debug    ] Connecting to redis            [entityservice.cache] 
worker_1   | [2019-08-21 04:41:00,901: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[3c41916c-321a-41d2-b3f1-fe97ddb7da8c] succeeded in 6.972950855000818s: (102, 2452, 'similarity-scores/afb0dc0e73516d7b5600dce6.bin')
worker_1   | [2019-08-21 04:41:01,036: DEBUG/ForkPoolWorker-1] 2019-08-21T04:41:01.036747Z [debug    ] Progress. Compared 302476104 CLKS [entityservice.cache] run_id=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
worker_1   | [2019-08-21 04:41:01,037: DEBUG/ForkPoolWorker-1] 2019-08-21T04:41:01.036955Z [debug    ] Connecting to redis            [entityservice.cache] 
worker_1   | [2019-08-21 04:41:01,051: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[43cfd141-a2f5-4330-bdb6-859ce60c7d92] succeeded in 7.85452427600103s: (95, 2284, 'similarity-scores/c22ee335497ecc0099bff390.bin')
worker_1   | [2019-08-21 04:41:01,652: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[bc35d866-04e3-42b4-b9e8-79f9a52ebd46]  
worker_1   | [2019-08-21 04:41:01,653: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[225ecf69-23e7-461e-879d-9e1dfb43d01b]  
worker_1   | [2019-08-21 04:41:01,661: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[225ecf69-23e7-461e-879d-9e1dfb43d01b] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:01,662: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[bc35d866-04e3-42b4-b9e8-79f9a52ebd46] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:01,889: DEBUG/ForkPoolWorker-2] 2019-08-21T04:41:01.889789Z [debug    ] Progress. Compared 302493648 CLKS [entityservice.cache] run_id=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
worker_1   | [2019-08-21 04:41:01,890: DEBUG/ForkPoolWorker-2] 2019-08-21T04:41:01.889974Z [debug    ] Connecting to redis            [entityservice.cache] 
worker_1   | [2019-08-21 04:41:01,900: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[3b89c871-c111-4cc4-8d9e-6c42cb7f9e55] succeeded in 5.166657321999082s: (106, 2548, 'similarity-scores/274fa193fe61342496e132aa.bin')
worker_1   | [2019-08-21 04:41:01,937: DEBUG/ForkPoolWorker-12] 2019-08-21T04:41:01.937134Z [debug    ] Progress. Compared 302493648 CLKS [entityservice.cache] run_id=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
worker_1   | [2019-08-21 04:41:01,937: DEBUG/ForkPoolWorker-12] 2019-08-21T04:41:01.937324Z [debug    ] Connecting to redis            [entityservice.cache] 
worker_1   | [2019-08-21 04:41:01,947: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[fa43b5a3-ea25-47e8-b899-9bdd4e2f7810] succeeded in 7.215088257999014s: (89, 2140, 'similarity-scores/dac285de0534751957c6f8a4.bin')
worker_1   | [2019-08-21 04:41:02,026: DEBUG/ForkPoolWorker-6] 2019-08-21T04:41:02.026454Z [debug    ] Progress. Compared 302476406 CLKS [entityservice.cache] run_id=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
worker_1   | [2019-08-21 04:41:02,026: DEBUG/ForkPoolWorker-6] 2019-08-21T04:41:02.026639Z [debug    ] Connecting to redis            [entityservice.cache] 
worker_1   | [2019-08-21 04:41:02,035: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[3c24122a-b9d6-4f87-a1ca-e37fcabb49f1] succeeded in 6.2154367479997745s: (95, 2284, 'similarity-scores/066a5f621753a4235d8d2ecb.bin')
worker_1   | [2019-08-21 04:41:02,248: DEBUG/ForkPoolWorker-3] 2019-08-21T04:41:02.248055Z [debug    ] Progress. Compared 302493648 CLKS [entityservice.cache] run_id=5495cdb4084f63702445d76b928900ce62f7300205fd2dd7
worker_1   | [2019-08-21 04:41:02,248: DEBUG/ForkPoolWorker-3] 2019-08-21T04:41:02.248242Z [debug    ] Connecting to redis            [entityservice.cache] 
worker_1   | [2019-08-21 04:41:02,258: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[7301b4d2-df2c-4969-aacd-a97faac7231b] succeeded in 6.438693144002173s: (117, 2812, 'similarity-scores/a08a4a0b81fe0f699e457f36.bin')
worker_1   | [2019-08-21 04:41:02,682: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[93ff3cd5-e298-48a4-b224-4e71cf6be685]  
worker_1   | [2019-08-21 04:41:02,686: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[4078169f-71fb-4481-b22c-87a54ed68dc4]  
worker_1   | [2019-08-21 04:41:02,709: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[93ff3cd5-e298-48a4-b224-4e71cf6be685] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:02,710: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[4078169f-71fb-4481-b22c-87a54ed68dc4] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:02,739: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[ddef4e4e-6fea-403a-871a-95377c0b51ca]  
worker_1   | [2019-08-21 04:41:02,743: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[2fad08da-3f96-4f2c-aa0a-ef3c46385c8e]  
worker_1   | [2019-08-21 04:41:02,765: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[2fad08da-3f96-4f2c-aa0a-ef3c46385c8e] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:02,765: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[ddef4e4e-6fea-403a-871a-95377c0b51ca] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:03,058: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[8b678be8-831f-45dc-9e66-52513f1238ee]  
worker_1   | [2019-08-21 04:41:03,062: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[0a9bec2e-352c-45e7-b291-4b78778512e0]  
worker_1   | [2019-08-21 04:41:03,085: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[8b678be8-831f-45dc-9e66-52513f1238ee] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:03,085: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[0a9bec2e-352c-45e7-b291-4b78778512e0] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:03,673: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[4fe1a203-19de-49e8-bf89-d64208e09d20]  
worker_1   | [2019-08-21 04:41:03,677: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[c8a492fe-85ae-46d1-b32d-ba52e3300263]  
worker_1   | [2019-08-21 04:41:03,698: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[4fe1a203-19de-49e8-bf89-d64208e09d20] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:03,699: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[c8a492fe-85ae-46d1-b32d-ba52e3300263] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:03,955: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[7a4a2aa5-4ae0-4244-8c26-3ba3b998c80d]  
worker_1   | [2019-08-21 04:41:03,959: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[68e90ea6-db20-4cfb-8486-f7d9b9376dc6]  
worker_1   | [2019-08-21 04:41:03,982: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[7a4a2aa5-4ae0-4244-8c26-3ba3b998c80d] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:03,982: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[68e90ea6-db20-4cfb-8486-f7d9b9376dc6] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:04,266: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[9f2b0ac1-2a2e-47b5-8357-43c37facc92d]  
worker_1   | [2019-08-21 04:41:04,270: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[3dfb6de1-8ec9-4402-a196-d7d656ba8a12]  
worker_1   | [2019-08-21 04:41:04,294: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[9f2b0ac1-2a2e-47b5-8357-43c37facc92d] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:04,294: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[3dfb6de1-8ec9-4402-a196-d7d656ba8a12] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:04,721: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[89629234-691b-4c49-97f9-c903e822e147]  
worker_1   | [2019-08-21 04:41:04,725: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[f681b487-df4b-457c-b899-6de9689e4b96]  
worker_1   | [2019-08-21 04:41:04,748: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[f681b487-df4b-457c-b899-6de9689e4b96] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:04,749: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[89629234-691b-4c49-97f9-c903e822e147] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:04,777: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[eb971ce0-c7a5-4958-ae77-a6094b493a3a]  
worker_1   | [2019-08-21 04:41:04,780: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[c85e0e0e-89e1-4837-b8a6-c7805dbce433]  
worker_1   | [2019-08-21 04:41:04,802: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[eb971ce0-c7a5-4958-ae77-a6094b493a3a] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:04,802: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[c85e0e0e-89e1-4837-b8a6-c7805dbce433] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:05,709: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[66260b04-ef10-46ca-b072-7482476e9c50]  
worker_1   | [2019-08-21 04:41:05,714: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[5f3404a5-17a1-436c-bdf8-a0e6e8077c71]  
worker_1   | [2019-08-21 04:41:05,719: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[eaffc730-d658-4bdb-a728-172559ea5419]  
worker_1   | [2019-08-21 04:41:05,723: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[5a3dd0ae-ee79-49f4-be8e-43522bee9623]  
worker_1   | [2019-08-21 04:41:05,738: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[5f3404a5-17a1-436c-bdf8-a0e6e8077c71] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:05,739: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[66260b04-ef10-46ca-b072-7482476e9c50] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:05,744: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[eaffc730-d658-4bdb-a728-172559ea5419] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:05,746: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[5a3dd0ae-ee79-49f4-be8e-43522bee9623] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:05,995: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[32bc7566-9e99-4c3c-bb7b-f5e551f60a56]  
worker_1   | [2019-08-21 04:41:05,998: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[d21e78cf-26c9-43d9-935b-487e74c8ae60]  
worker_1   | [2019-08-21 04:41:06,011: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[d21e78cf-26c9-43d9-935b-487e74c8ae60] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:06,011: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[32bc7566-9e99-4c3c-bb7b-f5e551f60a56] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:06,746: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[93da3309-0142-44fa-8f70-e696edb07638]  
worker_1   | [2019-08-21 04:41:06,750: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[b6c2f4ec-fb6a-4f99-90a9-1697337b7a0d]  
worker_1   | [2019-08-21 04:41:06,773: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[93da3309-0142-44fa-8f70-e696edb07638] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:06,773: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[b6c2f4ec-fb6a-4f99-90a9-1697337b7a0d] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:06,812: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[871609cf-709f-45a0-abdf-68ad03e1faf1]  
worker_1   | [2019-08-21 04:41:06,817: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[ad534ca3-7975-4bf0-9f79-48a7de03e464]  
worker_1   | [2019-08-21 04:41:06,821: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[84ca68d7-efc3-496d-b497-3c017cda9bdb]  
worker_1   | [2019-08-21 04:41:06,825: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[6eab8f84-e8f7-4010-9717-6d4e5594dafe]  
worker_1   | [2019-08-21 04:41:06,838: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[ad534ca3-7975-4bf0-9f79-48a7de03e464] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:06,842: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[871609cf-709f-45a0-abdf-68ad03e1faf1] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:06,849: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[84ca68d7-efc3-496d-b497-3c017cda9bdb] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:06,849: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[6eab8f84-e8f7-4010-9717-6d4e5594dafe] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:07,748: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[9f9797f4-1bb8-45a9-8ba7-1801b460acc5]  
worker_1   | [2019-08-21 04:41:07,752: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[4a179556-f4c0-4fc5-83bf-0d25359c17ed]  
worker_1   | [2019-08-21 04:41:07,762: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[e29f61bf-1cab-4392-bf0c-b90606f5d093]  
worker_1   | [2019-08-21 04:41:07,766: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[952a3753-b9e3-4380-8008-fa64a23d0690]  
worker_1   | [2019-08-21 04:41:07,776: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[9f9797f4-1bb8-45a9-8ba7-1801b460acc5] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:07,777: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[4a179556-f4c0-4fc5-83bf-0d25359c17ed] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:07,783: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[e29f61bf-1cab-4392-bf0c-b90606f5d093] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:07,811: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[952a3753-b9e3-4380-8008-fa64a23d0690] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:08,229: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[2b4d88dd-b705-4823-b2d1-ecc5ca95a20a]  
worker_1   | [2019-08-21 04:41:08,232: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[4ed36a88-4ec4-4038-8da7-0832fcf797d5]  
worker_1   | [2019-08-21 04:41:08,254: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[2b4d88dd-b705-4823-b2d1-ecc5ca95a20a] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:08,254: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[4ed36a88-4ec4-4038-8da7-0832fcf797d5] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:08,845: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[f0da99a1-ed56-48c9-a2a6-d3ca4386e2b6]  
worker_1   | [2019-08-21 04:41:08,849: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[b1a4e19f-e9e1-46d4-ab04-c440df8b1116]  
worker_1   | [2019-08-21 04:41:08,857: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[c3995191-0581-483b-ab75-08d71d883529]  
worker_1   | [2019-08-21 04:41:08,865: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[680a4814-364a-432e-839c-aa6591c9c22d]  
worker_1   | [2019-08-21 04:41:08,871: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[9946dbde-db1a-4e3e-9c47-a9c1257a82ee]  
worker_1   | [2019-08-21 04:41:08,873: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[f0da99a1-ed56-48c9-a2a6-d3ca4386e2b6] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:08,873: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[b1a4e19f-e9e1-46d4-ab04-c440df8b1116] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:08,877: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[953ac8e3-27b0-492b-bad9-760d0c902e1b]  
worker_1   | [2019-08-21 04:41:08,886: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[c3995191-0581-483b-ab75-08d71d883529] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:08,886: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[680a4814-364a-432e-839c-aa6591c9c22d] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:08,892: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[9946dbde-db1a-4e3e-9c47-a9c1257a82ee] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:08,892: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[953ac8e3-27b0-492b-bad9-760d0c902e1b] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:09,789: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[0b5a3d9e-242a-46d8-b27b-2db32d7f7d4e]  
worker_1   | [2019-08-21 04:41:09,793: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[6fa3c956-bad4-4aed-a8f3-008831cba013]  
worker_1   | [2019-08-21 04:41:09,803: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[f2840479-be6f-41fb-b60e-8391ad6d6ab8]  
worker_1   | [2019-08-21 04:41:09,815: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[0b5a3d9e-242a-46d8-b27b-2db32d7f7d4e] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:09,816: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[6fa3c956-bad4-4aed-a8f3-008831cba013] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:09,823: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[f2840479-be6f-41fb-b60e-8391ad6d6ab8] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:10,266: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[64ba173b-5e2e-4fd0-ad2b-569898d30cf4]  
worker_1   | [2019-08-21 04:41:10,272: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[95642236-379a-4088-833c-9d5cd7af68a8]  
worker_1   | [2019-08-21 04:41:10,277: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[c556b29b-11c5-4fc2-8d42-b2e27259d981]  
worker_1   | [2019-08-21 04:41:10,288: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[64ba173b-5e2e-4fd0-ad2b-569898d30cf4] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:10,288: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[95642236-379a-4088-833c-9d5cd7af68a8] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:10,290: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[c556b29b-11c5-4fc2-8d42-b2e27259d981] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:10,897: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[a7eeacc6-7716-4892-96f9-49a381bbfa76]  
worker_1   | [2019-08-21 04:41:10,902: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[1673e1af-613e-400e-bb72-3bbb224876be]  
worker_1   | [2019-08-21 04:41:10,912: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[6b054274-3008-4ef6-9f36-e6043dc3d5be]  
worker_1   | [2019-08-21 04:41:10,917: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[c9aba665-a9bf-4f0e-b90e-a324e80b35a9]  
worker_1   | [2019-08-21 04:41:10,922: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[b2d94419-0530-4ceb-bda8-d62f3290a6a3]  
worker_1   | [2019-08-21 04:41:10,926: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[e6b63961-8c73-4c79-a574-186fec3eeb3c]  
worker_1   | [2019-08-21 04:41:10,926: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[a7eeacc6-7716-4892-96f9-49a381bbfa76] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:10,927: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[1673e1af-613e-400e-bb72-3bbb224876be] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:10,933: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[c9aba665-a9bf-4f0e-b90e-a324e80b35a9] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:10,933: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[6b054274-3008-4ef6-9f36-e6043dc3d5be] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:10,937: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[b2d94419-0530-4ceb-bda8-d62f3290a6a3] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:10,938: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[e6b63961-8c73-4c79-a574-186fec3eeb3c] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:11,828: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[283d038d-3955-48e6-abff-8da2f2ff1324]  
worker_1   | [2019-08-21 04:41:11,835: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[81d0df33-2f0e-46ba-854b-52fdc09121ce]  
worker_1   | [2019-08-21 04:41:11,840: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[ca82c9a6-5aee-4d5e-a422-81d9878fe7a2]  
worker_1   | [2019-08-21 04:41:11,857: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[283d038d-3955-48e6-abff-8da2f2ff1324] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:11,858: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[81d0df33-2f0e-46ba-854b-52fdc09121ce] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:11,864: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[ca82c9a6-5aee-4d5e-a422-81d9878fe7a2] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:12,302: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[f47c4057-e211-4323-9e6a-7ce09b32ab96]  
worker_1   | [2019-08-21 04:41:12,307: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[66047281-6726-4879-a710-6ff6e2e80167]  
worker_1   | [2019-08-21 04:41:12,312: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[32bb83db-fd88-48ab-8323-29be5bdf7b9e]  
worker_1   | [2019-08-21 04:41:12,329: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[f47c4057-e211-4323-9e6a-7ce09b32ab96] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:12,330: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[66047281-6726-4879-a710-6ff6e2e80167] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:12,335: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[32bb83db-fd88-48ab-8323-29be5bdf7b9e] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:12,942: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[96eca016-4d48-494b-8986-1683d9524fe6]  
worker_1   | [2019-08-21 04:41:12,949: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[b174763a-a68c-4127-ae8b-3a346fd30f04]  
worker_1   | [2019-08-21 04:41:12,961: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[b27e37e5-2559-41a0-a3bd-c767a7f4c2d8]  
worker_1   | [2019-08-21 04:41:12,966: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[d40373d3-3c9c-473a-b6c6-7f2a6d01e00e]  
worker_1   | [2019-08-21 04:41:12,971: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[4814c553-944a-48d0-b2e4-ef3d89128f10]  
worker_1   | [2019-08-21 04:41:12,974: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[b174763a-a68c-4127-ae8b-3a346fd30f04] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:12,974: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[5ca1c12c-e7af-444c-b7ea-9a54c63916b8]  
worker_1   | [2019-08-21 04:41:12,975: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[96eca016-4d48-494b-8986-1683d9524fe6] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:12,981: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[d40373d3-3c9c-473a-b6c6-7f2a6d01e00e] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:12,985: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[4814c553-944a-48d0-b2e4-ef3d89128f10] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:12,985: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[5ca1c12c-e7af-444c-b7ea-9a54c63916b8] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:13,003: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[b27e37e5-2559-41a0-a3bd-c767a7f4c2d8] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:13,870: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[b15c921e-f080-4ea2-ac00-53b988628af8]  
worker_1   | [2019-08-21 04:41:13,877: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[92b8aceb-afc8-4fb2-8663-dfe7fa38120c]  
worker_1   | [2019-08-21 04:41:13,883: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[1474fb5b-8b22-47a9-b19e-df77b3dd5b84]  
worker_1   | [2019-08-21 04:41:13,901: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[b15c921e-f080-4ea2-ac00-53b988628af8] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:13,904: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[92b8aceb-afc8-4fb2-8663-dfe7fa38120c] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:13,912: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[1474fb5b-8b22-47a9-b19e-df77b3dd5b84] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:14,347: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[c2c56dbb-4495-4a6d-a5a1-c0e821be8653]  
worker_1   | [2019-08-21 04:41:14,352: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[0803321f-5e23-4dcf-9d9c-957ffd1f0559]  
worker_1   | [2019-08-21 04:41:14,356: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[ad270462-7f26-4e09-91a3-c399ae850fd9]  
worker_1   | [2019-08-21 04:41:14,364: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[0803321f-5e23-4dcf-9d9c-957ffd1f0559] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:14,365: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[c2c56dbb-4495-4a6d-a5a1-c0e821be8653] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:14,368: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[ad270462-7f26-4e09-91a3-c399ae850fd9] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:14,986: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[34627009-06d2-4da7-b144-52e2f6d18464]  
worker_1   | [2019-08-21 04:41:14,994: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[bcf639bd-70f8-4528-a023-73ecc39bc215]  
worker_1   | [2019-08-21 04:41:15,005: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[7e659ad8-2967-4919-8bec-ab8568908c5c]  
worker_1   | [2019-08-21 04:41:15,009: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[8949814b-559a-4f7b-97b4-abf7eb5e16e0]  
worker_1   | [2019-08-21 04:41:15,014: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[dc6df76b-43aa-4099-9a08-90ec94e5d89d]  
worker_1   | [2019-08-21 04:41:15,015: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[34627009-06d2-4da7-b144-52e2f6d18464] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:15,017: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[bcf639bd-70f8-4528-a023-73ecc39bc215] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:15,017: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[26875bc5-83d9-497c-acfc-1bb679436fde]  
worker_1   | [2019-08-21 04:41:15,023: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[8949814b-559a-4f7b-97b4-abf7eb5e16e0] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:15,023: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[7e659ad8-2967-4919-8bec-ab8568908c5c] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:15,027: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[26875bc5-83d9-497c-acfc-1bb679436fde] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:15,027: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[dc6df76b-43aa-4099-9a08-90ec94e5d89d] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:15,917: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[d97caf54-2562-4952-b72f-daa07b61ded9]  
worker_1   | [2019-08-21 04:41:15,923: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[431549c1-9ee4-4ff3-ae2c-95b7b236f61d]  
worker_1   | [2019-08-21 04:41:15,929: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[ee9ea917-e8dc-4d65-a5db-c9b084a5f447]  
worker_1   | [2019-08-21 04:41:15,938: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[d97caf54-2562-4952-b72f-daa07b61ded9] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:15,938: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[431549c1-9ee4-4ff3-ae2c-95b7b236f61d] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:15,942: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[ee9ea917-e8dc-4d65-a5db-c9b084a5f447] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:16,377: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[8f88112a-a2ae-474a-adda-19c5545610da]  
worker_1   | [2019-08-21 04:41:16,382: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[9ec88986-38c4-4222-86c4-d3009ccbb5fa]  
worker_1   | [2019-08-21 04:41:16,388: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[d7ae176d-da7c-4b61-98bf-de5250fb421e]  
worker_1   | [2019-08-21 04:41:16,406: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[9ec88986-38c4-4222-86c4-d3009ccbb5fa] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:16,407: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[8f88112a-a2ae-474a-adda-19c5545610da] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:16,410: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[d7ae176d-da7c-4b61-98bf-de5250fb421e] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:17,028: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[39cd084d-5b40-4ba0-b7a0-9e7998846bf6]  
worker_1   | [2019-08-21 04:41:17,032: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[06f98005-543e-4acd-8531-984763757981]  
worker_1   | [2019-08-21 04:41:17,048: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[01eb6732-ace2-409c-a638-ae7b985fcdca]  
worker_1   | [2019-08-21 04:41:17,052: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[e61fbbfe-f0a0-4d9d-8a8f-2ad0c7fe79b6]  
worker_1   | [2019-08-21 04:41:17,058: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[c1e50267-4d0a-4233-8448-b3ffe5754b0d]  
worker_1   | [2019-08-21 04:41:17,058: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[06f98005-543e-4acd-8531-984763757981] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:17,058: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[39cd084d-5b40-4ba0-b7a0-9e7998846bf6] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:17,061: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[88af6539-52d8-458b-908b-7a66686bd0e0]  
worker_1   | [2019-08-21 04:41:17,068: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[e61fbbfe-f0a0-4d9d-8a8f-2ad0c7fe79b6] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:17,069: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[01eb6732-ace2-409c-a638-ae7b985fcdca] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:17,073: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[c1e50267-4d0a-4233-8448-b3ffe5754b0d] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:17,073: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[88af6539-52d8-458b-908b-7a66686bd0e0] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:17,952: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[2691221e-12d2-4543-bf8b-9b925549359c]  
worker_1   | [2019-08-21 04:41:17,957: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[44fb29a1-8076-4d95-a6b8-573cd9a92c85]  
worker_1   | [2019-08-21 04:41:17,961: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[22211953-7ca4-4714-87b7-1281f7609078]  
worker_1   | [2019-08-21 04:41:17,979: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[44fb29a1-8076-4d95-a6b8-573cd9a92c85] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:17,979: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[2691221e-12d2-4543-bf8b-9b925549359c] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:17,983: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[22211953-7ca4-4714-87b7-1281f7609078] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:18,421: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[4d532b02-0331-44a1-8c2c-c2831879ecbe]  
worker_1   | [2019-08-21 04:41:18,424: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[a0911024-3b00-4d8a-b757-56ece6772f75]  
worker_1   | [2019-08-21 04:41:18,428: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[5b44e2b0-4d62-4eba-b1ef-dd02aa8b855c]  
worker_1   | [2019-08-21 04:41:18,437: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[a0911024-3b00-4d8a-b757-56ece6772f75] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:18,437: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[4d532b02-0331-44a1-8c2c-c2831879ecbe] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:18,440: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[5b44e2b0-4d62-4eba-b1ef-dd02aa8b855c] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:19,072: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[aaec4799-2ef6-4f69-af88-9ea9cf54713f]  
worker_1   | [2019-08-21 04:41:19,077: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[5894847c-52a7-4c9d-bb7a-d9bff7d5df01]  
worker_1   | [2019-08-21 04:41:19,093: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[131223f4-7d1a-4fa5-8ba3-3e6e310e2fe4]  
worker_1   | [2019-08-21 04:41:19,097: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[d16dc2a7-c68b-4d09-b747-a0357ca546ea]  
worker_1   | [2019-08-21 04:41:19,101: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[3675dca1-98e3-4129-8b30-4842bb67c555]  
worker_1   | [2019-08-21 04:41:19,102: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[5894847c-52a7-4c9d-bb7a-d9bff7d5df01] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:19,103: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[aaec4799-2ef6-4f69-af88-9ea9cf54713f] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:19,105: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[ef23222b-f337-453c-8cd3-bae67882940f]  
worker_1   | [2019-08-21 04:41:19,111: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[d16dc2a7-c68b-4d09-b747-a0357ca546ea] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:19,111: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[131223f4-7d1a-4fa5-8ba3-3e6e310e2fe4] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:19,115: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[ef23222b-f337-453c-8cd3-bae67882940f] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:19,115: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[3675dca1-98e3-4129-8b30-4842bb67c555] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:19,996: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[5503dc5d-ca73-4424-80b0-010f7b741305]  
worker_1   | [2019-08-21 04:41:20,000: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[5f4207b2-b0ca-46f7-ab36-3675586fe52d]  
worker_1   | [2019-08-21 04:41:20,005: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[0c21bde3-0a17-4a19-8166-05351cb9bc3c]  
worker_1   | [2019-08-21 04:41:20,024: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[5503dc5d-ca73-4424-80b0-010f7b741305] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:20,025: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[5f4207b2-b0ca-46f7-ab36-3675586fe52d] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:20,030: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[0c21bde3-0a17-4a19-8166-05351cb9bc3c] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:20,451: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[3a76c993-9bfa-49b6-9aa3-b7848f68759a]  
worker_1   | [2019-08-21 04:41:20,455: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[7a263572-1ffa-42f5-8b13-fd6a738b32c7]  
worker_1   | [2019-08-21 04:41:20,461: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[697c0434-e15a-456b-8140-751f53be6bf5]  
worker_1   | [2019-08-21 04:41:20,478: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[3a76c993-9bfa-49b6-9aa3-b7848f68759a] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:20,479: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[7a263572-1ffa-42f5-8b13-fd6a738b32c7] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:20,485: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[697c0434-e15a-456b-8140-751f53be6bf5] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:21,116: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[fe1c73f7-97ae-4b2d-baab-adffcfd45041]  
worker_1   | [2019-08-21 04:41:21,120: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[0f71e7cf-1c29-4025-992e-89979084d123]  
worker_1   | [2019-08-21 04:41:21,140: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[d2b7853c-8793-439b-8808-5fd735890418]  
worker_1   | [2019-08-21 04:41:21,143: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[dc21a833-3d31-4bda-afbd-af4909f2f0e3]  
worker_1   | [2019-08-21 04:41:21,145: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[0f71e7cf-1c29-4025-992e-89979084d123] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:21,146: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[fe1c73f7-97ae-4b2d-baab-adffcfd45041] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:21,147: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[c6314218-8e8b-4514-9282-e3e1e650673a]  
worker_1   | [2019-08-21 04:41:21,149: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[2b8e113b-d251-4532-a864-bdc80c874800]  
worker_1   | [2019-08-21 04:41:21,158: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[d2b7853c-8793-439b-8808-5fd735890418] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:21,158: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[dc21a833-3d31-4bda-afbd-af4909f2f0e3] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:21,161: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[c6314218-8e8b-4514-9282-e3e1e650673a] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:21,161: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[2b8e113b-d251-4532-a864-bdc80c874800] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:22,038: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[6a3fa6cf-7f32-4224-8f8f-d86936f60aa5]  
worker_1   | [2019-08-21 04:41:22,044: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[fddd0b27-c5c7-4f4f-b6c2-3923beee6af4]  
worker_1   | [2019-08-21 04:41:22,049: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[c0e6b267-a181-4a38-bb56-ed7359e3fe53]  
worker_1   | [2019-08-21 04:41:22,069: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[6a3fa6cf-7f32-4224-8f8f-d86936f60aa5] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:22,071: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[fddd0b27-c5c7-4f4f-b6c2-3923beee6af4] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:22,078: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[c0e6b267-a181-4a38-bb56-ed7359e3fe53] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:22,490: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[3c9fa5d7-7510-48a8-992d-90506c3dccd1]  
worker_1   | [2019-08-21 04:41:22,495: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[c129c644-324d-424d-bb5e-61ba2814cd8f]  
worker_1   | [2019-08-21 04:41:22,503: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[6696cb28-738b-45d2-a08e-e2ac0f9d2a3e]  
worker_1   | [2019-08-21 04:41:22,519: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[3c9fa5d7-7510-48a8-992d-90506c3dccd1] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:22,520: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[c129c644-324d-424d-bb5e-61ba2814cd8f] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:22,528: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[6696cb28-738b-45d2-a08e-e2ac0f9d2a3e] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:23,160: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[3cb91189-2087-4edf-a332-fe95e7b72ecd]  
worker_1   | [2019-08-21 04:41:23,164: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[414a28bb-638d-432d-b2a6-c82550e04263]  
worker_1   | [2019-08-21 04:41:23,181: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[579a3902-f639-41ea-93ae-c2fdaac2e966]  
worker_1   | [2019-08-21 04:41:23,184: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[13881dd0-a6ba-4222-9851-ff8cd3899b18]  
worker_1   | [2019-08-21 04:41:23,187: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[3cb91189-2087-4edf-a332-fe95e7b72ecd] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:23,187: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[414a28bb-638d-432d-b2a6-c82550e04263] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:23,187: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[40f93f13-fc17-4918-b339-b04a13be7074]  
worker_1   | [2019-08-21 04:41:23,190: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[90719d5d-022c-4ab0-9464-50d166809462]  
worker_1   | [2019-08-21 04:41:23,195: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[13881dd0-a6ba-4222-9851-ff8cd3899b18] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:23,196: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[579a3902-f639-41ea-93ae-c2fdaac2e966] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:23,199: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[90719d5d-022c-4ab0-9464-50d166809462] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:23,199: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[40f93f13-fc17-4918-b339-b04a13be7074] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:24,082: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[a4147d98-229d-4de1-ad5f-1fc42ff872ef]  
worker_1   | [2019-08-21 04:41:24,086: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[321f7533-517c-4cef-962f-1a9f470c9f2f]  
worker_1   | [2019-08-21 04:41:24,095: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[ba763908-5999-4ef9-8b95-54a88c211a6b]  
worker_1   | [2019-08-21 04:41:24,112: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[321f7533-517c-4cef-962f-1a9f470c9f2f] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:24,114: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[a4147d98-229d-4de1-ad5f-1fc42ff872ef] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:24,120: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[ba763908-5999-4ef9-8b95-54a88c211a6b] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:24,532: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[cee51d7f-2b23-4666-a123-7c5d9e63d183]  
worker_1   | [2019-08-21 04:41:24,539: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[0c55ccff-270b-45a7-a9c3-21f79d302a8c]  
worker_1   | [2019-08-21 04:41:24,544: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[f6a98fa2-1ce9-4f9c-b144-5cf1e1640105]  
worker_1   | [2019-08-21 04:41:24,564: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[cee51d7f-2b23-4666-a123-7c5d9e63d183] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:24,566: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[0c55ccff-270b-45a7-a9c3-21f79d302a8c] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:24,570: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[f6a98fa2-1ce9-4f9c-b144-5cf1e1640105] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:25,200: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[de0070da-9ac6-4750-bd26-25076f850363]  
worker_1   | [2019-08-21 04:41:25,206: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[a846492c-b1bb-42c0-b3fd-8dbb28091897]  
worker_1   | [2019-08-21 04:41:25,224: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[ca61979c-f276-462f-9456-9584e4bf50e5]  
worker_1   | [2019-08-21 04:41:25,229: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[5b417ca2-128e-442e-8461-30c3689c85ae]  
worker_1   | [2019-08-21 04:41:25,234: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[5a40a9d0-f58f-43c9-b182-15068e369eee]  
worker_1   | [2019-08-21 04:41:25,235: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[a846492c-b1bb-42c0-b3fd-8dbb28091897] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:25,236: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[de0070da-9ac6-4750-bd26-25076f850363] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:25,238: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[44e73f38-f5a7-4606-98fd-5e50eb6b21a3]  
worker_1   | [2019-08-21 04:41:25,245: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[5b417ca2-128e-442e-8461-30c3689c85ae] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:25,246: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[ca61979c-f276-462f-9456-9584e4bf50e5] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:25,251: INFO/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[44e73f38-f5a7-4606-98fd-5e50eb6b21a3] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:25,251: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[5a40a9d0-f58f-43c9-b182-15068e369eee] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:26,127: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[5ece673b-f265-4015-8901-e86a6c2f3dca]  
worker_1   | [2019-08-21 04:41:26,135: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[55e5f1e3-ab84-4d4e-829b-bee3eb8985f2]  
worker_1   | [2019-08-21 04:41:26,141: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[84e7a05b-a271-4be4-88e0-cbe6cd9eb25b]  
worker_1   | [2019-08-21 04:41:26,154: INFO/ForkPoolWorker-8] Task entityservice.tasks.comparing.compute_filter_similarity[5ece673b-f265-4015-8901-e86a6c2f3dca] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:26,154: INFO/ForkPoolWorker-1] Task entityservice.tasks.comparing.compute_filter_similarity[55e5f1e3-ab84-4d4e-829b-bee3eb8985f2] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:26,158: INFO/ForkPoolWorker-11] Task entityservice.tasks.comparing.compute_filter_similarity[84e7a05b-a271-4be4-88e0-cbe6cd9eb25b] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:26,579: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[b55405bf-8713-4b13-a32d-5b0206117d3d]  
worker_1   | [2019-08-21 04:41:26,584: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[837facbe-b889-459e-b1ed-fc21b6cee460]  
worker_1   | [2019-08-21 04:41:26,589: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[77d4dd63-a91f-4c9a-b8ad-9f12d8fbfcdc]  
worker_1   | [2019-08-21 04:41:26,607: INFO/ForkPoolWorker-2] Task entityservice.tasks.comparing.compute_filter_similarity[837facbe-b889-459e-b1ed-fc21b6cee460] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:26,608: INFO/ForkPoolWorker-12] Task entityservice.tasks.comparing.compute_filter_similarity[b55405bf-8713-4b13-a32d-5b0206117d3d] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:26,615: INFO/ForkPoolWorker-10] Task entityservice.tasks.comparing.compute_filter_similarity[77d4dd63-a91f-4c9a-b8ad-9f12d8fbfcdc] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:27,248: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[9e18a29b-d2ac-465b-b6b2-1e48c3e3bac6]  
worker_1   | [2019-08-21 04:41:27,253: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[7a149536-e62a-48fa-9203-36ca30179054]  
worker_1   | [2019-08-21 04:41:27,270: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[b1a05f5e-d8f7-45e7-8e5d-a6e2befaec35]  
worker_1   | [2019-08-21 04:41:27,274: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[8c5d15f5-5702-4b6c-a1e3-fa3949345a3d]  
worker_1   | [2019-08-21 04:41:27,278: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[23720d1e-30c0-42ef-b2a9-26d79779ae53]  
worker_1   | [2019-08-21 04:41:27,279: INFO/ForkPoolWorker-3] Task entityservice.tasks.comparing.compute_filter_similarity[9e18a29b-d2ac-465b-b6b2-1e48c3e3bac6] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:27,279: INFO/ForkPoolWorker-5] Task entityservice.tasks.comparing.compute_filter_similarity[7a149536-e62a-48fa-9203-36ca30179054] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:27,281: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[acb23caa-61e2-45eb-b51f-17d938824abe]  
db_1       | 2019-08-21 04:41:27.287 UTC [3032] FATAL:  sorry, too many clients already
worker_1   | [2019-08-21 04:41:27,288: WARNING/ForkPoolWorker-4] 2019-08-21T04:41:27.287928Z [warning  ] fallback connecting to default postgres db [entityservice.database.util] database=postgres host=db user=postgres
worker_1   | [2019-08-21 04:41:27,288: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[8c5d15f5-5702-4b6c-a1e3-fa3949345a3d] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:27,289: INFO/ForkPoolWorker-7] Task entityservice.tasks.comparing.compute_filter_similarity[b1a05f5e-d8f7-45e7-8e5d-a6e2befaec35] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
db_1       | 2019-08-21 04:41:27.289 UTC [3033] FATAL:  sorry, too many clients already
worker_1   | [2019-08-21 04:41:27,290: WARNING/ForkPoolWorker-4] 2019-08-21T04:41:27.289919Z [warning  ] Can't connect to database      [entityservice.database.util]
worker_1   | [2019-08-21 04:41:27,292: INFO/ForkPoolWorker-6] Task entityservice.tasks.comparing.compute_filter_similarity[23720d1e-30c0-42ef-b2a9-26d79779ae53] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)
worker_1   | [2019-08-21 04:41:27,293: WARNING/ForkPoolWorker-4] 2019-08-21T04:41:27.292808Z [error    ] Unexpected exception raised in task acb23caa-61e2-45eb-b51f-17d938824abe [entityservice.tasks] 
worker_1   | Traceback (most recent call last):
worker_1   |   File "/var/www/entityservice/database/util.py", line 38, in connect_db
worker_1   |     conn = psycopg2.connect(database=db, user=user, password=pw, host=host)
worker_1   |   File "/usr/lib/python3.6/site-packages/psycopg2/__init__.py", line 130, in connect
worker_1   |     conn = _connect(dsn, connection_factory=connection_factory, **kwasync)
worker_1   | psycopg2.OperationalError: FATAL:  sorry, too many clients already
worker_1   | 
worker_1   | 
worker_1   | During handling of the above exception, another exception occurred:
worker_1   | 
worker_1   | Traceback (most recent call last):
worker_1   |   File "/var/www/entityservice/database/util.py", line 41, in connect_db
worker_1   |     conn = psycopg2.connect(database='postgres', user=user, password=pw, host=host)
worker_1   |   File "/usr/lib/python3.6/site-packages/psycopg2/__init__.py", line 130, in connect
worker_1   |     conn = _connect(dsn, connection_factory=connection_factory, **kwasync)
worker_1   | psycopg2.OperationalError: FATAL:  sorry, too many clients already
worker_1   | 
worker_1   | 
worker_1   | During handling of the above exception, another exception occurred:
worker_1   | 
worker_1   | Traceback (most recent call last):
worker_1   |   File "/usr/lib/python3.6/site-packages/celery/app/trace.py", line 382, in trace_task
worker_1   |     R = retval = fun(*args, **kwargs)
worker_1   |   File "/var/www/entityservice/tasks/base_task.py", line 87, in __call__
worker_1   |     return super(TracedTask, self).__call__(*args, **kwargs)
worker_1   |   File "/usr/lib/python3.6/site-packages/celery/app/trace.py", line 641, in __protected_call__
worker_1   |     return self.run(*args, **kwargs)
worker_1   |   File "/var/www/entityservice/tasks/comparing.py", line 120, in compute_filter_similarity
worker_1   |     with DBConn() as db:
worker_1   |   File "/var/www/entityservice/database/util.py", line 68, in __init__
worker_1   |     self.conn = conn if conn is not None else connect_db()
worker_1   |   File "/var/www/entityservice/database/util.py", line 45, in connect_db
worker_1   |     raise ConnectionError("Issue connecting to database")
worker_1   | ConnectionError: Issue connecting to database
worker_1   | [2019-08-21 04:41:27,293: ERROR/ForkPoolWorker-4] Task entityservice.tasks.comparing.compute_filter_similarity[acb23caa-61e2-45eb-b51f-17d938824abe] raised unexpected: ConnectionError('Issue connecting to database',)
worker_1   | Traceback (most recent call last):
worker_1   |   File "/var/www/entityservice/database/util.py", line 38, in connect_db
worker_1   |     conn = psycopg2.connect(database=db, user=user, password=pw, host=host)
worker_1   |   File "/usr/lib/python3.6/site-packages/psycopg2/__init__.py", line 130, in connect
worker_1   |     conn = _connect(dsn, connection_factory=connection_factory, **kwasync)
worker_1   | psycopg2.OperationalError: FATAL:  sorry, too many clients already

From the few logs I can see, it happened after a timeout, and the benchmarks sends a request to delete the run which is not finished (see nginx_1 receiving the request to delete). After which we can see a lot of

worker_1   | [2019-08-21 04:40:58,223: INFO/MainProcess] Received task: entityservice.tasks.comparing.compute_filter_similarity[3389936a-9f0b-421c-bc41-d726e517a7f1]  
worker_1   | [2019-08-21 04:40:58,236: INFO/ForkPoolWorker-9] Task entityservice.tasks.comparing.compute_filter_similarity[3389936a-9f0b-421c-bc41-d726e517a7f1] raised expected: DBResourceMissing('unknown project or run not found in database not found in database',)

There are around 150 to 200 DBResourceMissing raised before it falls down to

Traceback (most recent call last):
  File "/var/www/entityservice/database/util.py", line 38, in connect_db
    conn = psycopg2.connect(database=db, user=user, password=pw, host=host)
  File "/usr/lib/python3.6/site-packages/psycopg2/__init__.py", line 130, in connect
    conn = _connect(dsn, connection_factory=connection_factory, **kwasync)
psycopg2.OperationalError: FATAL:  sorry, too many clients already

So I assume that we are not handling well our connections on errors.

gusmith commented 5 years ago

Reducing the timeout of the benchmark accelerate a lot the observation. I set the timeout to 10 seconds, and start monitoring the number of connection to the db every second (started a bit after the benchmark). Results:

   15
   17
   18
   18
   17
   17
   17
   16
   12
    8
   13
   17
   24
   29
   36
   41
   48
   52
   63
   63
   74
   74
   86
   86
   98
  100

From which I couldn't send the request anymore (receiving a psql: FATAL: sorry, too many clients already error).

gusmith commented 5 years ago

When running the same thing using the branch feature-connection-pools, I got the following observations:

   6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6
    6

Starting to observe before starting the benchmark and stopping after the benchmark timeout, letting time to normally observed the failing behavior. The branch seems to help :)

gusmith commented 5 years ago

Closed by Closed by #405