Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.12.2022 04:39
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 14775060 kB
MemAvailable 30085652 kB
Buffers 20 kB
Cached 16888476 kB
SwapCached 3416 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-12-10 04:37:22
DTS_END                         2022-12-10 04:37:22.2080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53588
TRN_ID                          212081
ATT_ID                          564
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: 8936.86 29704 1072423
customer order: creation 159.77 516 19172
customer order: refuse 13.58 494 1630
order to supplier: creation 43.28 1390 5194
order to supplier: removal 3.62 154532 434
invoice (draft): creation 43.34 3269 5201
invoice (draft): removal 49.58 1023 5949
invoice accept: apply 40.41 29773 4849
invoice accept: cancel 44.48 1379 5338
customer reserve: creation 3859.55 278 463146
customer reserve: removal 4405.64 98 528677
realization accept: apply 78.39 300 9407
realization accept: cancel 72.65 191 8718
payment from customer: creation 55.76 201 6691
payment from customer: removal 5.39 184 647
payment to supplier: creation 45.83 238 5500
payment to supplier: removal 3.14 169 377
service: total inventory turnovers 3.93 4333 472
service: total monetary turnovers 7.56 382 907
service: aggregate perf. data 0.92 17699 110
service: refresh index statistics 0.03 377638 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-12-2022 04:39:39 to 10-12-2022 04:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3084
TEST_TIME 2 5406
TEST_TIME 3 5705
TEST_TIME 4 5601
TEST_TIME 5 5726
TEST_TIME 6 5576
TEST_TIME 7 5782
TEST_TIME 8 6046
TEST_TIME 9 5987
TEST_TIME 10 6241
TEST_TIME 11 6571
TEST_TIME 12 6709
TEST_TIME 13 6955
TEST_TIME 14 7185
TEST_TIME 15 7176
TEST_TIME 16 7244
TEST_TIME 17 7207
TEST_TIME 18 7267
TEST_TIME 19 7337
TEST_TIME 20 7459
TEST_TIME 21 7446
TEST_TIME 22 7799
TEST_TIME 23 7787
TEST_TIME 24 7979
TEST_TIME 25 8392
TEST_TIME 26 8420
TEST_TIME 27 8361
TEST_TIME 28 8823
TEST_TIME 29 8894
TEST_TIME 30 8989
TEST_TIME 31 9318
TEST_TIME 32 9308
TEST_TIME 33 9592
TEST_TIME 34 9746
TEST_TIME 35 10174
TEST_TIME 36 10392
TEST_TIME 37 10455
TEST_TIME 38 10370
TEST_TIME 39 10411
TEST_TIME 40 10300
TEST_TIME 41 10223
TEST_TIME 42 10118
TEST_TIME 43 9993
TEST_TIME 44 9776
TEST_TIME 45 9882
TEST_TIME 46 9826
TEST_TIME 47 9690
TEST_TIME 48 9708
TEST_TIME 49 9668
TEST_TIME 50 9646
TEST_TIME 51 9631
TEST_TIME 52 9686
TEST_TIME 53 9616
TEST_TIME 54 9625
TEST_TIME 55 9586
TEST_TIME 56 9525
TEST_TIME 57 9441
TEST_TIME 58 9410
TEST_TIME 59 9336
TEST_TIME 60 9206
TEST_TIME 61 9173
TEST_TIME 62 9175
TEST_TIME 63 9104
TEST_TIME 64 9140
TEST_TIME 65 9145
TEST_TIME 66 9103
TEST_TIME 67 9230
TEST_TIME 68 9221
TEST_TIME 69 9186
TEST_TIME 70 9251
TEST_TIME 71 9371
TEST_TIME 72 9347
TEST_TIME 73 9302
TEST_TIME 74 9343
TEST_TIME 75 9427
TEST_TIME 76 9314
TEST_TIME 77 9310
TEST_TIME 78 9246
TEST_TIME 79 9273
TEST_TIME 80 9263
TEST_TIME 81 9198
TEST_TIME 82 9155
TEST_TIME 83 9100
TEST_TIME 84 9088
TEST_TIME 85 9050
TEST_TIME 86 9014
TEST_TIME 87 9018
TEST_TIME 88 8959
TEST_TIME 89 8977
TEST_TIME 90 8980
TEST_TIME 91 8945
TEST_TIME 92 8947
TEST_TIME 93 9035
TEST_TIME 94 9040
TEST_TIME 95 9023
TEST_TIME 96 9008
TEST_TIME 97 9023
TEST_TIME 98 9131
TEST_TIME 99 9134
TEST_TIME 100 9065
TEST_TIME 101 9082
TEST_TIME 102 9063
TEST_TIME 103 9035
TEST_TIME 104 9000
TEST_TIME 105 9013
TEST_TIME 106 8977
TEST_TIME 107 8965
TEST_TIME 108 8996
TEST_TIME 109 8958
TEST_TIME 110 8924
TEST_TIME 111 8950
TEST_TIME 112 8992
TEST_TIME 113 8990
TEST_TIME 114 9015
TEST_TIME 115 9041
TEST_TIME 116 9015
TEST_TIME 117 9038
TEST_TIME 118 9031
TEST_TIME 119 9019
TEST_TIME 120 8945

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-12-2022 04:39:43 to 10-12-2022 04:39:46.

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 1497751 1497751 0 0.00 0 28511 14 0 0
doc_list_biud 1497748 1497748 0 0.00 0 52855 41 0 0
sp_add_invoice_to_stock 4949 4849 100 2.02 70 198488 29773 0 100
sp_cancel_adding_invoice 5338 5338 0 0.00 7 85595 1380 0 0
sp_cancel_client_order 1630 1630 0 0.00 5 8469 494 0 0
sp_cancel_customer_reserve 528677 528677 0 0.00 1 32784 99 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 4 16907 184 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 4236 170 0 0
sp_cancel_supplier_invoice 5962 5949 13 0.21 4 52927 1023 0 13
sp_cancel_supplier_order 434 434 0 0.00 12 1576328 154532 0 0
sp_cancel_write_off 8718 8718 0 0.00 2 8424 191 0 0
sp_client_order 19172 19172 0 0.00 5 66601 516 0 0
sp_customer_reserve 463146 463146 0 0.00 1 124544 278 0 0
sp_fill_shopping_cart 29558 29558 0 0.00 1 10439 84 0 0
sp_fill_shopping_cart_clo_res 463136 463136 0 0.00 0 14458 19 0 0
sp_get_clo_for_invoice 4834 4834 0 0.00 0 15790 274 0 0
sp_kill_cost_storno 15080 15080 0 0.00 0 28506 91 0 0
sp_kill_qstorno_ret_qs2qd 1072455 1072455 0 0.00 0 52853 43 0 0
sp_kill_qty_storno 564987 564987 0 0.00 0 52854 100 0 0
sp_lock_selected_doc 19716 19716 0 0.00 0 9910 9 0 0
sp_make_cost_storno 26432 26432 0 0.00 0 24489 90 0 0
sp_multiply_rows_for_pdistr 26432 26432 0 0.00 0 17491 44 0 0
sp_multiply_rows_for_qdistr 19172 19172 0 0.00 0 13351 185 0 0
sp_pay_from_customer 6691 6691 0 0.00 6 16027 201 0 0
sp_pay_to_supplier 5500 5500 0 0.00 7 20915 239 0 0
sp_payment_common 12191 12191 0 0.00 2 7263 47 0 0
sp_qd_handle_on_cancel_clo 1630 1630 0 0.00 1 8286 401 0 0
sp_qd_handle_on_invoice_upd_sts 10172 10172 0 0.00 0 33839 588 0 0
sp_qd_handle_on_reserve_upd_sts 9407 9407 0 0.00 0 24406 60 0 0
sp_reserve_write_off 9407 9407 0 0.00 8 24453 300 0 0
sp_supplier_invoice 5201 5201 0 0.00 9 89579 3270 0 0
sp_supplier_order 5194 5194 0 0.00 7 47602 1391 0 0
srv_aggregate_perf_data 110 110 0 0.00 2573 187630 17700 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 745 140906 34992 0 0
srv_find_qd_qs_mism 1024767 1024767 0 0.00 0 9259 11 0 0
srv_make_invnt_saldo 472 472 0 0.00 61 54199 4333 0 0
srv_make_money_saldo 907 907 0 0.00 1 11194 383 0 0
srv_recalc_idx_stat 4 4 0 0.00 199015 472296 377639 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4851 4851 0 0.00 1 10605 1052 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 1 6984 510 0 0
t$perf-norm:sp_cancel_client_order 1630 1630 0 0.00 0 4339 36 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 5662 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 5128 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 3177 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 3756 51 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 90540 7167 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 1533 23 0 0
t$perf-norm:sp_client_order 19172 19172 0 0.00 0 7974 39 0 0
t$perf-norm:sp_customer_reserve 910 910 0 0.00 0 3915 26 0 0
t$perf-norm:sp_pay_from_customer 6691 6691 0 0.00 0 11226 27 0 0
t$perf-norm:sp_pay_to_supplier 5500 5500 0 0.00 0 20718 29 0 0
t$perf-norm:sp_reserve_write_off 9407 9407 0 0.00 0 25600 36 0 0
t$perf-norm:sp_supplier_invoice 5201 5201 0 0.00 0 6119 36 0 0
t$perf-norm:sp_supplier_order 5194 5194 0 0.00 0 4055 34 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 304 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 792 20 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 1301 16 0 0
t$perf-norm:srv_make_money_saldo 907 907 0 0.00 0 399 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 203 60 0 0
v_add_invoice_to_stock 9868 9668 200 2.02 0 7871 29 0 200
v_all_customers 35120 35120 0 0.00 0 76 4 0 0
v_all_suppliers 29014 29014 0 0.00 0 204 5 0 0
v_all_wares 38344 38344 0 0.00 0 58 4 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 1104 18 0 0
v_cancel_client_order 3260 3260 0 0.00 0 3652 17 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 2862 16 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 2537 16 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 372 12 0 0
v_cancel_supplier_invoice 806 780 26 3.22 0 1288 29 0 26
v_cancel_supplier_order 868 868 0 0.00 0 3260 22 0 0
v_cancel_write_off 1928 1928 0 0.00 0 4515 30 0 0
v_max_id_clo_ord 5194 5194 0 0.00 0 1558 6 0 0
v_max_id_clo_res 910 910 0 0.00 0 2442 19 0 0
v_max_id_ord_sup 5192 5192 0 0.00 0 4867 8 0 0
v_max_non_paid_invoice 1379 1379 0 0.00 0 197 9 0 0
v_max_non_paid_realizn 3525 3525 0 0.00 0 5317 10 0 0
v_min_id_clo_ord 5194 5194 0 0.00 0 2229 9 0 0
v_min_id_clo_res 910 910 0 0.00 0 6481 27 0 0
v_min_id_ord_sup 5192 5192 0 0.00 0 9346 11 0 0
v_min_non_paid_invoice 5500 5500 0 0.00 0 4061 13 0 0
v_min_non_paid_realizn 6691 6691 0 0.00 0 5473 18 0 0
v_our_firm 9556 9556 0 0.00 0 60 5 0 0
v_reserve_write_off 18814 18814 0 0.00 0 7883 13 0 0
x_lock_depdocs_on_canc_invoice 10897 10897 0 0.00 0 13803 124 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 6162 380 0 0
x_make_qstorno_1000_1200 5194 5194 0 0.00 2 47575 1208 0 0
x_make_qstorno_1200_2000 5201 5201 0 0.00 3 88301 3055 0 0
x_make_qstorno_2100_3300 463145 463145 0 0.00 0 124539 246 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2022 04:39:47 to 10-12-2022 04:39:51.

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
02:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:28 32234 8192000000 9724125072 10020184064 793243776 8.828 100 92 184 0 823847472 7810128 22793568
02:10:06 20509 8192000000 9747589296 10054995968 823047776 9.129 100 84 168 0 856470944 12773392 20649776
02:11:32 16702 8192000000 9793228944 10112262144 862318576 9.523 100 78 154 0 904046256 23567344 18160336
02:12:56 7195 8192000000 9861167888 10192523264 912961648 10.027 100 89 178 0 975282000 42564240 19756112
02:14:10 4367 8192000000 9902876960 10245586944 949466624 10.386 100 87 162 3 1018138384 51491056 17180704
02:15:17 3226 8192000000 9964177360 10311421952 993696816 10.817 100 92 183 0 1080942032 68050528 19194688
02:16:24 1399 8192000000 9980992832 10339119104 1010339264 10.979 100 96 182 1 1101298608 72610144 18349200
02:17:28 1422 8192000000 10005154112 10365505536 1030520720 11.173 100 97 183 1 1129085760 81063040 17502000
02:18:31 1321 8192000000 10016412768 10391621632 1049010720 11.351 100 93 180 0 1138484352 70600304 18873328
02:19:34 1350 8192000000 10032213728 10407579648 1062059424 11.476 100 98 185 3 1151491728 70910416 18521888
02:20:37 863 8192000000 10030993584 10407628800 1066485648 11.519 100 97 190 3 1148142816 61998224 19658944
02:21:39 948 8192000000 10042738992 10426511360 1077724896 11.626 100 96 183 0 1166401280 69343168 19333216
02:22:42 1268 8192000000 10057365312 10445561856 1082569680 11.672 100 95 168 2 1183516096 82851648 18094768
02:23:44 1388 8192000000 10056343840 10436120576 1085908928 11.704 100 92 165 4 1178204096 76895888 15399280
02:24:46 1014 8192000000 10059050128 10449526784 1089953376 11.742 100 95 183 0 1184328464 75739216 18635872
02:25:48 3745 8192000000 10048624256 10435063808 1095071920 11.791 100 85 164 1 1179435952 68596336 15767696
02:26:52 6372 8192000000 10037649952 10435604480 1093577616 11.777 100 64 124 1 1161951168 55786384 12587168
02:28:03 2439 8192000000 10050496064 10446319616 1095842192 11.798 100 78 151 1 1174573664 63523408 15208064
02:29:08 5014 8192000000 10036978304 10431356928 1089257984 11.736 100 60 112 0 1160233008 60359504 10615520
02:30:14 1330 8192000000 10049625392 10438688768 1083244800 11.678 100 88 164 1 1167139216 68542624 15351792
02:31:16 1153 8192000000 10074797728 10461253632 1080136800 11.649 100 100 187 1 1196701520 97830080 18734640
02:32:18 980 8192000000 10022891872 10407534592 1053942624 11.398 100 88 170 1 1147518880 76018448 17557808
02:33:21 894 8192000000 10011127296 10388176896 1040035856 11.265 98 80 155 3 1135233952 78183104 17014992
02:34:24 1057 8192000000 9988918576 10357002240 1023079440 11.102 100 94 179 1 1110614928 68525872 19009616
02:35:26 3237 8192000000 9964985424 10341822464 1008623744 10.962 98 62 111 0 1079545712 59995200 10926768
02:36:31 715 8192000000 9959897376 10333204480 1032470208 11.192 100 85 157 2 1083392288 34552368 16369712
02:37:33 2549 8192000000 9943055648 10319470592 1030061136 11.169 100 76 134 2 1060992512 17008944 13922432
02:38:41 14268 8192000000 9980816912 10353152000 1047987664 11.341 100 75 143 0 1096234752 34219952 14027136
02:39:58 1527 8192000000 10047494704 10427256832 1068611648 11.539 100 92 177 0 1159647312 73024768 18010896
02:41:04 4211 8192000000 10046976016 10428887040 1061131232 11.467 98 79 158 1 1166550368 88889392 16529744
02:42:13 2134 8192000000 10102261248 10486292480 1093670640 11.778 100 79 153 1 1216887824 107899648 15317536
02:43:19 934 8192000000 10153947472 10538385408 1104836336 11.884 100 96 189 0 1270876448 145598528 20441584
02:44:28 1479 8192000000 10161739120 10559848448 1117218672 12.001 100 89 174 0 1281387568 143989536 20179360
02:45:34 1690 8192000000 10177865872 10576883712 1120952960 12.036 100 84 159 1 1283948096 146125312 16869824
02:46:42 3109 8192000000 10206554448 10596212736 1134618544 12.165 100 91 172 2 1315219264 162305008 18295712
02:47:49 2085 8192000000 10206909040 10620268544 1137750528 12.194 99 85 151 1 1310588624 156955472 15882624
02:48:53 1316 8192000000 10260011120 10657304576 1157001648 12.375 100 94 177 1 1372069280 195551184 19516448
02:50:00 1053 8192000000 10263819120 10670866432 1157522176 12.380 100 97 183 4 1370307584 193832880 18952528
02:51:04 1583 8192000000 10287419280 10694164480 1167274368 12.471 100 86 165 0 1399140192 213397296 18468528
02:52:08 2373 8192000000 10291309392 10689331200 1173955488 12.534 100 94 185 0 1398107680 203154128 20998064
02:53:13 734 8192000000 10330137824 10728517632 1177644640 12.568 100 93 177 1 1434974560 237778992 19550928
02:54:17 774 8192000000 10366766704 10773250048 1190921136 12.692 100 98 188 1 1476301616 263726320 21654160
02:55:22 3890 8192000000 10405981744 10820116480 1187816432 12.663 100 90 176 1 1513441312 305400128 20224752
02:56:32 1070 8192000000 10444563632 10868359168 1195723008 12.737 100 99 192 0 1548299744 330171232 22405504
02:57:35 798 8192000000 10483796112 10890125312 1190294528 12.686 100 92 182 0 1588537744 376849808 21393408
02:58:40 804 8192000000 10460304272 10905456640 1187895952 12.664 100 96 188 1 1567001232 356371312 22733968
02:59:44 735 8192000000 10471218448 10888060928 1172816272 12.523 100 96 185 0 1572746928 376952400 22978256
03:00:48 1058 8192000000 10475966560 10927996928 1178238128 12.574 100 97 190 1 1579847008 378991632 22617248
03:01:53 1776 8192000000 10471549776 10916225024 1182738272 12.616 100 95 184 1 1576620640 372019840 21862528
03:02:57 2409 8192000000 10465549440 10910248960 1186920464 12.655 100 77 154 0 1572696768 367071968 18704336
03:04:05 6215 8192000000 10494389616 10946854912 1187925872 12.664 100 60 120 0 1592673648 389906608 14841168
03:05:20 1779 8192000000 10480642384 10941476864 1199553344 12.772 100 71 142 0 1579706016 362670432 17482240
03:06:37 13638 8192000000 10442783568 10919002112 1186574176 12.651 100 47 92 1 1547521568 348692208 12255184
03:08:04 17897 8192000000 10409790288 10864021504 1172826672 12.523 100 36 72 0 1514346160 332269824 9249664
03:09:28 24372 8192000000 10392482448 10861080576 1164756160 12.448 100 31 62 0 1506318400 333352320 8209920
03:11:03 24584 8192000000 10290340720 10763603968 1149339584 12.303 99 32 61 0 1388260912 231224608 7696720
03:12:50 31971 8192000000 10157758048 10637062144 1118275536 12.011 98 21 40 0 1257031632 133774880 4981216
03:14:23 72993 8192000000 10092717504 10538004480 1085730512 11.702 97 16 28 1 1187627616 98808128 3088976
03:16:54 70911 8192000000 10108986288 10577072128 1114707552 11.977 99 15 30 0 1229094112 111018944 3367616
03:19:06 131762 8192000000 10135658112 10608934912 1111634480 11.948 98 24 42 1 1220267312 104161248 4471584
03:22:34 140895 8192000000 10105191952 10549280768 1116125520 11.990 98 40 80 0 1202377328 78273024 7978784
03:25:58 65746 8192000000 10131121024 10573750272 1115358288 11.983 98 50 96 0 1208494208 82507632 10628288
03:28:17 75889 8192000000 10079647504 10590199808 1100614656 11.843 97 18 36 0 1192767760 88492032 3661072
03:30:53 55365 8192000000 10068423872 10563534848 1106672800 11.901 98 13 26 0 1197737408 88648816 2415792
03:32:50 73389 8192000000 10061874752 10536001536 1120910256 12.036 100 11 22 0 1163725232 40573888 2241088
03:35:15 12843 8192000000 10077299536 10532102144 1136241664 12.180 100 31 59 1 1186412096 43478336 6692096
03:36:28 56289 8192000000 10092753232 10577829888 1141550400 12.230 100 11 22 0 1203149360 59340368 2258592
03:38:33 47011 8192000000 10071621920 10585968640 1128757808 12.110 99 11 22 0 1210373824 78989200 2626816
03:41:00 44792 8192000000 10113989680 10597261312 1152669568 12.335 100 22 44 0 1235024944 77536288 4819088
03:43:00 61366 8192000000 10124443328 10560258048 1098537552 11.824 96 16 32 0 1166003856 64218496 3247808
03:45:03 37366 8192000000 10078179664 10520104960 1125699712 12.081 99 21 42 0 1177900992 47903680 4297600
03:46:47 61538 8192000000 10110857296 10566651904 1138305472 12.200 99 31 62 0 1230136496 85266960 6564064
03:48:50 48189 8192000000 10089127680 10523357184 1134436800 12.163 99 38 75 0 1164472752 22451024 7584928
03:50:38 11306 8192000000 10146977504 10578231296 1150140096 12.311 100 54 108 1 1236269776 75422384 10707296
03:51:50 87535 8192000000 10122748512 10605944832 1124703216 12.071 98 31 63 0 1244843904 113685280 6455408
03:55:04 82972 8192000000 10127762784 10595082240 1090047728 11.743 95 47 93 0 1202214944 102502160 9665056
03:57:32 71033 8192000000 10065311600 10547539968 1111743808 11.949 98 8 17 0 1151999312 38511104 1744400
03:59:44 81586 8192000000 10060876832 10539094016 1127745552 12.100 100 17 34 0 1185964096 54222256 3996288
04:02:16 68422 8192000000 10080544144 10563162112 1125687520 12.081 99 7 14 0 1174241008 47196784 1356704
04:04:56 53235 8192000000 10104256096 10550288384 1131346496 12.134 99 27 52 0 1169844032 33005920 5491616
04:06:55 27587 8192000000 10093159456 10529845248 1109046048 11.923 97 33 64 0 1173027216 56989392 6991776
04:08:45 17560 8192000000 10107445040 10552598528 1117038976 11.999 99 46 90 1 1190002784 63484016 9479792
04:10:07 31801 8192000000 10110736864 10572926976 1130918512 12.130 100 47 93 0 1242582576 102061552 9602512
04:11:40 31797 8192000000 10083178800 10530295808 1096985120 11.809 97 38 73 0 1138219248 33457360 7776768
04:13:18 4915 8192000000 10130666864 10551599104 1131181920 12.133 100 65 128 0 1223130816 79181040 12767856
04:14:31 50930 8192000000 10145484160 10619305984 1125111920 12.075 99 40 80 0 1246252672 112935712 8205040
04:16:22 140359 8192000000 10082566208 10588004352 1115137824 11.981 98 15 30 0 1212452048 93926384 3387840
04:20:11 59036 8192000000 10039734464 10531655680 1096396416 11.803 97 7 14 0 1129892816 32025200 1471200
04:22:11 91941 8192000000 10063944048 10525769728 1090057728 11.743 96 22 44 0 1120283840 25953744 4272368
04:24:47 28537 8192000000 10082473632 10527555584 1111131792 11.943 98 27 54 1 1161978960 45526384 5320784
04:26:27 37750 8192000000 10108395904 10544435200 1119724816 12.024 99 43 82 1 1191819840 62918368 9176656
04:28:05 51286 8192000000 10159940064 10613817344 1111970592 11.951 98 30 58 0 1219691760 101042928 6678240
04:29:58 66590 8192000000 10073901840 10579468288 1104684960 11.882 98 18 36 0 1193343744 84816256 3842528
04:32:05 21757 8192000000 10102562512 10552111104 1135990768 12.178 100 37 73 1 1180916448 37836656 7089024
04:33:27 8671 8192000000 10084078192 10521141248 1138419792 12.201 100 37 73 0 1182558320 36415376 7723152
04:34:40 30574 8192000000 10113288160 10545852416 1130478592 12.126 99 40 80 0 1183320320 44930304 7911424
04:36:11 95791 8192000000 8897091888 9091571712 70740464 0.856 7 3 6 0 76214192 4912944 560784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-12-2022 04:39:53 to 10-12-2022 04:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-12-2022 04:40:10 to 10-12-2022 04:40:12.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          212404
MON$OLDEST_ACTIVE               212405
MON$OLDEST_SNAPSHOT             212405
MON$NEXT_TRANSACTION            212405
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-30 07:30:08.6030 GMT
MON$PAGES                       2061024
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2883 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2883 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2883 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2883 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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-oltp40 according to config parameter 'remove_isql_logs'

10.12.22 04:40:13. There are 3 logs in /var/tmp/logs-oltp40 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-12-10 04:40:16 No severe PSQL-related problems occured
10.12.22 04:40:17. End of report.