[2023-03-27 21
0747 +0000] [1] [INFO] Starting gunicorn 20.1.0
[2023-03-27 21
0747 +0000] [1] [INFO] Listening at:
http://0.0.0.0:7002 (1)
[2023-03-27 21
0747 +0000] [1] [INFO] Using worker: uvicorn.workers.UvicornWorker
[2023-03-27 21
0747 +0000] [7] [INFO] Booting worker with pid: 7
[2023-03-27 21
0747 +0000] [8] [INFO] Booting worker with pid: 8
[2023-03-27 21
0748 +0000] [9] [INFO] Booting worker with pid: 9
[2023-03-27 21
0748 +0000] [10] [INFO] Booting worker with pid: 10
2023-03-27T21
0753.085100+0000 | 10 | opal_server.server | INFO | OPAL is running in secure mode - will verify API requests with JWT tokens.
2023-03-27T21
0753.097822+0000 | 10 | opal_server.pubsub | INFO | Initializing broadcaster for server<->server communication
2023-03-27T21
0753.108472+0000 | 7 | opal_server.server | INFO | OPAL is running in secure mode - will verify API requests with JWT tokens.
2023-03-27T21
0753.109058+0000 | 7 | opal_server.pubsub | INFO | Initializing broadcaster for server<->server communication
2023-03-27T21
0753.109211+0000 | 8 | opal_server.server | INFO | OPAL is running in secure mode - will verify API requests with JWT tokens.
2023-03-27T21
0753.109975+0000 | 8 | opal_server.pubsub | INFO | Initializing broadcaster for server<->server communication
2023-03-27T21
0753.219564+0000 | 8 | opal_server.server | INFO |
* OPAL Server Startup
*
2023-03-27T21
0753.220184+0000 | 8 | opal_server.server | INFO | leadership lock acquired, leader pid: 8
2023-03-27T21
0753.222437+0000 | 8 | opal_server.data.data_update_publisher | INFO | [8] Starting Polling Updates
2023-03-27T21
0753.262027+0000 | 9 | opal_server.server | INFO | OPAL is running in secure mode - will verify API requests with JWT tokens.
2023-03-27T21
0753.263190+0000 | 9 | opal_server.pubsub | INFO | Initializing broadcaster for server<->server communication
2023-03-27T21
0753.272127+0000 | 10 | opal_server.server | INFO |
* OPAL Server Startup
*
2023-03-27T21
0753.272584+0000 | 10 | opal_server.server | INFO | leadership lock acquired, leader pid: 10
2023-03-27T21
0753.274503+0000 | 10 | opal_server.data.data_update_publisher | INFO | [10] Starting Polling Updates
2023-03-27T21
0753.280263+0000 | 7 | opal_server.server | INFO |
* OPAL Server Startup
*
2023-03-27T21
0753.280813+0000 | 7 | opal_server.server | INFO | leadership lock acquired, leader pid: 7
2023-03-27T21
0753.282334+0000 | 7 | opal_server.data.data_update_publisher | INFO | [7] Starting Polling Updates
2023-03-27T21
0753.364364+0000 | 9 | opal_server.server | INFO |
* OPAL Server Startup
*
2023-03-27T21
0753.364934+0000 | 9 | opal_server.server | INFO | leadership lock acquired, leader pid: 9
2023-03-27T21
0754.622524+0000 | 10 | fastapi_websocket_pubsub.event_broadc...| INFO | Listening for incoming events from broadcast channel (first listener started)
2023-03-27T21
0754.626346+0000 | 9 | fastapi_websocket_pubsub.event_broadc...| INFO | Listening for incoming events from broadcast channel (first listener started)
2023-03-27T21
0754.672025+0000 | 10 | fastapi_websocket_pubsub.event_broadc...| INFO | Starting broadcaster listener
2023-03-27T21
0754.673888+0000 | 10 | websockets.legacy.server | INFO | connection open
2023-03-27T21
0754.678279+0000 | 10 | websockets.legacy.server | INFO | connection closed
2023-03-27T21
0754.678573+0000 | 9 | fastapi_websocket_pubsub.event_broadc...| INFO | Starting broadcaster listener
2023-03-27T21
0754.683554+0000 | 9 | websockets.legacy.server | INFO | connection open
2023-03-27T21
0754.684821+0000 | 9 | websockets.legacy.server | INFO | connection closed
2023-03-27T21
0754.934279+0000 | 9 | fastapi_websocket_rpc.websocket_rpc_e...| INFO | Client connected
2023-03-27T21
0754.935256+0000 | 9 | websockets.legacy.server | INFO | connection open
2023-03-27T21
0754.948555+0000 | 9 | fastapi_websocket_pubsub.event_notifier | INFO | New subscription {'id': '90ccbc521f534d8e94b291bd2828c9e7', 'subscriber_id': '28258cf421e4424b988f1782a8a25945', 'topic': 'policy:.', 'callback': <function RpcEventServerMethods.subscribe.<locals>.callback at 0x7f83704f7520>, 'notifier_id': None}
2023-03-27T21
0754.965006+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38288 - "GET /policy?path=. HTTP/1.1" 503
2023-03-27T21
0755.433013+0000 | 7 | fastapi_websocket_pubsub.event_broadc...| INFO | Listening for incoming events from broadcast channel (first listener started)
2023-03-27T21
0755.495129+0000 | 7 | fastapi_websocket_pubsub.event_broadc...| INFO | Starting broadcaster listener
2023-03-27T21
0755.496535+0000 | 7 | websockets.legacy.server | INFO | connection open
2023-03-27T21
0755.500232+0000 | 7 | websockets.legacy.server | INFO | connection closed
2023-03-27T21
0755.670369+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38292 - "GET /policy?path=. HTTP/1.1" 503
2023-03-27T21
0757.231283+0000 | 7 | fastapi_websocket_rpc.websocket_rpc_e...| INFO | Client connected
2023-03-27T21
0757.232085+0000 | 7 | websockets.legacy.server | INFO | connection open
2023-03-27T21
0757.240986+0000 | 7 | fastapi_websocket_pubsub.event_notifier | INFO | New subscription {'id': 'ccaa34ac6a9a4102ab90179f80450220', 'subscriber_id': '8f0a77aa16d240a88dc384d8c3adf773', 'topic': 'xxxxxxxxxxxxxxxx', 'callback': <function RpcEventServerMethods.subscribe.<locals>.callback at 0x7f83704f7520>, 'notifier_id': None}
2023-03-27T21
0757.250068+0000 | 9 | opal_server.data.api | INFO | Source configuration is available at 'xxxxxxxxxxxxxxxxxx', redirecting with token=eyJhb...YR4JQ (abbrv.)
2023-03-27T21
0757.250604+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38296 - "GET /data/config HTTP/1.1" 307
2023-03-27T21
0757.598108+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38304 - "GET /policy?path=. HTTP/1.1" 503
2023-03-27T21
0758.371569+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38308 - "GET /policy?path=. HTTP/1.1" 503
2023-03-27T21
0802.566022+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38314 - "GET /policy?path=. HTTP/1.1" 503
2023-03-27T21
0828.909838+0000 | 7 | fastapi_websocket_rpc.websocket_rpc_e...| INFO | Client disconnected - 38294 :: 8f0a77aa16d240a88dc384d8c3adf773
2023-03-27T21
0828.909967+0000 | 9 | fastapi_websocket_rpc.websocket_rpc_e...| INFO | Client disconnected - 38286 :: 28258cf421e4424b988f1782a8a25945
2023-03-27T21
0828.910450+0000 | 7 | fastapi_websocket_pubsub.event_notifier | INFO | Removing Subscription of topic='xxxxxxx' for subscriber=8f0a77aa16d240a88dc384d8c3adf773
2023-03-27T21
0828.910667+0000 | 9 | fastapi_websocket_pubsub.event_notifier | INFO | Removing Subscription of topic='policy:.' for subscriber=28258cf421e4424b988f1782a8a25945
2023-03-27T21
0828.911063+0000 | 7 | websockets.legacy.server | INFO | connection closed
2023-03-27T21
0828.911203+0000 | 9 | websockets.legacy.server | INFO | connection closed
2023-03-27T21
0837.063426+0000 | 9 | fastapi_websocket_rpc.websocket_rpc_e...| INFO | Client connected
2023-03-27T21
0837.064713+0000 | 9 | websockets.legacy.server | INFO | connection open
2023-03-27T21
0837.066288+0000 | 9 | fastapi_websocket_rpc.websocket_rpc_e...| INFO | Client connected
2023-03-27T21
0837.067100+0000 | 9 | websockets.legacy.server | INFO | connection open
2023-03-27T21
0837.083938+0000 | 9 | fastapi_websocket_pubsub.event_notifier | INFO | New subscription {'id': '5bcb4da06a074ccab593f01810386949', 'subscriber_id': '113c1b8ab8d34ffa80beb1b948d2af26', 'topic': 'policy:.', 'callback': <function RpcEventServerMethods.subscribe.<locals>.callback at 0x7f83704cf2e0>, 'notifier_id': None}
2023-03-27T21
0837.087605+0000 | 9 | fastapi_websocket_pubsub.event_notifier | INFO | New subscription {'id': '365ccdd93a7943b7a9f7356f39c8b178', 'subscriber_id': 'bdb2650238ae4cf68850302bb0a6d354', 'topic': 'xxxxxxxxxxxx', 'callback': <function RpcEventServerMethods.subscribe.<locals>.callback at 0x7f83704e5750>, 'notifier_id': None}
2023-03-27T21
0837.095389+0000 | 9 | opal_server.data.api | INFO | Source configuration is available at 'xxxxxxxxxxxxxxxxxxxxxxxxxxxxx', redirecting with token=eyJhb...YR4JQ (abbrv.)
2023-03-27T21
0837.095760+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38356 - "GET /data/config HTTP/1.1" 307
2023-03-27T21
0837.102899+0000 | 10 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38354 - "GET /policy?path=. HTTP/1.1" 503
2023-03-27T21
0837.330221+0000 | 10 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38364 - "GET /policy?path=. HTTP/1.1" 503
2023-03-27T21
0837.517073+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38366 - "GET /policy?path=. HTTP/1.1" 503
2023-03-27T21
0838.604268+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38370 - "GET /policy?path=. HTTP/1.1" 503
2023-03-27T21
0842.997388+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.7:38376 - "GET /policy?path=. HTTP/1.1" 503
2023-03-27T21
0900.223530+0000 | 8 | opal_server.data.data_update_publisher | INFO | [8] Publishing data update to topics: {'xxxxxxxxxxxxxxxxxx'}, reason: None, entries: [{'url': 'xxxxxxxxxxxxxxxxxxxx', 'method': 'PUT', 'path': '/static', 'inline_data': False, 'topics': ['xxxxxxxxxxxxxxxxxxxxx']}]
2023-03-27T21
0900.224537+0000 | 8 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.1:35876 - "POST /data/config HTTP/1.1" 200
2023-03-27T21
0945.614641+0000 | 9 | opal_server.security.api | INFO | Generated opal token: peer_type=datasource
2023-03-27T21
0945.615949+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.1:35892 - "POST /token HTTP/1.1" 200
2023-03-27T21
1030.311026+0000 | 9 | opal_server.data.data_update_publisher | INFO | [9] Publishing data update to topics: {'xxxxxxxxxxxxx'}, reason: None, entries: [{'url': 'xxxxxxxxxxxxxxxxx', 'method': 'PUT', 'path': '/static', 'inline_data': False, 'topics': ['xxxxxxxxxxxxxxxxxxxx']}]
2023-03-27T21
1030.311625+0000 | 9 | uvicorn.protocols.http.httptools_impl | INFO | 172.22.0.1:35908 - "POST /data/config HTTP/1.1" 200
2023-03-27T21
1030.312596+0000 | 9 | fastapi_websocket_pubsub.event_notifier | INFO | calling subscription callbacks: topic=xxxxxxxxxxxxxxxx, subscription_id=365ccdd93a7943b7a9f7356f39c8b178, subscriber_id=bdb2650238ae4cf68850302bb0a6d354
2023-03-27T21
1030.313851+0000 | 9 | fastapi_websocket_pubsub.rpc_event_me...| INFO | Notifying other side: subscription={'id': '365ccdd93a7943b7a9f7356f39c8b178', 'subscriber_id': 'bdb2650238ae4cf68850302bb0a6d354', 'topic': 'xxxxxxxxxxxxxxxx', 'notifier_id': None}, data=id='f56f9c09-7ec7-4799-914b-a0459ad5d26c' entries=[DataSourceEntry(url='xxxxxxxxxxxxxxxx', data=None, config={'headers': {'authorization': 'bearer xxxxxxxxxxxxxxxxxxxxx'}}, topics=['xxxxxxxxxxxxxxxxxxxxxxx'], dst_path='/static', save_method='PUT')] reason=None callback=UpdateCallback(callbacks=[]), channel_id=bdb2650238ae4cf68850302bb0a6d354