Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.10.2022 19: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 15490216 kB
MemAvailable 30598060 kB
Buffers 0 kB
Cached 16256916 kB
SwapCached 2068 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-10-02 19:38:30
DTS_END                         2022-10-02 19:38:30.0860
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/55434
TRN_ID                          248340
ATT_ID                          652
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: 9450.58 17623 1134069
customer order: creation 182.34 351 21881
customer order: refuse 15.93 339 1912
order to supplier: creation 47.34 1251 5681
order to supplier: removal 3.54 96087 425
invoice (draft): creation 48.26 3314 5791
invoice (draft): removal 52.65 755 6318
invoice accept: apply 43.58 17094 5229
invoice accept: cancel 48.03 790 5764
customer reserve: creation 4006.43 170 480771
customer reserve: removal 4692.27 56 563073
realization accept: apply 86.83 196 10419
realization accept: cancel 87.42 106 10490
payment from customer: creation 62.16 151 7459
payment from customer: removal 5.95 60 714
payment to supplier: creation 51.52 140 6182
payment to supplier: removal 3.43 121 412
service: total inventory turnovers 5.20 1015 624
service: total monetary turnovers 6.79 45 815
service: aggregate perf. data 0.88 17282 105
service: refresh index statistics 0.03 213130 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-10-2022 19:40:31 to 02-10-2022 19:40:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3612
TEST_TIME 2 3386
TEST_TIME 3 3603
TEST_TIME 4 4581
TEST_TIME 5 4592
TEST_TIME 6 4227
TEST_TIME 7 4301
TEST_TIME 8 4493
TEST_TIME 9 4729
TEST_TIME 10 4728
TEST_TIME 11 4765
TEST_TIME 12 5160
TEST_TIME 13 5280
TEST_TIME 14 5777
TEST_TIME 15 6253
TEST_TIME 16 6806
TEST_TIME 17 6986
TEST_TIME 18 7107
TEST_TIME 19 7493
TEST_TIME 20 7874
TEST_TIME 21 8298
TEST_TIME 22 8142
TEST_TIME 23 8495
TEST_TIME 24 8861
TEST_TIME 25 8916
TEST_TIME 26 9289
TEST_TIME 27 9409
TEST_TIME 28 9654
TEST_TIME 29 10246
TEST_TIME 30 10154
TEST_TIME 31 10053
TEST_TIME 32 10164
TEST_TIME 33 10655
TEST_TIME 34 10648
TEST_TIME 35 10535
TEST_TIME 36 10460
TEST_TIME 37 10458
TEST_TIME 38 10242
TEST_TIME 39 10168
TEST_TIME 40 10059
TEST_TIME 41 10065
TEST_TIME 42 10016
TEST_TIME 43 10046
TEST_TIME 44 10000
TEST_TIME 45 9866
TEST_TIME 46 9873
TEST_TIME 47 9899
TEST_TIME 48 10046
TEST_TIME 49 9938
TEST_TIME 50 9907
TEST_TIME 51 9841
TEST_TIME 52 9758
TEST_TIME 53 9595
TEST_TIME 54 9606
TEST_TIME 55 9589
TEST_TIME 56 9498
TEST_TIME 57 9509
TEST_TIME 58 9447
TEST_TIME 59 9469
TEST_TIME 60 9545
TEST_TIME 61 9534
TEST_TIME 62 9591
TEST_TIME 63 9639
TEST_TIME 64 9648
TEST_TIME 65 9732
TEST_TIME 66 9786
TEST_TIME 67 9794
TEST_TIME 68 9856
TEST_TIME 69 9844
TEST_TIME 70 9868
TEST_TIME 71 9908
TEST_TIME 72 9855
TEST_TIME 73 9723
TEST_TIME 74 9779
TEST_TIME 75 9797
TEST_TIME 76 9731
TEST_TIME 77 9641
TEST_TIME 78 9658
TEST_TIME 79 9652
TEST_TIME 80 9602
TEST_TIME 81 9586
TEST_TIME 82 9560
TEST_TIME 83 9644
TEST_TIME 84 9657
TEST_TIME 85 9627
TEST_TIME 86 9673
TEST_TIME 87 9635
TEST_TIME 88 9714
TEST_TIME 89 9685
TEST_TIME 90 9729
TEST_TIME 91 9731
TEST_TIME 92 9753
TEST_TIME 93 9722
TEST_TIME 94 9688
TEST_TIME 95 9637
TEST_TIME 96 9581
TEST_TIME 97 9582
TEST_TIME 98 9527
TEST_TIME 99 9551
TEST_TIME 100 9515
TEST_TIME 101 9484
TEST_TIME 102 9523
TEST_TIME 103 9497
TEST_TIME 104 9537
TEST_TIME 105 9518
TEST_TIME 106 9531
TEST_TIME 107 9522
TEST_TIME 108 9524
TEST_TIME 109 9601
TEST_TIME 110 9587
TEST_TIME 111 9560
TEST_TIME 112 9626
TEST_TIME 113 9644
TEST_TIME 114 9610
TEST_TIME 115 9577
TEST_TIME 116 9564
TEST_TIME 117 9539
TEST_TIME 118 9552
TEST_TIME 119 9489
TEST_TIME 120 9452

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-10-2022 19:40:35 to 02-10-2022 19:40:39.

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 1580185 1580185 0 0.00 0 26839 6 0 0
doc_list_biud 1580184 1580184 0 0.00 0 34951 26 0 0
sp_add_invoice_to_stock 5380 5229 151 2.80 52 170275 17094 0 151
sp_cancel_adding_invoice 5764 5764 0 0.00 4 75282 791 0 0
sp_cancel_client_order 1912 1912 0 0.00 3 12307 340 0 0
sp_cancel_customer_reserve 563073 563073 0 0.00 0 34953 57 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 4154 60 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 7368 121 0 0
sp_cancel_supplier_invoice 6334 6318 16 0.25 1 31336 756 0 16
sp_cancel_supplier_order 425 425 0 0.00 6 949849 96087 0 0
sp_cancel_write_off 10490 10490 0 0.00 1 13099 107 0 0
sp_client_order 21881 21881 0 0.00 3 21340 352 0 0
sp_customer_reserve 480771 480771 0 0.00 0 76707 170 0 0
sp_fill_shopping_cart 33327 33327 0 0.00 1 18321 33 0 0
sp_fill_shopping_cart_clo_res 480769 480769 0 0.00 0 18143 14 0 0
sp_get_clo_for_invoice 5226 5226 0 0.00 0 33122 281 0 0
sp_kill_cost_storno 17380 17380 0 0.00 0 26835 53 0 0
sp_kill_qstorno_ret_qs2qd 1143379 1143379 0 0.00 0 32874 31 0 0
sp_kill_qty_storno 603627 603627 0 0.00 0 34951 67 0 0
sp_lock_selected_doc 21629 21629 0 0.00 0 9182 7 0 0
sp_make_cost_storno 29286 29286 0 0.00 0 18114 63 0 0
sp_multiply_rows_for_pdistr 29286 29286 0 0.00 0 14434 29 0 0
sp_multiply_rows_for_qdistr 21881 21881 0 0.00 0 18922 150 0 0
sp_pay_from_customer 7459 7459 0 0.00 4 17738 151 0 0
sp_pay_to_supplier 6182 6182 0 0.00 4 15873 141 0 0
sp_payment_common 13641 13641 0 0.00 1 9671 32 0 0
sp_qd_handle_on_cancel_clo 1912 1912 0 0.00 0 12298 316 0 0
sp_qd_handle_on_invoice_upd_sts 10990 10990 0 0.00 0 25569 320 0 0
sp_qd_handle_on_reserve_upd_sts 10419 10419 0 0.00 0 12363 58 0 0
sp_reserve_write_off 10419 10419 0 0.00 6 18837 197 0 0
sp_supplier_invoice 5791 5791 0 0.00 6 102893 3315 0 0
sp_supplier_order 5681 5681 0 0.00 4 31520 1251 0 0
srv_aggregate_perf_data 105 105 0 0.00 3086 233204 17282 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1009 145715 41932 0 0
srv_find_qd_qs_mism 1084535 1084535 0 0.00 0 13658 5 0 0
srv_make_invnt_saldo 624 624 0 0.00 29 70636 1016 0 0
srv_make_money_saldo 815 815 0 0.00 1 19190 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 128211 313353 213130 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5229 5229 0 0.00 0 23370 192 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 9168 105 0 0
t$perf-norm:sp_cancel_client_order 1912 1912 0 0.00 0 4698 17 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 2393 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 2293 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1962 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 6583 30 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 30114 1791 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 9730 16 0 0
t$perf-norm:sp_client_order 21881 21881 0 0.00 0 16366 19 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 2731 5 0 0
t$perf-norm:sp_pay_from_customer 7459 7459 0 0.00 0 9045 14 0 0
t$perf-norm:sp_pay_to_supplier 6182 6182 0 0.00 0 9038 13 0 0
t$perf-norm:sp_reserve_write_off 10419 10419 0 0.00 0 4263 11 0 0
t$perf-norm:sp_supplier_invoice 5791 5791 0 0.00 0 12951 20 0 0
t$perf-norm:sp_supplier_order 5681 5681 0 0.00 0 10842 16 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 434 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 74 1 0 0
t$perf-norm:srv_make_invnt_saldo 624 624 0 0.00 0 2018 20 0 0
t$perf-norm:srv_make_money_saldo 815 815 0 0.00 0 10602 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10752 10450 302 2.80 0 17256 25 0 302
v_all_customers 39634 39634 0 0.00 0 107 0 0 0
v_all_suppliers 31794 31794 0 0.00 0 30 1 0 0
v_all_wares 43762 43762 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 288 5 0 0
v_cancel_client_order 3824 3824 0 0.00 0 2261 5 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 1590 5 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 2576 9 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 5565 17 0 0
v_cancel_supplier_invoice 888 856 32 3.60 0 2338 16 0 32
v_cancel_supplier_order 850 850 0 0.00 0 137 2 0 0
v_cancel_write_off 1980 1980 0 0.00 0 4296 19 0 0
v_max_id_clo_ord 5662 5662 0 0.00 0 763 1 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 3562 26 0 0
v_max_id_ord_sup 5784 5784 0 0.00 0 4083 4 0 0
v_max_non_paid_invoice 1731 1731 0 0.00 0 1039 3 0 0
v_max_non_paid_realizn 3928 3928 0 0.00 0 4183 5 0 0
v_min_id_clo_ord 5662 5662 0 0.00 0 18280 13 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 6707 8 0 0
v_min_id_ord_sup 5784 5784 0 0.00 0 5492 6 0 0
v_min_non_paid_invoice 6182 6182 0 0.00 0 8030 23 0 0
v_min_non_paid_realizn 7459 7459 0 0.00 0 7352 25 0 0
v_our_firm 11190 11190 0 0.00 0 22 1 0 0
v_reserve_write_off 20838 20838 0 0.00 0 7585 11 0 0
x_lock_depdocs_on_canc_invoice 11654 11654 0 0.00 0 14328 107 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 14938 335 0 0
x_make_qstorno_1000_1200 5681 5681 0 0.00 1 31511 1173 0 0
x_make_qstorno_1200_2000 5791 5791 0 0.00 3 101427 3202 0 0
x_make_qstorno_2100_3300 480771 480771 0 0.00 0 76706 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-10-2022 19:40:40 to 02-10-2022 19:40:44.

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
17:08:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:34 4177 8192000000 9879076464 10183733248 972089504 10.607 100 83 166 0 1000057728 5737664 22230560
17:10:44 8256 8192000000 9922756928 10235461632 1007177264 10.948 100 91 182 0 1039052608 8237456 23637888
17:12:01 11430 8192000000 9960898416 10283282432 1038368880 11.249 100 87 174 0 1070391136 9816096 22206160
17:13:17 13264 8192000000 9991782000 10326618112 1075793904 11.607 100 78 156 0 1104842320 9988912 19059504
17:14:36 6141 8192000000 10057078672 10410004480 1143364624 12.247 100 77 152 0 1172150432 11566592 17219216
17:15:48 6474 8192000000 10105275232 10468868096 1194546976 12.726 100 76 152 0 1223802272 12383296 16872000
17:16:56 2544 8192000000 10132584352 10509627392 1230397024 13.058 100 71 140 1 1257881632 12191072 15293536
17:18:00 1377 8192000000 10177343120 10559795200 1264279136 13.369 100 80 160 0 1295501968 13381680 17841152
17:19:04 1603 8192000000 10210548496 10595835904 1292221712 13.624 100 77 153 0 1326392976 17325872 16845392
17:20:09 7933 8192000000 10211922528 10601869312 1304102000 13.733 100 74 142 1 1331138032 12208544 14827488
17:21:18 2642 8192000000 10210335760 10610794496 1314984048 13.831 100 57 114 0 1335663808 7814144 12865616
17:22:21 7655 8192000000 10205368848 10619809792 1319379376 13.871 100 29 59 1 1332520336 6245168 6895792
17:23:29 8815 8192000000 10203138736 10622550016 1323787824 13.911 100 20 39 0 1333566832 5577584 4201424
17:24:39 58846 8192000000 10220143072 10642059264 1333362480 13.998 100 28 54 1 1344996448 4977696 6656272
17:26:38 3458 8192000000 10234151600 10655121408 1339184064 14.050 100 41 82 1 1356953728 8241184 9528480
17:27:44 6500 8192000000 10235478096 10656067584 1341388816 14.070 100 33 67 0 1356162704 7481184 7292704
17:28:51 16039 8192000000 10201105344 10609455104 1263863632 13.365 96 25 52 1 1275238320 5389616 5985072
17:30:09 14442 8192000000 10191008448 10604244992 1279961072 13.513 98 36 70 0 1293254592 6037232 7256288
17:31:28 6926 8192000000 10145728240 10543865856 1215298720 12.918 96 25 50 1 1227091344 6067040 5725584
17:32:42 6985 8192000000 10143081040 10547396608 1240837344 13.154 98 20 40 0 1250586064 5050976 4697744
17:33:56 61120 8192000000 10174848368 10580549632 1277416256 13.489 100 27 53 1 1293059168 9228256 6414656
17:36:00 6029 8192000000 10184743232 10593976320 1292481952 13.627 100 38 76 0 1308151184 6538352 9130880
17:37:06 9167 8192000000 10197055232 10607861760 1305044864 13.741 100 20 37 0 1315382880 5441840 4896176
17:38:15 11099 8192000000 10219291552 10632404992 1324547824 13.918 100 18 36 0 1333750064 5442176 3760064
17:39:28 70621 8192000000 10251451632 10666614784 1345388496 14.106 100 45 88 1 1363017856 8184032 9445328
17:41:54 3923 8192000000 10276959072 10688868352 1365249232 14.284 100 56 108 3 1388711984 11696032 11766720
17:43:04 62058 8192000000 10290025024 10715500544 1384049488 14.453 100 25 50 1 1403358000 13150160 6158352
17:45:24 17964 8192000000 10307269136 10731905024 1384480688 14.457 99 37 74 0 1412533296 18890880 9161728
17:46:50 12589 8192000000 10320775456 10748047360 1402934528 14.621 100 43 87 0 1438053008 24050288 11068192
17:48:07 12912 8192000000 10319032816 10750533632 1403878272 14.630 100 37 72 0 1435333200 21913952 9540976
17:49:30 5547 8192000000 10327254496 10745630720 1381616848 14.431 98 59 112 0 1422106192 26211040 14278304
17:50:41 7927 8192000000 10340074544 10756755456 1393054304 14.533 99 58 116 1 1433668576 26276864 14337408
17:51:51 2787 8192000000 10339482208 10757226496 1399909856 14.594 100 54 104 0 1442813040 29394912 13508272
17:52:57 4428 8192000000 10328695392 10747154432 1368887584 14.317 98 64 127 1 1419770976 34474912 16408480
17:54:07 2515 8192000000 10320599664 10739302400 1379814592 14.415 100 62 123 2 1431035152 36030464 15190096
17:55:15 3012 8192000000 10295815536 10712973312 1347208752 14.122 98 54 106 0 1395198896 34469616 13520528
17:56:24 3211 8192000000 10314601760 10719969280 1359905760 14.237 99 77 152 0 1419146752 40585984 18655008
17:57:33 1006 8192000000 10339281568 10748907520 1376410784 14.384 99 71 142 2 1444600016 50250448 17938784
17:58:37 4436 8192000000 10320771664 10743328768 1368948480 14.318 100 44 88 1 1428988240 48076832 11962928
18:00:04 17301 8192000000 10285767856 10709315584 1347625264 14.126 100 29 58 0 1394350016 38132448 8592304
18:01:33 28509 8192000000 10295514080 10731307008 1351221184 14.158 99 32 63 1 1410370176 50380144 8768848
18:03:04 26339 8192000000 10305806656 10746228736 1371094560 14.337 100 24 48 0 1416812592 39342496 6375536
18:04:33 32709 8192000000 10292743792 10728841216 1340103904 14.058 99 21 42 0 1393331184 47312832 5914448
18:06:09 27348 8192000000 10288479232 10723172352 1341575120 14.072 99 38 75 0 1389225616 38403056 9247440
18:07:47 57863 8192000000 10272101648 10707329024 1347912784 14.129 99 23 47 0 1367602624 14712064 4977776
18:09:54 10078 8192000000 10320224384 10746257408 1383816864 14.451 100 59 115 2 1414254528 17578656 12859008
18:11:14 64648 8192000000 10281343744 10731081728 1359690304 14.235 99 11 22 0 1387670208 25717728 2262176
18:14:26 30215 8192000000 10298060560 10743545856 1386430112 14.474 100 24 48 0 1398637872 7115568 5092192
18:16:39 127147 8192000000 10313820496 10758217728 1390671728 14.512 99 33 66 0 1411918320 13105136 8141456
18:19:52 28903 8192000000 10328771376 10779422720 1407290944 14.660 100 46 93 0 1435518848 17799008 10428896
18:21:23 92022 8192000000 10308360848 10764910592 1380543200 14.421 98 15 28 0 1396720736 13059104 3118432
18:24:06 17553 8192000000 10329266864 10784227328 1420637648 14.778 100 33 65 0 1443867376 14650240 8579488
18:25:26 28619 8192000000 10322800912 10780524544 1401336640 14.607 99 38 76 1 1432222784 22243072 8643072
18:26:57 55891 8192000000 10312508768 10777182208 1412009840 14.702 100 15 30 0 1422922608 7552736 3360032
18:28:57 18990 8192000000 10320641200 10774298624 1408726976 14.673 100 17 32 3 1421133216 8863856 3542384
18:30:16 41504 8192000000 10303022768 10777473024 1410285072 14.686 100 12 24 1 1423335488 10235184 2815232
18:32:32 33976 8192000000 10286651664 10750226432 1360694256 14.244 97 31 62 1 1377145520 9700368 6750896
18:34:09 53745 8192000000 10299534800 10767097856 1405808256 14.647 100 11 20 0 1419270912 11535232 1927424
18:36:22 47819 8192000000 10288365552 10758737920 1360911024 14.246 97 15 30 0 1374110096 9259344 3939728
18:38:13 22350 8192000000 10271698016 10725904384 1337665568 14.036 96 33 66 0 1353600400 8814288 7120544
18:39:36 62450 8192000000 10266236736 10731819008 1333881792 14.002 96 20 40 1 1348699152 10451104 4366256
18:41:39 25494 8192000000 10259613728 10711912448 1322070512 13.895 96 13 26 0 1333858304 9107168 2680624
18:43:05 14603 8192000000 10284253088 10732523520 1371096592 14.337 100 38 76 0 1393055616 13451008 8508016
18:44:22 30617 8192000000 10276804704 10720272384 1346629824 14.117 98 39 76 0 1363031712 8042912 8358976
18:45:53 29755 8192000000 10268172800 10709381120 1310470720 13.790 96 34 68 1 1332445248 14527680 7446848
18:47:29 61495 8192000000 10237285040 10681638912 1265550464 13.381 93 15 30 0 1284788352 15822400 3415488
18:49:32 60388 8192000000 10278985760 10710134784 1325739824 13.929 98 30 59 0 1341977232 9125952 7111456
18:51:35 117479 8192000000 10261645280 10731085824 1321361680 13.889 97 20 40 0 1346881760 20951472 4568608
18:54:33 39295 8192000000 10250445856 10713726976 1328248128 13.951 98 4 8 0 1348729024 19879232 601664
18:56:25 68318 8192000000 10263797008 10721341440 1368116080 14.310 100 9 18 0 1379894960 9869024 1909856
18:58:50 105076 8192000000 10271362672 10732077056 1340837744 14.065 97 8 16 0 1355509104 12817472 1853888
19:01:35 39808 8192000000 10273072992 10720722944 1354797680 14.191 99 18 36 0 1369902640 10771616 4333344
19:03:15 48958 8192000000 10301184704 10738966528 1377043792 14.390 100 33 66 0 1397417136 12710960 7662384
19:05:06 32819 8192000000 10303000704 10747854848 1379171104 14.409 99 31 61 0 1394895920 8921664 6803152
19:06:40 50959 8192000000 10301471344 10756956160 1365652544 14.288 98 20 40 0 1380980336 11195712 4132080
19:08:33 71213 8192000000 10299185984 10746236928 1313691744 13.820 94 19 38 0 1328258800 10399280 4167776
19:10:52 64304 8192000000 10299643088 10745360384 1383125184 14.444 99 35 70 0 1400091744 9140432 7826128
19:12:59 44529 8192000000 10296486000 10759184384 1393755328 14.539 100 20 40 0 1411398816 13416976 4226512
19:15:17 57151 8192000000 10287968784 10753187840 1381554480 14.430 99 17 34 0 1399231392 13764912 3912000
19:17:14 42412 8192000000 10286267088 10749267968 1376656128 14.387 99 10 20 0 1389646112 10602048 2387936
19:19:01 84786 8192000000 10287317936 10758979584 1391391456 14.518 100 12 24 0 1403863888 10005360 2467072
19:21:26 57218 8192000000 10282085472 10748637184 1351072128 14.157 97 13 26 0 1367838640 13934912 2831600
19:24:10 47262 8192000000 10279874080 10728095744 1354469680 14.188 99 33 69 0 1377265696 15016592 7779424
19:26:00 64488 8192000000 10250689312 10691420160 1304106464 13.733 96 22 44 0 1321426720 12361744 4958512
19:28:06 14548 8192000000 10258444448 10689495040 1318017760 13.859 97 52 104 1 1337210432 7155904 12036768
19:29:24 25963 8192000000 10272738640 10700791808 1324238608 13.915 98 43 85 1 1345175888 10886096 10051184
19:30:55 69545 8192000000 10266749792 10722549760 1340554448 14.062 99 21 42 0 1357441136 12330816 4555872
19:33:05 145714 8192000000 10265806016 10728251392 1351897024 14.165 99 32 63 1 1375614496 15963616 7753856
19:36:46 70406 8192000000 10234299376 10693492736 1309717648 13.784 97 4 8 0 1325973920 15479984 776288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-10-2022 19:40:46 to 02-10-2022 19:41:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 151 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

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

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          246865
MON$OLDEST_ACTIVE               248574
MON$OLDEST_SNAPSHOT             248574
MON$NEXT_TRANSACTION            248574
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                       2037024
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.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:95: 
:96: mscs-test-161.budzdorov.ru    Sun Oct  2 19:38:33 2022
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:98: 
:99: 
:100: mscs-test-161.budzdorov.ru    Sun Oct  2 19:38:46 2022
:101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:102: 
:103: 
:104: mscs-test-161.budzdorov.ru    Sun Oct  2 19:38:47 2022
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:106: 
:107: 
:108: mscs-test-161.budzdorov.ru    Sun Oct  2 19:38:47 2022
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:110: 
--- end of diff output ---

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

02.10.22 19:41:05. 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-10-02 19:41:08 No severe PSQL-related problems occured
02.10.22 19:41:09. End of report.