Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2022 16:40
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 15044276 kB
MemAvailable 30133420 kB
Buffers 0 kB
Cached 16692612 kB
SwapCached 3460 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.11
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-12-27 16:38:34
DTS_END                         2022-12-27 16:38:34.1770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49892
TRN_ID                          245771
ATT_ID                          691
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: 9197.19 22051 1103663
customer order: creation 177.37 363 21284
customer order: refuse 15.48 315 1858
order to supplier: creation 48.78 1277 5853
order to supplier: removal 3.27 108268 392
invoice (draft): creation 48.28 3186 5794
invoice (draft): removal 49.94 868 5993
invoice accept: apply 43.63 16639 5235
invoice accept: cancel 45.31 756 5437
customer reserve: creation 4027.88 165 483346
customer reserve: removal 4433.65 63 532038
realization accept: apply 86.36 168 10363
realization accept: cancel 83.05 110 9966
payment from customer: creation 61.79 106 7415
payment from customer: removal 6.11 83 733
payment to supplier: creation 49.93 125 5991
payment to supplier: removal 3.91 71 469
service: total inventory turnovers 4.72 1198 566
service: total monetary turnovers 6.90 56 828
service: aggregate perf. data 0.82 23986 98
service: refresh index statistics 0.03 283209 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-12-2022 16:40:54 to 27-12-2022 16:40:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2351
TEST_TIME 2 3351
TEST_TIME 3 4691
TEST_TIME 4 5336
TEST_TIME 5 5348
TEST_TIME 6 5238
TEST_TIME 7 5223
TEST_TIME 8 5108
TEST_TIME 9 5037
TEST_TIME 10 5274
TEST_TIME 11 5542
TEST_TIME 12 5507
TEST_TIME 13 6312
TEST_TIME 14 6698
TEST_TIME 15 6844
TEST_TIME 16 7362
TEST_TIME 17 7288
TEST_TIME 18 7629
TEST_TIME 19 7623
TEST_TIME 20 7940
TEST_TIME 21 8110
TEST_TIME 22 7995
TEST_TIME 23 8826
TEST_TIME 24 9127
TEST_TIME 25 9345
TEST_TIME 26 9500
TEST_TIME 27 9950
TEST_TIME 28 10698
TEST_TIME 29 11300
TEST_TIME 30 11343
TEST_TIME 31 11254
TEST_TIME 32 11214
TEST_TIME 33 10952
TEST_TIME 34 10732
TEST_TIME 35 10684
TEST_TIME 36 10577
TEST_TIME 37 10645
TEST_TIME 38 10437
TEST_TIME 39 10298
TEST_TIME 40 10164
TEST_TIME 41 10096
TEST_TIME 42 10107
TEST_TIME 43 10059
TEST_TIME 44 9951
TEST_TIME 45 9868
TEST_TIME 46 9781
TEST_TIME 47 9641
TEST_TIME 48 9574
TEST_TIME 49 9479
TEST_TIME 50 9450
TEST_TIME 51 9433
TEST_TIME 52 9377
TEST_TIME 53 9353
TEST_TIME 54 9293
TEST_TIME 55 9240
TEST_TIME 56 9237
TEST_TIME 57 9199
TEST_TIME 58 9172
TEST_TIME 59 9207
TEST_TIME 60 9367
TEST_TIME 61 9347
TEST_TIME 62 9411
TEST_TIME 63 9405
TEST_TIME 64 9470
TEST_TIME 65 9472
TEST_TIME 66 9409
TEST_TIME 67 9482
TEST_TIME 68 9473
TEST_TIME 69 9503
TEST_TIME 70 9579
TEST_TIME 71 9527
TEST_TIME 72 9516
TEST_TIME 73 9459
TEST_TIME 74 9375
TEST_TIME 75 9359
TEST_TIME 76 9345
TEST_TIME 77 9320
TEST_TIME 78 9282
TEST_TIME 79 9248
TEST_TIME 80 9258
TEST_TIME 81 9211
TEST_TIME 82 9184
TEST_TIME 83 9281
TEST_TIME 84 9274
TEST_TIME 85 9266
TEST_TIME 86 9326
TEST_TIME 87 9314
TEST_TIME 88 9391
TEST_TIME 89 9386
TEST_TIME 90 9394
TEST_TIME 91 9434
TEST_TIME 92 9415
TEST_TIME 93 9449
TEST_TIME 94 9394
TEST_TIME 95 9401
TEST_TIME 96 9379
TEST_TIME 97 9349
TEST_TIME 98 9317
TEST_TIME 99 9276
TEST_TIME 100 9233
TEST_TIME 101 9224
TEST_TIME 102 9221
TEST_TIME 103 9271
TEST_TIME 104 9246
TEST_TIME 105 9261
TEST_TIME 106 9303
TEST_TIME 107 9304
TEST_TIME 108 9299
TEST_TIME 109 9283
TEST_TIME 110 9316
TEST_TIME 111 9374
TEST_TIME 112 9354
TEST_TIME 113 9303
TEST_TIME 114 9390
TEST_TIME 115 9369
TEST_TIME 116 9316
TEST_TIME 117 9298
TEST_TIME 118 9283
TEST_TIME 119 9263
TEST_TIME 120 9199

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-12-2022 16:40:58 to 27-12-2022 16:41:01.

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 1549624 1549624 0 0.00 0 17716 6 0 0
doc_list_biud 1549624 1549624 0 0.00 0 33094 27 0 0
sp_add_invoice_to_stock 5362 5235 127 2.36 25 241162 16639 0 127
sp_cancel_adding_invoice 5437 5437 0 0.00 4 50895 757 0 0
sp_cancel_client_order 1858 1858 0 0.00 4 10040 316 0 0
sp_cancel_customer_reserve 532038 532038 0 0.00 0 33095 63 0 0
sp_cancel_pay_from_customer 733 733 0 0.00 2 4970 83 0 0
sp_cancel_pay_to_supplier 469 469 0 0.00 3 4169 71 0 0
sp_cancel_supplier_invoice 6007 5993 14 0.23 1 19297 869 0 14
sp_cancel_supplier_order 392 392 0 0.00 6 1035996 108269 0 0
sp_cancel_write_off 9966 9966 0 0.00 1 12356 110 0 0
sp_client_order 21284 21284 0 0.00 3 25561 364 0 0
sp_customer_reserve 483346 483346 0 0.00 0 46277 165 0 0
sp_fill_shopping_cart 32913 32913 0 0.00 1 38385 45 0 0
sp_fill_shopping_cart_clo_res 483328 483328 0 0.00 0 22892 13 0 0
sp_get_clo_for_invoice 5217 5217 0 0.00 0 25122 350 0 0
sp_kill_cost_storno 16605 16605 0 0.00 0 12353 54 0 0
sp_kill_qstorno_ret_qs2qd 1080427 1080427 0 0.00 0 33091 34 0 0
sp_kill_qty_storno 571264 571264 0 0.00 0 33093 73 0 0
sp_lock_selected_doc 21617 21617 0 0.00 0 7650 6 0 0
sp_make_cost_storno 28986 28986 0 0.00 0 17695 49 0 0
sp_multiply_rows_for_pdistr 28986 28986 0 0.00 0 7782 27 0 0
sp_multiply_rows_for_qdistr 21284 21284 0 0.00 0 24706 152 0 0
sp_pay_from_customer 7415 7415 0 0.00 4 14702 107 0 0
sp_pay_to_supplier 5991 5991 0 0.00 4 14718 125 0 0
sp_payment_common 13406 13406 0 0.00 1 11576 26 0 0
sp_qd_handle_on_cancel_clo 1858 1858 0 0.00 0 10033 285 0 0
sp_qd_handle_on_invoice_upd_sts 10654 10654 0 0.00 0 30342 304 0 0
sp_qd_handle_on_reserve_upd_sts 10363 10363 0 0.00 0 16031 55 0 0
sp_reserve_write_off 10363 10363 0 0.00 5 21566 169 0 0
sp_supplier_invoice 5794 5794 0 0.00 5 81852 3186 0 0
sp_supplier_order 5853 5853 0 0.00 4 48383 1277 0 0
srv_aggregate_perf_data 98 98 0 0.00 2848 453400 23987 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1685 109766 45269 0 0
srv_find_qd_qs_mism 1053158 1053158 0 0.00 0 16513 6 0 0
srv_make_invnt_saldo 566 566 0 0.00 29 106271 1199 0 0
srv_make_money_saldo 828 828 0 0.00 0 23067 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 155067 511633 283210 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5235 5235 0 0.00 0 16167 181 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 8667 103 0 0
t$perf-norm:sp_cancel_client_order 1858 1858 0 0.00 0 4876 15 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 2713 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 733 733 0 0.00 0 2724 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 469 469 0 0.00 0 1230 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 3664 26 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 28984 1613 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 8243 12 0 0
t$perf-norm:sp_client_order 21284 21284 0 0.00 0 15201 16 0 0
t$perf-norm:sp_customer_reserve 1069 1069 0 0.00 0 2399 6 0 0
t$perf-norm:sp_pay_from_customer 7415 7415 0 0.00 0 11026 10 0 0
t$perf-norm:sp_pay_to_supplier 5991 5991 0 0.00 0 6372 10 0 0
t$perf-norm:sp_reserve_write_off 10363 10363 0 0.00 0 15911 15 0 0
t$perf-norm:sp_supplier_invoice 5794 5794 0 0.00 0 9265 18 0 0
t$perf-norm:sp_supplier_order 5853 5853 0 0.00 0 11087 15 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 99 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 248 7 0 0
t$perf-norm:srv_make_invnt_saldo 566 566 0 0.00 0 899 21 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 4355 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 23 7 0 0
v_add_invoice_to_stock 10688 10434 254 2.37 0 5516 17 0 254
v_all_customers 39084 39084 0 0.00 0 17 1 0 0
v_all_suppliers 31554 31554 0 0.00 0 21 1 0 0
v_all_wares 42568 42568 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 1742 10 0 0
v_cancel_client_order 3716 3716 0 0.00 0 4828 6 0 0
v_cancel_customer_prepayment 1466 1466 0 0.00 0 4870 19 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 2837 9 0 0
v_cancel_payment_to_supplier 938 938 0 0.00 0 4150 9 0 0
v_cancel_supplier_invoice 902 874 28 3.10 0 2013 18 0 28
v_cancel_supplier_order 784 784 0 0.00 0 1895 10 0 0
v_cancel_write_off 2092 2092 0 0.00 0 1625 14 0 0
v_max_id_clo_ord 5850 5850 0 0.00 0 18945 6 0 0
v_max_id_clo_res 1069 1069 0 0.00 0 1425 7 0 0
v_max_id_ord_sup 5779 5779 0 0.00 0 7771 8 0 0
v_max_non_paid_invoice 1843 1843 0 0.00 0 5549 5 0 0
v_max_non_paid_realizn 3781 3781 0 0.00 0 723 3 0 0
v_min_id_clo_ord 5850 5850 0 0.00 0 8033 9 0 0
v_min_id_clo_res 1069 1069 0 0.00 0 4544 10 0 0
v_min_id_ord_sup 5779 5779 0 0.00 0 7725 12 0 0
v_min_non_paid_invoice 5991 5991 0 0.00 0 11571 21 0 0
v_min_non_paid_realizn 7415 7415 0 0.00 0 6680 19 0 0
v_our_firm 10752 10752 0 0.00 0 19 1 0 0
v_reserve_write_off 20726 20726 0 0.00 0 5012 9 0 0
x_lock_depdocs_on_canc_invoice 10993 10993 0 0.00 0 16732 110 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 10761 391 0 0
x_make_qstorno_1000_1200 5853 5853 0 0.00 1 48372 1191 0 0
x_make_qstorno_1200_2000 5794 5794 0 0.00 2 81837 3018 0 0
x_make_qstorno_2100_3300 483346 483346 0 0.00 0 46246 149 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 27-12-2022 16:41:02 to 27-12-2022 16:41:07.

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:38 2 8192000000 8797686464 8808529920 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:38 10224 8192000000 9889051264 10196852736 980534736 10.689 100 86 170 1 1008168864 5557392 22076736
14:10:51 25330 8192000000 9952692976 10275471360 1031591712 11.184 100 87 174 0 1061986880 8308672 22086496
14:12:22 9952 8192000000 9980474208 10312609792 1064983408 11.504 100 83 166 0 1095477520 9913552 20580560
14:13:46 15502 8192000000 10028246576 10375761920 1112881072 11.960 100 84 168 1 1144167648 10549120 20737456
14:15:07 8241 8192000000 10067431568 10424905728 1157501248 12.380 100 78 156 0 1186832048 10972640 18358160
14:16:17 2904 8192000000 10126109328 10494947328 1216436704 12.929 100 76 148 0 1245251040 12496384 16317952
14:17:22 2122 8192000000 10167140912 10538889216 1245242240 13.194 100 88 171 0 1277137712 13129392 18766080
14:18:26 1858 8192000000 10186987264 10565500928 1279179584 13.506 100 89 178 0 1312595728 13462048 19954096
14:19:29 2967 8192000000 10199533520 10589470720 1295466928 13.654 100 76 151 1 1324315776 12215552 16633296
14:20:34 950 8192000000 10217629392 10605903872 1304875488 13.740 100 72 144 0 1333312016 12470768 15965760
14:21:36 6097 8192000000 10197972064 10608365568 1311843520 13.803 100 35 70 0 1326970448 7411888 7715040
14:22:44 5468 8192000000 10204352480 10618261504 1318113584 13.860 100 40 80 0 1333006064 5680192 9212288
14:23:51 55766 8192000000 10215398720 10633826304 1323937168 13.912 100 32 62 1 1336977856 5835536 7205152
14:25:47 10219 8192000000 10225510000 10641547264 1330453856 13.971 100 25 46 0 1341836320 5806176 5576288
14:26:58 7100 8192000000 10214291952 10636832768 1326614288 13.937 100 29 60 1 1340167200 7071968 6480944
14:28:07 17555 8192000000 10207940320 10618142720 1263235728 13.360 96 21 41 0 1272272432 4633984 4402720
14:29:28 9776 8192000000 10195154112 10616532992 1312353968 13.807 100 27 54 0 1324889568 6547344 5988256
14:30:38 6709 8192000000 10141285328 10527657984 1207188672 12.843 96 42 83 0 1222615392 6585200 8841520
14:31:48 8372 8192000000 10117463104 10507546624 1182206864 12.611 95 26 52 0 1193832000 5434400 6190736
14:32:57 45999 8192000000 10156367024 10562342912 1257344496 13.306 99 29 56 0 1270270976 5234144 7692336
14:34:52 5989 8192000000 10178644688 10583527424 1284924208 13.558 100 40 80 0 1302262432 8202608 9135616
14:36:00 6975 8192000000 10192612960 10609483776 1303230400 13.725 100 39 78 0 1319562672 6900304 9431968
14:37:08 9735 8192000000 10206255152 10623549440 1314882624 13.830 100 14 26 0 1323147344 5505056 2759664
14:38:19 65691 8192000000 10241235664 10650664960 1331871472 13.984 100 43 83 0 1346696208 5892480 8932256
14:40:37 20532 8192000000 10272631408 10687623168 1362287360 14.258 100 59 114 0 1390851648 16024656 12539632
14:42:00 19524 8192000000 10290672784 10711265280 1376998432 14.390 100 49 99 0 1405001424 17195888 10807104
14:43:25 6744 8192000000 10308313360 10730852352 1393131152 14.534 100 44 88 0 1420706176 16974496 10600528
14:44:35 20906 8192000000 10312797216 10737676288 1398695792 14.583 100 54 104 0 1432239008 21493200 12050016
14:45:59 15039 8192000000 10326163936 10748243968 1406477136 14.653 100 54 101 0 1440039056 22009232 11552688
14:47:22 6386 8192000000 10342890736 10768543744 1415692032 14.734 100 56 112 1 1455693360 26993872 13007456
14:48:32 6476 8192000000 10354382000 10778177536 1417759712 14.753 100 49 98 2 1462214368 31833472 12621184
14:49:43 3355 8192000000 10366089536 10789531648 1425125296 14.818 100 62 123 0 1477057392 36514176 15417920
14:51:01 3265 8192000000 10373944128 10803302400 1430343744 14.864 100 45 90 0 1486468240 44244352 11880144
14:52:17 2125 8192000000 10373853744 10785636352 1420291376 14.775 100 86 168 1 1475645952 35272640 20081936
14:53:23 1679 8192000000 10349299072 10763558912 1397502928 14.573 100 76 148 1 1457456528 41010032 18943568
14:54:31 3806 8192000000 10345288448 10760716288 1392060864 14.524 100 77 153 0 1455988288 44696272 19231152
14:55:40 3889 8192000000 10361510320 10772795392 1400028528 14.595 100 70 139 0 1462201360 44202368 17970464
14:56:55 10462 8192000000 10367558288 10782437376 1398675008 14.583 100 60 118 3 1469262000 55606160 14980832
14:58:12 9850 8192000000 10344314352 10757963776 1371044064 14.336 99 44 88 0 1441368848 57883808 12440976
14:59:25 14224 8192000000 10365885600 10789687296 1405099776 14.640 100 43 86 0 1476421632 58908240 12413616
15:00:44 10641 8192000000 10370652208 10789519360 1382662480 14.440 99 43 86 0 1462335920 67199920 12473520
15:02:01 14532 8192000000 10363479120 10789892096 1376666688 14.387 98 40 80 0 1449663376 61782352 11214336
15:03:18 14323 8192000000 10353714912 10787237888 1382738544 14.441 100 37 74 0 1456274240 63143232 10392464
15:04:41 23523 8192000000 10333354848 10773127168 1352515952 14.170 99 29 58 0 1426009424 64789040 8704432
15:06:27 25289 8192000000 10313290704 10761347072 1353919824 14.183 100 15 28 0 1423478784 65204720 4354240
15:08:23 39264 8192000000 10262208544 10705817600 1340527696 14.062 98 16 33 1 1355643152 11648368 3467088
15:10:08 85623 8192000000 10283375664 10745655296 1377228592 14.392 100 15 30 1 1392512816 12030720 3253504
15:12:53 86386 8192000000 10289944992 10746564608 1359856144 14.236 98 14 24 1 1376805600 14146864 2802592
15:15:20 83312 8192000000 10294233024 10743873536 1376196032 14.383 99 8 15 1 1387499776 9892256 1411488
15:17:50 103187 8192000000 10308795472 10756046848 1371404048 14.340 98 25 50 0 1387421120 10131424 5885648
15:20:41 39372 8192000000 10300981360 10759880704 1395490144 14.555 100 34 68 0 1415621696 12522928 7608624
15:22:24 55654 8192000000 10286619440 10748243968 1367069424 14.301 98 13 26 0 1376133968 6556032 2508512
15:24:29 52700 8192000000 10292878672 10756329472 1394632192 14.547 100 11 22 0 1408425808 11085216 2708400
15:26:22 70293 8192000000 10294399328 10758668288 1383485504 14.448 99 18 36 0 1398772976 10728976 4558496
15:28:34 101766 8192000000 10306244256 10757754880 1372729072 14.351 98 20 40 0 1386909056 9690464 4489520
15:31:17 78977 8192000000 10295990896 10767605760 1380399440 14.420 98 18 37 0 1393866560 8835504 4631616
15:33:37 106148 8192000000 10282503696 10762919936 1362321568 14.258 97 13 25 0 1376533872 11151600 3060704
15:36:23 29021 8192000000 10304365200 10751242240 1354170128 14.185 97 32 64 0 1369059472 7755520 7133824
15:37:53 64558 8192000000 10305245088 10769559552 1386107248 14.471 99 18 37 0 1410939104 20722656 4109200
15:40:04 78907 8192000000 10296627616 10761048064 1366800160 14.298 98 13 27 1 1380479072 11164352 2514560
15:42:24 54504 8192000000 10295749632 10759041024 1353190048 14.176 97 23 46 0 1368398912 10118720 5090144
15:44:19 21240 8192000000 10302783520 10740781056 1358549696 14.224 98 53 107 0 1386294752 16064880 11680176
15:45:43 62156 8192000000 10282496720 10723876864 1343771088 14.091 98 22 43 0 1359569552 11010400 4788064
15:47:59 58028 8192000000 10254937808 10706894848 1329725904 13.965 98 11 21 1 1347082368 14600800 2755664
15:50:39 98451 8192000000 10243748704 10691772416 1315468736 13.836 97 24 48 0 1330725216 10041808 5214672
15:53:43 47896 8192000000 10236899888 10688364544 1313744384 13.820 97 14 28 0 1327031776 9999904 3287488
15:55:39 41247 8192000000 10243930384 10691354624 1302427296 13.717 96 22 43 0 1316269136 9120896 4720944
15:57:47 69004 8192000000 10233136160 10691227648 1308949584 13.777 97 7 14 0 1319717696 9410592 1357520
15:59:56 30475 8192000000 10264553984 10706157568 1338873616 14.047 98 24 48 0 1354532912 10118096 5541200
16:01:30 53064 8192000000 10276729488 10721361920 1367844096 14.308 100 12 24 0 1378678496 8416704 2417696
16:03:27 35244 8192000000 10264403840 10709708800 1357311760 14.213 100 35 68 1 1378197008 13034176 7851072
16:05:03 37884 8192000000 10286985568 10725449728 1355582640 14.198 99 30 60 0 1376072208 13872000 6617568
16:06:43 109766 8192000000 10276069904 10734977024 1305769520 13.748 95 17 34 0 1323392256 14111824 3510912
16:09:32 51874 8192000000 10270949344 10713296896 1315522704 13.836 96 14 26 0 1324556064 6567488 2465872
16:11:30 103760 8192000000 10279974336 10726187008 1362214688 14.257 99 20 40 1 1377830352 10702592 4913072
16:14:27 37153 8192000000 10278710080 10740695040 1371861008 14.344 100 18 36 0 1389576832 13379136 4336688
16:16:04 96936 8192000000 10275151104 10740084736 1373545280 14.359 99 11 22 0 1384226560 7840816 2840464
16:18:42 47119 8192000000 10274347952 10734252032 1354867232 14.191 98 10 20 0 1366325664 8877728 2580704
16:20:31 66625 8192000000 10278041040 10734395392 1357757792 14.217 98 11 23 1 1370918848 10273408 2887648
16:22:38 61348 8192000000 10284231584 10737307648 1342709488 14.082 97 23 42 0 1360026032 11971616 5344928
16:24:43 30922 8192000000 10295570400 10741182464 1366222512 14.293 99 42 84 0 1383540480 8218080 9099888
16:26:22 78847 8192000000 10301962064 10743009280 1340489600 14.062 96 24 48 1 1365306592 19744656 5072336
16:28:42 26689 8192000000 10263248688 10704412672 1292736544 13.629 94 25 50 2 1310215856 11649072 5830240
16:30:13 46663 8192000000 10253386144 10689945600 1264832688 13.374 93 18 36 0 1279505152 11015616 3656848
16:32:16 39256 8192000000 10256566256 10694963200 1324343344 13.916 98 22 45 1 1339384112 10351024 4689744
16:34:04 104799 8192000000 10244134528 10699149312 1335448880 14.016 99 17 32 1 1349083104 9791264 3842960
16:36:49 12637 8192000000 10238988032 10685751296 1338796352 14.047 100 23 46 0 1352382048 8632176 4953520
16:38:03 41308 8192000000 9485792256 9811214336 626356016 7.102 47 8 16 0 639585904 11844448 1385440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-12-2022 16:41:08 to 27-12-2022 16:41:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-12-2022 16:41:25 to 27-12-2022 16:41:27.

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          245317
MON$OLDEST_ACTIVE               246030
MON$OLDEST_SNAPSHOT             246030
MON$NEXT_TRANSACTION            246030
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                       2036000
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:740: 
:741: mscs-test-161.budzdorov.ru    Tue Dec 27 16:39:04 2022
:742:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:743: 
--- end of diff output ---

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

27.12.22 16:41:28. 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-12-27 16:41:30 No severe PSQL-related problems occured
27.12.22 16:41:31. End of report.