Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.05.2022 16:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16406388 kB
MemAvailable 31373416 kB
Buffers 0 kB
Cached 15278360 kB
SwapCached 2588 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-23 16:38:26
DTS_END                         2022-05-23 16:38:20.3240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51410
TRN_ID                          241566
ATT_ID                          274
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9291.33 19358 1114960
customer order: creation 172.02 370 20642
customer order: refuse 14.88 341 1785
order to supplier: creation 44.95 1399 5394
order to supplier: removal 3.49 102565 419
invoice (draft): creation 45.31 3575 5437
invoice (draft): removal 51.98 862 6237
invoice accept: apply 42.03 19402 5044
invoice accept: cancel 46.90 873 5628
customer reserve: creation 3895.70 192 467484
customer reserve: removal 4674.37 60 560924
realization accept: apply 85.30 185 10236
realization accept: cancel 83.16 115 9979
payment from customer: creation 60.23 110 7227
payment from customer: removal 5.89 91 707
payment to supplier: creation 49.19 128 5903
payment to supplier: removal 3.37 88 404
service: total inventory turnovers 4.51 1240 541
service: total monetary turnovers 7.18 36 861
service: aggregate perf. data 0.87 21067 104
service: refresh index statistics 0.03 234456 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-05-2022 16:38:59 to 23-05-2022 16:39:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6897
TEST_TIME 2 4404
TEST_TIME 3 5537
TEST_TIME 4 5276
TEST_TIME 5 5193
TEST_TIME 6 5024
TEST_TIME 7 5255
TEST_TIME 8 5311
TEST_TIME 9 5641
TEST_TIME 10 5517
TEST_TIME 11 5663
TEST_TIME 12 5821
TEST_TIME 13 5809
TEST_TIME 14 5981
TEST_TIME 15 6664
TEST_TIME 16 6999
TEST_TIME 17 7254
TEST_TIME 18 7387
TEST_TIME 19 7548
TEST_TIME 20 7750
TEST_TIME 21 7840
TEST_TIME 22 8282
TEST_TIME 23 8631
TEST_TIME 24 9536
TEST_TIME 25 9528
TEST_TIME 26 9651
TEST_TIME 27 9833
TEST_TIME 28 9879
TEST_TIME 29 10269
TEST_TIME 30 11038
TEST_TIME 31 10939
TEST_TIME 32 11057
TEST_TIME 33 10837
TEST_TIME 34 10542
TEST_TIME 35 10387
TEST_TIME 36 10419
TEST_TIME 37 10229
TEST_TIME 38 10104
TEST_TIME 39 9988
TEST_TIME 40 9933
TEST_TIME 41 9946
TEST_TIME 42 9871
TEST_TIME 43 9782
TEST_TIME 44 9665
TEST_TIME 45 9506
TEST_TIME 46 9511
TEST_TIME 47 9559
TEST_TIME 48 9616
TEST_TIME 49 9578
TEST_TIME 50 9505
TEST_TIME 51 9377
TEST_TIME 52 9436
TEST_TIME 53 9408
TEST_TIME 54 9328
TEST_TIME 55 9249
TEST_TIME 56 9205
TEST_TIME 57 9235
TEST_TIME 58 9220
TEST_TIME 59 9252
TEST_TIME 60 9274
TEST_TIME 61 9331
TEST_TIME 62 9375
TEST_TIME 63 9384
TEST_TIME 64 9530
TEST_TIME 65 9467
TEST_TIME 66 9435
TEST_TIME 67 9540
TEST_TIME 68 9548
TEST_TIME 69 9496
TEST_TIME 70 9579
TEST_TIME 71 9544
TEST_TIME 72 9469
TEST_TIME 73 9417
TEST_TIME 74 9380
TEST_TIME 75 9404
TEST_TIME 76 9371
TEST_TIME 77 9303
TEST_TIME 78 9329
TEST_TIME 79 9323
TEST_TIME 80 9282
TEST_TIME 81 9326
TEST_TIME 82 9322
TEST_TIME 83 9340
TEST_TIME 84 9355
TEST_TIME 85 9366
TEST_TIME 86 9372
TEST_TIME 87 9326
TEST_TIME 88 9347
TEST_TIME 89 9483
TEST_TIME 90 9483
TEST_TIME 91 9446
TEST_TIME 92 9487
TEST_TIME 93 9499
TEST_TIME 94 9504
TEST_TIME 95 9440
TEST_TIME 96 9378
TEST_TIME 97 9371
TEST_TIME 98 9341
TEST_TIME 99 9323
TEST_TIME 100 9287
TEST_TIME 101 9275
TEST_TIME 102 9248
TEST_TIME 103 9242
TEST_TIME 104 9264
TEST_TIME 105 9249
TEST_TIME 106 9221
TEST_TIME 107 9172
TEST_TIME 108 9228
TEST_TIME 109 9189
TEST_TIME 110 9212
TEST_TIME 111 9204
TEST_TIME 112 9175
TEST_TIME 113 9212
TEST_TIME 114 9252
TEST_TIME 115 9255
TEST_TIME 116 9258
TEST_TIME 117 9229
TEST_TIME 118 9253
TEST_TIME 119 9360
TEST_TIME 120 9295

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-05-2022 16:39:03 to 23-05-2022 16:39:06.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1547601 1547601 0 0.00 0 16228 6 0 0
doc_list_biud 1547598 1547598 0 0.00 0 32346 28 0 0
sp_add_invoice_to_stock 5168 5044 124 2.39 32 268355 19402 0 124
sp_cancel_adding_invoice 5628 5628 0 0.00 4 64488 874 0 0
sp_cancel_client_order 1785 1785 0 0.00 4 13276 342 0 0
sp_cancel_customer_reserve 560924 560924 0 0.00 1 23479 60 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 4451 91 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 3588 89 0 0
sp_cancel_supplier_invoice 6241 6237 4 0.06 1 32354 863 0 4
sp_cancel_supplier_order 419 419 0 0.00 10 1203695 102565 0 0
sp_cancel_write_off 9979 9979 0 0.00 1 16310 115 0 0
sp_client_order 20642 20642 0 0.00 3 34181 370 0 0
sp_customer_reserve 467484 467484 0 0.00 0 39656 192 0 0
sp_fill_shopping_cart 31461 31461 0 0.00 1 15772 43 0 0
sp_fill_shopping_cart_clo_res 467477 467477 0 0.00 0 18147 13 0 0
sp_get_clo_for_invoice 5037 5037 0 0.00 0 44786 332 0 0
sp_kill_cost_storno 16718 16718 0 0.00 0 7170 56 0 0
sp_kill_qstorno_ret_qs2qd 1138483 1138483 0 0.00 0 32343 33 0 0
sp_kill_qty_storno 600244 600244 0 0.00 0 32345 71 0 0
sp_lock_selected_doc 21089 21089 0 0.00 0 5514 5 0 0
sp_make_cost_storno 28403 28403 0 0.00 0 14914 56 0 0
sp_multiply_rows_for_pdistr 28403 28403 0 0.00 0 6335 30 0 0
sp_multiply_rows_for_qdistr 20642 20642 0 0.00 0 34164 156 0 0
sp_pay_from_customer 7227 7227 0 0.00 4 20520 111 0 0
sp_pay_to_supplier 5903 5903 0 0.00 4 7945 128 0 0
sp_payment_common 13129 13129 0 0.00 1 20510 31 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 13203 316 0 0
sp_qd_handle_on_invoice_upd_sts 10665 10665 0 0.00 0 31657 347 0 0
sp_qd_handle_on_reserve_upd_sts 10235 10235 0 0.00 0 12708 65 0 0
sp_reserve_write_off 10236 10236 0 0.00 7 14863 186 0 0
sp_supplier_invoice 5437 5437 0 0.00 5 112115 3576 0 0
sp_supplier_order 5394 5394 0 0.00 5 32513 1399 0 0
srv_aggregate_perf_data 105 104 1 0.95 2340 386464 21068 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1970 131668 44525 0 0
srv_find_qd_qs_mism 1066808 1066808 0 0.00 0 16227 6 0 0
srv_make_invnt_saldo 541 541 0 0.00 27 94116 1241 0 0
srv_make_money_saldo 861 861 0 0.00 0 6672 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 118067 315300 234456 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5044 5044 0 0.00 0 30767 194 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 4957 94 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 6049 19 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 4636 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 6270 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 1940 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 1654 19 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 34354 1860 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 10854 22 0 0
t$perf-norm:sp_client_order 20642 20642 0 0.00 0 11511 21 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 5577 18 0 0
t$perf-norm:sp_pay_from_customer 7227 7227 0 0.00 0 12333 14 0 0
t$perf-norm:sp_pay_to_supplier 5903 5903 0 0.00 0 12011 12 0 0
t$perf-norm:sp_reserve_write_off 10236 10236 0 0.00 0 5615 13 0 0
t$perf-norm:sp_supplier_invoice 5437 5437 0 0.00 0 9366 21 0 0
t$perf-norm:sp_supplier_order 5394 5394 0 0.00 0 11863 20 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 5263 106 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 1135 18 0 0
t$perf-norm:srv_make_money_saldo 861 861 0 0.00 0 850 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10320 10072 248 2.40 0 4542 16 0 248
v_all_customers 37640 37640 0 0.00 0 17 1 0 0
v_all_suppliers 30082 30082 0 0.00 0 28 1 0 0
v_all_wares 41280 41280 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 2132 11 0 0
v_cancel_client_order 3570 3570 0 0.00 0 1448 4 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 3890 12 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 1049 3 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 3398 10 0 0
v_cancel_supplier_invoice 820 812 8 0.97 0 3207 21 0 8
v_cancel_supplier_order 838 838 0 0.00 0 3162 9 0 0
v_cancel_write_off 2010 2010 0 0.00 0 1853 14 0 0
v_max_id_clo_ord 5392 5392 0 0.00 0 330 1 0 0
v_max_id_clo_res 1045 1045 0 0.00 0 1941 7 0 0
v_max_id_ord_sup 5429 5429 0 0.00 0 4230 2 0 0
v_max_non_paid_invoice 1683 1683 0 0.00 0 4843 9 0 0
v_max_non_paid_realizn 4015 4015 0 0.00 0 6562 8 0 0
v_min_id_clo_ord 5392 5392 0 0.00 0 5968 12 0 0
v_min_id_clo_res 1045 1045 0 0.00 0 6147 18 0 0
v_min_id_ord_sup 5429 5429 0 0.00 0 5896 15 0 0
v_min_non_paid_invoice 5903 5903 0 0.00 0 6903 19 0 0
v_min_non_paid_realizn 7226 7226 0 0.00 0 8620 21 0 0
v_our_firm 10062 10062 0 0.00 0 19 1 0 0
v_reserve_write_off 20470 20470 0 0.00 0 3160 6 0 0
x_lock_depdocs_on_canc_invoice 11459 11459 0 0.00 0 21067 130 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 10416 363 0 0
x_make_qstorno_1000_1200 5394 5394 0 0.00 2 32503 1303 0 0
x_make_qstorno_1200_2000 5437 5437 0 0.00 2 112093 3425 0 0
x_make_qstorno_2100_3300 467484 467484 0 0.00 0 39583 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-05-2022 16:39:07 to 23-05-2022 16:39:11.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:24 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:25 35354 8192000000 9900586080 10209296384 980374880 10.688 100 92 184 0 1011108976 6227216 24506880
14:11:09 24998 8192000000 9949937376 10271617024 1029156992 11.160 100 86 172 0 1059964656 8439200 22368464
14:12:39 19173 8192000000 9978606464 10311970816 1061559312 11.471 100 78 156 0 1090971744 9995392 19417040
14:14:02 13580 8192000000 10052496368 10401521664 1137387104 12.191 100 78 156 0 1166695216 11018320 18289792
14:15:19 8729 8192000000 10092624048 10455408640 1184719184 12.634 100 76 152 1 1215142992 12754608 17669200
14:16:32 3821 8192000000 10149238320 10519023616 1231669088 13.069 100 88 176 0 1264519488 13679456 19170944
14:17:37 2016 8192000000 10170383856 10544979968 1261017872 13.339 100 83 160 2 1291960016 12454128 18488016
14:18:41 1894 8192000000 10200235584 10580566016 1281896928 13.530 100 88 176 0 1316034400 13789120 20348352
14:19:43 9209 8192000000 10192372288 10593812480 1295960368 13.658 100 42 84 1 1314021568 8419680 9641520
14:21:03 14735 8192000000 10176384448 10591776768 1300301328 13.698 100 32 61 2 1313074768 5288096 7485344
14:22:19 8166 8192000000 10195175440 10615283712 1312095120 13.805 100 16 31 0 1321753440 6495280 3163040
14:23:28 9962 8192000000 10201517232 10622992384 1323414576 13.908 100 11 22 0 1331655984 5874928 2366480
14:24:41 6380 8192000000 10218985008 10636800000 1333936640 14.003 100 24 48 1 1345519376 6247536 5335200
14:25:50 41658 8192000000 10246082880 10650558464 1337400384 14.034 100 63 128 0 1357878688 5217344 15260960
14:27:32 4944 8192000000 10220586640 10635427840 1316421168 13.844 99 33 66 1 1329477104 5641424 7414512
14:28:42 8188 8192000000 10193798032 10608410624 1297901376 13.676 99 13 26 0 1305820256 4742064 3176816
14:29:50 57126 8192000000 10131305184 10527567872 1166373120 12.463 92 35 70 0 1180872704 6272704 8226880
14:31:49 6469 8192000000 10127941552 10523148288 1234127664 13.092 100 30 60 1 1247339472 6325808 6886000
14:33:00 4164 8192000000 10147235664 10543833088 1230576640 13.059 98 32 64 0 1244001584 6219600 7205344
14:34:05 7148 8192000000 10153685872 10558181376 1262199520 13.350 100 19 38 1 1273106224 5836496 5070208
14:35:13 41695 8192000000 10165625296 10576551936 1276669248 13.483 100 28 56 1 1289428768 5658160 7101360
14:36:57 9042 8192000000 10174452272 10588233728 1288158448 13.587 100 27 54 1 1300207904 5659984 6389472
14:38:10 8040 8192000000 10198150288 10615902208 1311510592 13.800 100 27 54 1 1323028560 4821424 6696544
14:39:24 9344 8192000000 10248824272 10652028928 1335870944 14.020 100 56 113 1 1356914960 8930288 12113728
14:40:44 23900 8192000000 10248976336 10664923136 1343137504 14.086 100 53 106 2 1365489168 10298944 12052720
14:42:14 45430 8192000000 10256664608 10677248000 1352141760 14.167 100 28 56 0 1370409520 11948560 6319200
14:44:02 9347 8192000000 10279709552 10697838592 1368211728 14.311 100 48 96 2 1394554176 15179248 11163200
14:45:15 5884 8192000000 10301214800 10712576000 1376286816 14.383 100 70 140 4 1411429328 18521312 16621200
14:46:28 10685 8192000000 10296112864 10715086848 1370932800 14.335 99 54 108 2 1401060512 17486816 12640896
14:47:43 12114 8192000000 10301539792 10724392960 1373705968 14.360 99 49 96 1 1408723952 23364320 11653664
14:49:02 5070 8192000000 10322113312 10738667520 1392618928 14.529 100 60 119 2 1434582128 27513744 14449456
14:50:14 12918 8192000000 10339673168 10763329536 1408773152 14.673 100 70 138 0 1455618752 29931600 16914000
14:51:39 3448 8192000000 10345903008 10759274496 1404740720 14.637 100 73 147 0 1452120096 29763424 17615952
14:52:50 2402 8192000000 10355359728 10764378112 1400500112 14.599 100 90 180 2 1458308368 35573296 22234960
14:53:57 4390 8192000000 10331536032 10743148544 1382720784 14.441 100 78 154 2 1435531984 34282896 18528304
14:55:06 5734 8192000000 10314689232 10720059392 1355072048 14.193 99 83 166 0 1411736416 35844624 20819744
14:56:18 3106 8192000000 10332383136 10742075392 1379106528 14.409 100 78 154 1 1439018304 40025728 19886048
14:57:26 1967 8192000000 10351764752 10754396160 1382320912 14.437 100 91 179 0 1453657120 48275792 23060416
14:58:32 6447 8192000000 10339367616 10745958400 1374998912 14.372 100 79 156 3 1445670624 50238176 20433536
14:59:45 18294 8192000000 10331674256 10751078400 1353370576 14.178 98 46 92 0 1421431664 55162512 12898576
15:01:07 11763 8192000000 10336398304 10758377472 1362945680 14.264 99 41 82 4 1427086544 52627648 11513216
15:02:23 11139 8192000000 10302753536 10738544640 1358317760 14.222 99 30 59 1 1413941472 47481952 8141760
15:03:50 5987 8192000000 10308390624 10735386624 1364332816 14.276 99 36 72 2 1409348992 35477824 9538352
15:04:58 23879 8192000000 10311244832 10759884800 1374234656 14.365 100 21 41 1 1417831776 38007696 5589424
15:06:24 33750 8192000000 10315736096 10750599168 1357632704 14.216 99 17 34 1 1400937120 38291664 5012752
15:07:59 70092 8192000000 10292236208 10758766592 1354547616 14.188 99 11 22 0 1393924544 36572000 2804928
15:10:09 46546 8192000000 10297441136 10755866624 1394166784 14.543 100 24 48 2 1411227280 11587424 5473072
15:11:56 47380 8192000000 10283498832 10722848768 1357017792 14.211 98 30 61 1 1371287744 6559040 7710912
15:14:16 77454 8192000000 10288768816 10741526528 1389672992 14.503 100 19 39 0 1402731648 9267984 3790672
15:16:38 42438 8192000000 10289421984 10739396608 1370209168 14.329 98 27 54 1 1385308928 8172992 6926768
15:18:31 46211 8192000000 10266870672 10722754560 1352380016 14.169 97 13 26 1 1365094720 9535488 3179216
15:20:17 46301 8192000000 10291959872 10750877696 1391834544 14.522 100 15 30 1 1405460592 10533120 3092928
15:22:04 48037 8192000000 10306704752 10763681792 1402142080 14.614 100 12 24 0 1415272496 10343920 2786496
15:24:04 120024 8192000000 10302763984 10772877312 1384989024 14.461 99 24 48 1 1412772624 21332800 6450800
15:27:12 46726 8192000000 10286728640 10745507840 1389125232 14.498 99 18 36 1 1401500576 8202336 4173008
15:29:00 31363 8192000000 10300876272 10754293760 1390060144 14.506 99 31 62 1 1405184144 8285568 6838432
15:30:36 60742 8192000000 10290467680 10757812224 1384419152 14.456 99 11 22 0 1395587248 8817872 2350224
15:32:37 60145 8192000000 10268555728 10738651136 1376462704 14.385 99 10 20 2 1386603360 7839888 2300768
15:35:05 65639 8192000000 10282689200 10742435840 1375086512 14.373 99 7 15 0 1386388128 9706544 1595072
15:37:11 62586 8192000000 10290730624 10746396672 1378714336 14.405 99 12 24 0 1391416832 10100880 2601616
15:39:40 35805 8192000000 10293769680 10742177792 1368941488 14.318 99 22 44 1 1382404928 8567408 4896032
15:41:24 60441 8192000000 10291804256 10745110528 1349025152 14.139 98 11 22 0 1366399856 14926784 2447920
15:43:25 16113 8192000000 10292316544 10729734144 1375078048 14.373 100 48 91 1 1391077248 6204432 9794768
15:44:47 82884 8192000000 10272440512 10729041920 1321522768 13.890 96 21 42 1 1340801120 14939792 4338560
15:47:14 42823 8192000000 10277118640 10717679616 1351327136 14.159 98 38 76 4 1372933504 13173536 8432832
15:49:07 122100 8192000000 10283158160 10721189888 1320116016 13.878 96 22 44 0 1340139328 14847936 5175376
15:52:10 36354 8192000000 10286991712 10713661440 1327976544 13.949 97 50 99 2 1351909632 12733216 11199872
15:53:47 39645 8192000000 10255354576 10711699456 1348491664 14.134 99 29 58 0 1374231344 18822240 6917440
15:55:36 46365 8192000000 10273190496 10719023104 1363105584 14.265 100 19 38 1 1383234384 15556784 4572016
15:57:43 61403 8192000000 10252656224 10698600448 1343188944 14.086 99 14 28 0 1359313536 12752784 3371808
16:00:00 72278 8192000000 10266352784 10724257792 1347960160 14.129 99 9 19 0 1362592752 12714384 1918208
16:03:21 103370 8192000000 10282404544 10731999232 1352051184 14.166 99 20 40 0 1367822880 11372384 4399312
16:06:05 54827 8192000000 10296624720 10757771264 1384381456 14.456 100 18 36 0 1406522624 17776480 4364688
16:08:13 86102 8192000000 10284838816 10726600704 1336047632 14.022 96 20 40 0 1350165616 10233008 3884976
16:10:40 125798 8192000000 10286683120 10742673408 1315486816 13.836 95 10 20 1 1327440784 9808080 2145888
16:13:51 131668 8192000000 10285284288 10745491456 1362456352 14.259 98 35 70 0 1378917648 9020736 7440560
16:17:08 20473 8192000000 10278846880 10725568512 1365603712 14.288 98 44 86 0 1385577328 10374272 9599344
16:18:29 54074 8192000000 10283880752 10737270784 1355036928 14.193 98 13 26 2 1368467296 10448560 2981808
16:20:57 32215 8192000000 10272847776 10739957760 1359831872 14.236 98 24 48 0 1377365584 11780448 5753264
16:22:33 70245 8192000000 10274039760 10729467904 1371152384 14.337 100 9 18 0 1381951072 8687616 2111072
16:24:50 50866 8192000000 10299204336 10759069696 1383592768 14.449 100 19 38 1 1408044960 20266128 4186064
16:26:45 60470 8192000000 10287761824 10762072064 1375475248 14.376 99 8 16 0 1395559680 18305760 1778672
16:28:46 48125 8192000000 10277580176 10735345664 1348200144 14.131 97 18 36 0 1358560800 6871712 3488944
16:30:34 36335 8192000000 10286487328 10741215232 1357867808 14.218 98 27 54 0 1379540848 15350208 6322832
16:32:11 102356 8192000000 10286446464 10744385536 1319836176 13.875 95 30 60 1 1337661632 10727392 7098064
16:34:53 53889 8192000000 10281809648 10729091072 1340521536 14.062 97 15 30 0 1358162480 14058720 3582224
16:36:47 63726 8192000000 10270960064 10742005760 1335850144 14.020 98 13 26 0 1355576064 16734240 2991680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-05-2022 16:39:13 to 23-05-2022 16:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 124 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-05-2022 16:39:29 to 23-05-2022 16:39:31.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          247027
MON$OLDEST_ACTIVE               247028
MON$OLDEST_SNAPSHOT             247028
MON$NEXT_TRANSACTION            247028
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2035552
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

23.05.22 16:39:32. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-05-23 16:39:34 No severe PSQL-related problems occured
23.05.22 16:39:36. End of report.