Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.05.2022 07: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 16365240 kB
MemAvailable 31523824 kB
Buffers 0 kB
Cached 15374688 kB
SwapCached 2556 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.2
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-05-08 07:37:15
DTS_END                         2022-05-08 07:37:15.9140
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/58954
TRN_ID                          212019
ATT_ID                          655
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: 8819.13 22897 1058296
customer order: creation 160.13 480 19216
customer order: refuse 14.07 402 1688
order to supplier: creation 42.58 1352 5110
order to supplier: removal 3.35 174723 402
invoice (draft): creation 43.52 3205 5222
invoice (draft): removal 48.63 1039 5836
invoice accept: apply 40.13 27022 4816
invoice accept: cancel 44.59 1151 5351
customer reserve: creation 3786.33 255 454360
customer reserve: removal 4357.12 106 522854
realization accept: apply 78.24 265 9389
realization accept: cancel 79.29 154 9515
payment from customer: creation 55.13 175 6616
payment from customer: removal 5.51 108 661
payment to supplier: creation 45.58 190 5470
payment to supplier: removal 3.24 104 389
service: total inventory turnovers 3.74 4227 449
service: total monetary turnovers 7.21 379 865
service: aggregate perf. data 0.68 22384 81
service: refresh index statistics 0.05 220221 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-05-2022 07:39:44 to 08-05-2022 07:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7764
TEST_TIME 2 6402
TEST_TIME 3 6376
TEST_TIME 4 5798
TEST_TIME 5 5940
TEST_TIME 6 5728
TEST_TIME 7 5867
TEST_TIME 8 5814
TEST_TIME 9 5970
TEST_TIME 10 6220
TEST_TIME 11 6497
TEST_TIME 12 6786
TEST_TIME 13 6986
TEST_TIME 14 7486
TEST_TIME 15 7709
TEST_TIME 16 7674
TEST_TIME 17 7540
TEST_TIME 18 7522
TEST_TIME 19 7912
TEST_TIME 20 8272
TEST_TIME 21 8301
TEST_TIME 22 8220
TEST_TIME 23 8279
TEST_TIME 24 8338
TEST_TIME 25 8679
TEST_TIME 26 8897
TEST_TIME 27 8844
TEST_TIME 28 9194
TEST_TIME 29 9981
TEST_TIME 30 10246
TEST_TIME 31 10781
TEST_TIME 32 11291
TEST_TIME 33 11232
TEST_TIME 34 10989
TEST_TIME 35 10810
TEST_TIME 36 10577
TEST_TIME 37 10428
TEST_TIME 38 10443
TEST_TIME 39 10437
TEST_TIME 40 10284
TEST_TIME 41 10156
TEST_TIME 42 10034
TEST_TIME 43 9966
TEST_TIME 44 9928
TEST_TIME 45 9829
TEST_TIME 46 9656
TEST_TIME 47 9699
TEST_TIME 48 9630
TEST_TIME 49 9488
TEST_TIME 50 9329
TEST_TIME 51 9263
TEST_TIME 52 9328
TEST_TIME 53 9263
TEST_TIME 54 9264
TEST_TIME 55 9229
TEST_TIME 56 9139
TEST_TIME 57 9190
TEST_TIME 58 9230
TEST_TIME 59 9227
TEST_TIME 60 9200
TEST_TIME 61 9382
TEST_TIME 62 9327
TEST_TIME 63 9335
TEST_TIME 64 9396
TEST_TIME 65 9425
TEST_TIME 66 9400
TEST_TIME 67 9421
TEST_TIME 68 9299
TEST_TIME 69 9298
TEST_TIME 70 9199
TEST_TIME 71 9196
TEST_TIME 72 9194
TEST_TIME 73 9131
TEST_TIME 74 9043
TEST_TIME 75 9083
TEST_TIME 76 9148
TEST_TIME 77 9111
TEST_TIME 78 9106
TEST_TIME 79 9078
TEST_TIME 80 9075
TEST_TIME 81 9117
TEST_TIME 82 9092
TEST_TIME 83 9088
TEST_TIME 84 9115
TEST_TIME 85 9107
TEST_TIME 86 9130
TEST_TIME 87 9095
TEST_TIME 88 9023
TEST_TIME 89 8957
TEST_TIME 90 8992
TEST_TIME 91 8962
TEST_TIME 92 8908
TEST_TIME 93 8906
TEST_TIME 94 8868
TEST_TIME 95 8875
TEST_TIME 96 8867
TEST_TIME 97 8852
TEST_TIME 98 8833
TEST_TIME 99 8816
TEST_TIME 100 8808
TEST_TIME 101 8802
TEST_TIME 102 8787
TEST_TIME 103 8841
TEST_TIME 104 8847
TEST_TIME 105 8826
TEST_TIME 106 8817
TEST_TIME 107 9050
TEST_TIME 108 9176
TEST_TIME 109 9189
TEST_TIME 110 9198
TEST_TIME 111 9139
TEST_TIME 112 9077
TEST_TIME 113 9047
TEST_TIME 114 9017
TEST_TIME 115 8967
TEST_TIME 116 8951
TEST_TIME 117 8973
TEST_TIME 118 8946
TEST_TIME 119 8912
TEST_TIME 120 8845

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-05-2022 07:39:48 to 08-05-2022 07:39:51.

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 1474777 1474777 0 0.00 0 35033 13 0 0
doc_list_biud 1474762 1474762 0 0.00 0 35861 43 0 0
sp_add_invoice_to_stock 4940 4816 124 2.51 32 242440 27023 0 124
sp_cancel_adding_invoice 5351 5351 0 0.00 7 85948 1151 0 0
sp_cancel_client_order 1688 1688 0 0.00 7 11494 403 0 0
sp_cancel_customer_reserve 522854 522854 0 0.00 1 35865 107 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 4301 109 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 4 1257 104 0 0
sp_cancel_supplier_invoice 5849 5836 13 0.22 4 20543 1040 0 13
sp_cancel_supplier_order 402 402 0 0.00 15 1310399 174723 0 0
sp_cancel_write_off 9515 9515 0 0.00 2 9766 154 0 0
sp_client_order 19216 19216 0 0.00 6 36496 481 0 0
sp_customer_reserve 454360 454360 0 0.00 1 140906 255 0 0
sp_fill_shopping_cart 29539 29539 0 0.00 1 15516 85 0 0
sp_fill_shopping_cart_clo_res 454327 454327 0 0.00 0 15033 20 0 0
sp_get_clo_for_invoice 4778 4778 0 0.00 0 19748 295 0 0
sp_kill_cost_storno 15916 15916 0 0.00 0 10140 69 0 0
sp_kill_qstorno_ret_qs2qd 1061461 1061461 0 0.00 0 35842 46 0 0
sp_kill_qty_storno 559811 559811 0 0.00 0 35860 106 0 0
sp_lock_selected_doc 19616 19616 0 0.00 0 8268 7 0 0
sp_make_cost_storno 26251 26251 0 0.00 0 29147 77 0 0
sp_multiply_rows_for_pdistr 26251 26251 0 0.00 0 35029 36 0 0
sp_multiply_rows_for_qdistr 19215 19215 0 0.00 0 27462 187 0 0
sp_pay_from_customer 6616 6616 0 0.00 5 35039 176 0 0
sp_pay_to_supplier 5470 5470 0 0.00 7 22569 191 0 0
sp_payment_common 12086 12086 0 0.00 2 22545 41 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 11463 333 0 0
sp_qd_handle_on_invoice_upd_sts 10127 10127 0 0.00 0 29178 521 0 0
sp_qd_handle_on_reserve_upd_sts 9389 9389 0 0.00 0 30803 61 0 0
sp_reserve_write_off 9389 9389 0 0.00 8 30925 265 0 0
sp_supplier_invoice 5222 5222 0 0.00 10 108167 3206 0 0
sp_supplier_order 5110 5110 0 0.00 9 100654 1353 0 0
srv_aggregate_perf_data 81 81 0 0.00 2247 240697 22384 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 734 126412 36471 0 0
srv_find_qd_qs_mism 1010741 1010741 0 0.00 0 15017 11 0 0
srv_make_invnt_saldo 449 449 0 0.00 85 76004 4227 0 0
srv_make_money_saldo 865 865 0 0.00 1 11946 379 0 0
srv_recalc_idx_stat 6 6 0 0.00 68445 397474 220222 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 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4824 4824 0 0.00 1 28525 874 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 2 7773 436 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 936 25 0 0
t$perf-norm:sp_cancel_customer_reserve 617 617 0 0.00 0 3854 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 2513 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 4014 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 1404 38 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 108407 6865 0 0
t$perf-norm:sp_cancel_write_off 949 949 0 0.00 0 1082 19 0 0
t$perf-norm:sp_client_order 19216 19216 0 0.00 0 35252 40 0 0
t$perf-norm:sp_customer_reserve 906 906 0 0.00 0 7427 26 0 0
t$perf-norm:sp_pay_from_customer 6616 6616 0 0.00 0 26867 25 0 0
t$perf-norm:sp_pay_to_supplier 5470 5470 0 0.00 0 35568 29 0 0
t$perf-norm:sp_reserve_write_off 9389 9389 0 0.00 0 20251 28 0 0
t$perf-norm:sp_supplier_invoice 5222 5222 0 0.00 0 3929 27 0 0
t$perf-norm:sp_supplier_order 5110 5110 0 0.00 0 5404 31 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 259 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 455 12 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 577 14 0 0
t$perf-norm:srv_make_money_saldo 865 865 0 0.00 0 4157 12 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 177 84 0 0
v_add_invoice_to_stock 9796 9548 248 2.53 0 10392 26 0 248
v_all_customers 35316 35316 0 0.00 0 126 3 0 0
v_all_suppliers 28622 28622 0 0.00 0 61 4 0 0
v_all_wares 38429 38429 0 0.00 0 92 4 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1407 14 0 0
v_cancel_client_order 3376 3376 0 0.00 0 3438 9 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 1025 12 0 0
v_cancel_customer_reserve 1234 1234 0 0.00 1 12828 29 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 1197 13 0 0
v_cancel_supplier_invoice 752 726 26 3.45 0 406 21 0 26
v_cancel_supplier_order 804 804 0 0.00 0 1985 16 0 0
v_cancel_write_off 1898 1898 0 0.00 0 318 19 0 0
v_max_id_clo_ord 5108 5108 0 0.00 0 2995 6 0 0
v_max_id_clo_res 906 906 0 0.00 0 2169 14 0 0
v_max_id_ord_sup 5215 5215 0 0.00 0 7191 9 0 0
v_max_non_paid_invoice 1482 1482 0 0.00 0 123 7 0 0
v_max_non_paid_realizn 3411 3411 0 0.00 0 1929 7 0 0
v_min_id_clo_ord 5108 5108 0 0.00 0 1250 7 0 0
v_min_id_clo_res 906 906 0 0.00 0 2693 10 0 0
v_min_id_ord_sup 5215 5215 0 0.00 0 6588 12 0 0
v_min_non_paid_invoice 5470 5470 0 0.00 0 22526 17 0 0
v_min_non_paid_realizn 6616 6616 0 0.00 0 7909 14 0 0
v_our_firm 9522 9522 0 0.00 0 72 4 0 0
v_reserve_write_off 18778 18778 0 0.00 0 10885 11 0 0
x_lock_depdocs_on_canc_invoice 10824 10824 0 0.00 0 54005 134 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 4613 366 0 0
x_make_qstorno_1000_1200 5110 5110 0 0.00 3 100434 1182 0 0
x_make_qstorno_1200_2000 5222 5222 0 0.00 5 104701 2971 0 0
x_make_qstorno_2100_3300 454360 454360 0 0.00 0 140888 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-05-2022 07:39:52 to 08-05-2022 07:39:56.

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
05:07:19 966 8192000000 8930407344 8988045312 15559264 0.189 5 3 6 0 16021760 168576 293920
05:08:21 27476 8192000000 9728481040 10029584384 800196304 8.898 100 88 176 1 829366752 7737296 21433152
05:09:53 26041 8192000000 9760714672 10071441408 829580320 9.195 100 82 164 1 862298672 13316848 19401504
05:11:26 14285 8192000000 9818793136 10137673728 866229072 9.562 100 86 172 1 913728304 27827456 19671776
05:12:43 8035 8192000000 9873490576 10207674368 905817648 9.956 100 81 161 0 980037104 56613552 17605904
05:13:55 5813 8192000000 9911161584 10254401536 943453872 10.327 100 85 161 1 1015449040 55562960 16432208
05:15:05 2190 8192000000 9954775184 10301882368 978974384 10.674 100 91 180 0 1063390016 65198976 19216656
05:16:10 1547 8192000000 9978783600 10336649216 1000165392 10.880 100 86 167 1 1084094720 66851088 17078240
05:17:14 1519 8192000000 9998602720 10364760064 1025322464 11.123 100 93 174 0 1113032624 69993552 17716608
05:18:16 1131 8192000000 10034873328 10410577920 1042278400 11.287 100 92 179 0 1147595328 86317984 18998944
05:19:19 688 8192000000 10034780240 10409984000 1050978848 11.370 100 94 183 1 1146941616 77304800 18657968
05:20:20 829 8192000000 10031689392 10407661568 1057334816 11.431 100 92 179 0 1142308944 67296400 17677728
05:21:22 1144 8192000000 10033969104 10416885760 1061405952 11.470 100 84 164 2 1153299440 74742592 17150896
05:22:24 1026 8192000000 10034137360 10418491392 1058166512 11.439 100 90 177 1 1146286640 69750416 18369712
05:23:25 1489 8192000000 10080127408 10466054144 1062420320 11.480 100 95 184 1 1187651808 106494880 18736608
05:24:28 1766 8192000000 10047474400 10435563520 1066625344 11.520 100 85 156 0 1161114640 79142112 15347184
05:25:30 1110 8192000000 10056440976 10443235328 1070390864 11.556 100 92 179 2 1166435760 77998464 18046432
05:26:32 1190 8192000000 10068637040 10457628672 1073701888 11.587 100 96 183 0 1178919312 87039136 18178288
05:27:35 1141 8192000000 10065880816 10451820544 1073099088 11.582 100 95 179 0 1172079760 79236144 19744528
05:28:37 1649 8192000000 10055471392 10445479936 1078812608 11.636 100 91 172 2 1164604528 68371552 17420368
05:29:39 1734 8192000000 10026823664 10416828416 1066080928 11.515 100 71 135 1 1136601008 57381200 13138880
05:30:42 1026 8192000000 10011015008 10388221952 1043409200 11.297 99 93 175 0 1121760192 60356160 17994832
05:31:44 2041 8192000000 9990249952 10375028736 1052231232 11.382 100 71 133 0 1112759888 46412416 14116240
05:32:46 2036 8192000000 10014693424 10394873856 1045509600 11.318 99 71 141 2 1131257632 72114304 13633728
05:33:49 1131 8192000000 10021118432 10391953408 1035546832 11.222 99 97 184 0 1137999360 83401968 19050560
05:34:51 1114 8192000000 10020408192 10392899584 1045447936 11.317 100 95 183 1 1140323952 76039536 18836480
05:35:53 1408 8192000000 10008490448 10387505152 1049889952 11.360 99 89 168 1 1131105968 64202688 17013328
05:36:55 1940 8192000000 10015352896 10398928896 1049272448 11.354 100 80 145 1 1141528032 76678192 15577392
05:38:04 16666 8192000000 9964911824 10343886848 1034132912 11.208 98 62 118 1 1078431248 32236944 12061392
05:39:29 2527 8192000000 10048873664 10431627264 1081319072 11.660 100 86 166 0 1178537936 80523952 16694912
05:40:40 14858 8192000000 10081961600 10463535104 1092313072 11.765 100 79 151 0 1193954032 87184736 14456224
05:41:58 7340 8192000000 10140183920 10531414016 1109605424 11.929 100 72 138 0 1245608976 121635648 14367904
05:43:13 10174 8192000000 10160794112 10561183744 1116252608 11.992 100 71 142 0 1276742096 144742720 15746768
05:44:30 6697 8192000000 10151394448 10566447104 1118318128 12.011 100 49 90 0 1267105232 139114288 9672816
05:45:47 1104 8192000000 10205265712 10599096320 1139850240 12.214 100 91 177 3 1322506080 162184176 20471664
05:46:53 2183 8192000000 10265518416 10661097472 1145742912 12.270 100 81 160 0 1378169408 214233616 18192880
05:48:01 724 8192000000 10311119200 10712723456 1169383680 12.491 100 98 190 1 1424548848 233893920 21271248
05:49:05 1032 8192000000 10337038080 10740649984 1174549232 12.539 100 93 185 1 1446263312 250086592 21627488
05:50:08 1003 8192000000 10360541440 10770833408 1182053856 12.609 100 96 188 2 1470632304 267077104 21501344
05:51:11 1274 8192000000 10385883152 10823274496 1183701456 12.625 100 97 192 0 1494624400 289333536 21589408
05:52:14 942 8192000000 10395792128 10810126336 1186630544 12.652 100 96 192 0 1504002208 296301440 21070224
05:53:19 1610 8192000000 10402526016 10802257920 1172641856 12.522 99 92 178 1 1511526960 317805696 21079408
05:54:24 789 8192000000 10453639440 10861199360 1187476896 12.660 100 96 180 1 1559483248 351080176 20926176
05:55:28 1008 8192000000 10464307744 10899718144 1182708112 12.615 100 99 195 0 1573330320 366849552 23772656
05:56:32 990 8192000000 10462340864 10907852800 1173860000 12.533 100 96 186 0 1564617216 367353936 23403280
05:57:36 839 8192000000 10479674816 10902196224 1181753120 12.607 100 96 190 1 1586171648 382013104 22405424
05:58:39 1765 8192000000 10514599712 10929717248 1178990816 12.581 100 76 152 0 1619231744 421678768 18562160
05:59:44 2182 8192000000 10554746496 10976165888 1182934640 12.618 100 80 159 1 1659385424 457694816 18755968
06:00:51 3478 8192000000 10574196128 11021561856 1183524128 12.623 100 67 131 0 1680577632 480033776 17019728
06:01:59 3983 8192000000 10589052896 11041198080 1182606592 12.614 100 62 124 0 1696042480 497875232 15560656
06:03:29 21290 8192000000 10535503168 10990702592 1174552832 12.539 100 41 82 1 1634617056 449084384 10979840
06:05:08 16555 8192000000 10500268992 10996129792 1148760560 12.298 99 33 66 1 1624927344 466999840 9166944
06:06:25 26041 8192000000 10360240368 10825441280 1143897664 12.252 99 33 67 0 1476665520 323882528 8885328
06:07:52 20835 8192000000 10244406368 10757902336 1133117088 12.151 100 22 45 0 1379257888 240587296 5553504
06:09:16 79341 8192000000 10045185840 10508972032 1085151888 11.697 95 15 30 0 1151205888 61997008 4056992
06:11:36 126411 8192000000 10100830368 10535563264 1115209200 11.982 98 36 71 0 1195037424 71183600 8644624
06:14:44 29607 8192000000 10126564336 10566066176 1124996464 12.074 99 43 79 0 1208720864 75422368 8302032
06:16:18 58467 8192000000 10073244768 10565947392 1123633216 12.061 99 19 38 0 1170271696 42179760 4458720
06:18:29 55243 8192000000 10113918992 10570833920 1142737856 12.241 100 26 53 1 1216722304 67796000 6188448
06:20:26 28617 8192000000 10141028016 10614378496 1151859152 12.327 100 12 24 0 1238449520 83945360 2645008
06:21:59 118767 8192000000 10105639504 10574708736 1110262976 11.935 97 15 28 2 1191281552 77159184 3859392
06:25:17 36369 8192000000 10062221584 10525593600 1132576288 12.146 100 28 56 1 1178993424 40814512 5602624
06:26:58 99965 8192000000 10151948624 10605297664 1143940048 12.253 100 27 54 0 1233392736 83958096 5494592
06:29:40 47947 8192000000 10102018976 10570272768 1129752800 12.119 99 14 28 2 1203073920 70465568 2855552
06:31:36 59292 8192000000 10104317504 10581331968 1121482400 12.041 98 14 28 0 1186324272 61732352 3109520
06:33:47 32447 8192000000 10143738768 10600591360 1124993520 12.074 99 32 64 0 1222234224 90290896 6949808
06:35:20 19022 8192000000 10117221184 10572107776 1121543664 12.042 99 41 82 0 1220620848 89682096 9395088
06:36:42 32091 8192000000 10100849872 10543878144 1087481776 11.719 96 34 68 0 1173764000 79673808 6608416
06:38:15 63582 8192000000 10070274496 10550190080 1116236192 11.991 99 30 58 2 1192530768 70618496 5676080
06:40:22 36382 8192000000 10137567744 10590056448 1133170464 12.151 100 38 76 1 1232370000 91865632 7333904
06:42:01 73613 8192000000 10108009296 10557042688 1102574512 11.862 97 24 48 0 1162591088 54953856 5062720
06:44:15 82570 8192000000 10075785984 10509893632 1065566656 11.510 94 37 74 1 1125853680 52937792 7349232
06:47:04 42909 8192000000 10109003152 10542440448 1120058240 12.028 99 54 107 3 1194410320 63505744 10846336
06:48:57 54293 8192000000 10062046400 10556981248 1107456672 11.908 98 17 34 0 1177065216 66280816 3327728
06:50:51 59337 8192000000 10111091280 10561449984 1127392512 12.097 100 10 20 0 1193571296 63780416 2398368
06:53:03 86829 8192000000 10067877248 10586226688 1108833856 11.921 98 14 26 2 1196224416 84446592 2943968
06:55:34 34205 8192000000 10061205120 10497781760 1105228048 11.887 98 33 66 1 1153047488 41039296 6780144
06:57:12 47456 8192000000 10085519248 10529742848 1123710160 12.062 100 21 42 1 1210738304 82550768 4477376
06:59:04 20262 8192000000 10081789120 10517737472 1120983040 12.036 100 50 100 2 1167793184 35797680 11012464
07:00:28 29874 8192000000 10088218528 10527010816 1108907152 11.922 99 45 90 0 1202027264 83760832 9359280
07:01:58 74188 8192000000 10060935840 10538352640 1062131296 11.477 95 29 58 0 1141331664 73199568 6000800
07:05:23 89407 8192000000 10115482704 10551173120 1122164976 12.047 100 57 111 0 1236035968 102208432 11662560
07:07:54 29827 8192000000 10040210592 10488164352 1112213920 11.953 99 17 33 0 1145538848 30254304 3070624
07:09:33 56224 8192000000 10082053344 10550255616 1127466128 12.097 99 8 16 1 1170208016 41300672 1441216
07:12:25 58301 8192000000 10094844368 10554585088 1110353968 11.936 97 23 46 0 1158925600 43907600 4664032
07:14:40 22895 8192000000 10138913888 10584539136 1163599248 12.437 100 33 63 2 1254604656 83797472 7207936
07:16:11 23684 8192000000 10190498352 10627592192 1149656176 12.306 99 37 75 1 1273470512 115859712 7954624
07:17:39 34880 8192000000 10261498352 10698563584 1156859936 12.374 98 45 90 0 1326585248 159030480 10694832
07:19:15 12711 8192000000 10280509696 10706288640 1145760240 12.270 98 55 110 0 1353704656 194881168 13063248
07:20:32 44807 8192000000 10292379760 10730766336 1148419872 12.295 98 39 78 1 1357746768 200645232 8681664
07:22:45 34881 8192000000 10336450256 10774691840 1162160608 12.423 100 34 68 1 1419513328 249223984 8128736
07:24:37 27804 8192000000 10177429168 10746757120 1123939504 12.064 99 18 36 1 1362079040 233620832 4518704
07:26:16 108912 8192000000 10065036688 10533990400 1071176864 11.563 94 25 50 2 1145014112 69124224 4713024
07:29:05 75152 8192000000 10069399840 10507821056 1109999120 11.932 99 24 48 0 1150971552 36608928 4363504
07:31:36 94029 8192000000 10077825264 10561818624 1121524336 12.041 99 8 16 2 1184205440 61135680 1545424
07:34:24 43478 8192000000 10051991680 10514636800 1085813120 11.703 96 18 36 0 1121157936 31944816 3400000
07:36:08 62218 8192000000 10038525152 10508128256 1097133152 11.810 97 16 32 0 1131718352 30738640 3846560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-05-2022 07:39:58 to 08-05-2022 07:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 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 08-05-2022 07:40:15 to 08-05-2022 07:40:17.

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          212018
MON$OLDEST_ACTIVE               213177
MON$OLDEST_SNAPSHOT             213177
MON$NEXT_TRANSACTION            213177
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                       2062112
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
:94: 
:95: mscs-test-161.budzdorov.ru    Sun May  8 07:37:51 2022
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
--- end of diff output ---

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

08.05.22 07:40:18. 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-05-08 07:40:20 No severe PSQL-related problems occured
08.05.22 07:40:22. End of report.