Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.12.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 14873964 kB
MemAvailable 30136836 kB
Buffers 0 kB
Cached 16855268 kB
SwapCached 3272 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-26 19:38:35
DTS_END                         2022-12-26 19:38:35.7130
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/36538
TRN_ID                          244238
ATT_ID                          710
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: 9393.14 23542 1127177
customer order: creation 173.29 360 20795
customer order: refuse 14.73 384 1767
order to supplier: creation 46.18 1233 5541
order to supplier: removal 3.56 92203 427
invoice (draft): creation 46.79 3219 5615
invoice (draft): removal 55.18 705 6621
invoice accept: apply 42.01 16138 5041
invoice accept: cancel 49.50 817 5940
customer reserve: creation 3849.30 159 461916
customer reserve: removal 4811.15 54 577338
realization accept: apply 82.48 190 9898
realization accept: cancel 88.57 109 10628
payment from customer: creation 59.08 108 7089
payment from customer: removal 5.67 68 680
payment to supplier: creation 50.08 123 6010
payment to supplier: removal 3.74 72 449
service: total inventory turnovers 4.63 1413 556
service: total monetary turnovers 6.40 30 768
service: aggregate perf. data 0.78 20294 94
service: refresh index statistics 0.03 333155 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-12-2022 19:40:42 to 26-12-2022 19:40:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4099
TEST_TIME 2 4388
TEST_TIME 3 4688
TEST_TIME 4 5025
TEST_TIME 5 4867
TEST_TIME 6 5181
TEST_TIME 7 5466
TEST_TIME 8 5558
TEST_TIME 9 5565
TEST_TIME 10 5606
TEST_TIME 11 6470
TEST_TIME 12 6581
TEST_TIME 13 6497
TEST_TIME 14 6532
TEST_TIME 15 6918
TEST_TIME 16 7003
TEST_TIME 17 7249
TEST_TIME 18 7947
TEST_TIME 19 8388
TEST_TIME 20 8925
TEST_TIME 21 8930
TEST_TIME 22 8953
TEST_TIME 23 9320
TEST_TIME 24 9487
TEST_TIME 25 10173
TEST_TIME 26 10003
TEST_TIME 27 10460
TEST_TIME 28 10571
TEST_TIME 29 10956
TEST_TIME 30 10790
TEST_TIME 31 10688
TEST_TIME 32 10747
TEST_TIME 33 10621
TEST_TIME 34 10435
TEST_TIME 35 10157
TEST_TIME 36 10085
TEST_TIME 37 10062
TEST_TIME 38 10047
TEST_TIME 39 9939
TEST_TIME 40 9878
TEST_TIME 41 9782
TEST_TIME 42 9616
TEST_TIME 43 9688
TEST_TIME 44 9906
TEST_TIME 45 9783
TEST_TIME 46 9729
TEST_TIME 47 9593
TEST_TIME 48 9522
TEST_TIME 49 9459
TEST_TIME 50 9420
TEST_TIME 51 9332
TEST_TIME 52 9223
TEST_TIME 53 9141
TEST_TIME 54 9340
TEST_TIME 55 9355
TEST_TIME 56 9361
TEST_TIME 57 9313
TEST_TIME 58 9388
TEST_TIME 59 9542
TEST_TIME 60 9579
TEST_TIME 61 9594
TEST_TIME 62 9710
TEST_TIME 63 9749
TEST_TIME 64 9727
TEST_TIME 65 9767
TEST_TIME 66 9745
TEST_TIME 67 9800
TEST_TIME 68 9836
TEST_TIME 69 9808
TEST_TIME 70 9716
TEST_TIME 71 9635
TEST_TIME 72 9554
TEST_TIME 73 9614
TEST_TIME 74 9570
TEST_TIME 75 9511
TEST_TIME 76 9556
TEST_TIME 77 9497
TEST_TIME 78 9464
TEST_TIME 79 9391
TEST_TIME 80 9389
TEST_TIME 81 9485
TEST_TIME 82 9446
TEST_TIME 83 9458
TEST_TIME 84 9463
TEST_TIME 85 9590
TEST_TIME 86 9593
TEST_TIME 87 9596
TEST_TIME 88 9607
TEST_TIME 89 9645
TEST_TIME 90 9652
TEST_TIME 91 9734
TEST_TIME 92 9721
TEST_TIME 93 9748
TEST_TIME 94 9682
TEST_TIME 95 9612
TEST_TIME 96 9571
TEST_TIME 97 9532
TEST_TIME 98 9504
TEST_TIME 99 9499
TEST_TIME 100 9459
TEST_TIME 101 9467
TEST_TIME 102 9447
TEST_TIME 103 9416
TEST_TIME 104 9426
TEST_TIME 105 9518
TEST_TIME 106 9518
TEST_TIME 107 9490
TEST_TIME 108 9614
TEST_TIME 109 9638
TEST_TIME 110 9622
TEST_TIME 111 9675
TEST_TIME 112 9621
TEST_TIME 113 9559
TEST_TIME 114 9519
TEST_TIME 115 9499
TEST_TIME 116 9502
TEST_TIME 117 9480
TEST_TIME 118 9454
TEST_TIME 119 9435
TEST_TIME 120 9398

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-12-2022 19:40:46 to 26-12-2022 19:40:49.

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 1556670 1556670 0 0.00 0 27999 6 0 0
doc_list_biud 1556670 1556670 0 0.00 0 47662 26 0 0
sp_add_invoice_to_stock 5126 5041 85 1.65 31 187401 16138 0 85
sp_cancel_adding_invoice 5940 5940 0 0.00 5 59278 817 0 0
sp_cancel_client_order 1767 1767 0 0.00 4 13275 385 0 0
sp_cancel_customer_reserve 577338 577338 0 0.00 0 45541 54 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 3638 69 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 3 4890 73 0 0
sp_cancel_supplier_invoice 6634 6621 13 0.19 2 51013 705 0 13
sp_cancel_supplier_order 427 427 0 0.00 12 1019150 92203 0 0
sp_cancel_write_off 10628 10628 0 0.00 1 13171 109 0 0
sp_client_order 20795 20795 0 0.00 3 34006 361 0 0
sp_customer_reserve 461916 461916 0 0.00 0 59807 160 0 0
sp_fill_shopping_cart 31950 31950 0 0.00 1 12957 39 0 0
sp_fill_shopping_cart_clo_res 461906 461906 0 0.00 0 19897 13 0 0
sp_get_clo_for_invoice 5030 5030 0 0.00 0 31321 308 0 0
sp_kill_cost_storno 17697 17697 0 0.00 0 13817 51 0 0
sp_kill_qstorno_ret_qs2qd 1172352 1172352 0 0.00 0 47661 29 0 0
sp_kill_qty_storno 617649 617649 0 0.00 0 47662 64 0 0
sp_lock_selected_doc 20834 20834 0 0.00 0 5810 5 0 0
sp_make_cost_storno 28027 28027 0 0.00 0 27994 61 0 0
sp_multiply_rows_for_pdistr 28027 28027 0 0.00 0 6483 26 0 0
sp_multiply_rows_for_qdistr 20795 20795 0 0.00 0 17775 161 0 0
sp_pay_from_customer 7089 7089 0 0.00 4 11809 109 0 0
sp_pay_to_supplier 6010 6010 0 0.00 4 16701 123 0 0
sp_payment_common 13099 13099 0 0.00 1 11780 33 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 13257 360 0 0
sp_qd_handle_on_invoice_upd_sts 10970 10970 0 0.00 0 21810 313 0 0
sp_qd_handle_on_reserve_upd_sts 9898 9898 0 0.00 0 14516 68 0 0
sp_reserve_write_off 9898 9898 0 0.00 6 14591 191 0 0
sp_supplier_invoice 5615 5615 0 0.00 6 86719 3220 0 0
sp_supplier_order 5541 5541 0 0.00 5 44598 1234 0 0
srv_aggregate_perf_data 94 94 0 0.00 2873 224672 20295 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2110 201771 51182 0 0
srv_find_qd_qs_mism 1080049 1080049 0 0.00 0 23596 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 35 148001 1414 0 0
srv_make_money_saldo 768 768 0 0.00 1 2167 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 146348 485101 333156 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 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 5041 5041 0 0.00 0 12098 174 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 19917 170 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 4451 21 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 912 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 2212 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 711 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 6001 26 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 29965 1440 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 4990 14 0 0
t$perf-norm:sp_client_order 20795 20795 0 0.00 0 8850 17 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 3253 13 0 0
t$perf-norm:sp_pay_from_customer 7089 7089 0 0.00 0 8458 12 0 0
t$perf-norm:sp_pay_to_supplier 6010 6010 0 0.00 0 15999 11 0 0
t$perf-norm:sp_reserve_write_off 9898 9898 0 0.00 0 25730 15 0 0
t$perf-norm:sp_supplier_invoice 5615 5615 0 0.00 0 5345 18 0 0
t$perf-norm:sp_supplier_order 5541 5541 0 0.00 0 10482 15 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 113 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 266 8 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 676 18 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 512 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10230 10060 170 1.66 0 7544 18 0 170
v_all_customers 38078 38078 0 0.00 0 33 1 0 0
v_all_suppliers 31062 31062 0 0.00 0 47 1 0 0
v_all_wares 41590 41590 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 3244 13 0 0
v_cancel_client_order 3534 3534 0 0.00 0 3598 7 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 1523 4 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 1510 4 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 2526 8 0 0
v_cancel_supplier_invoice 866 840 26 3.00 0 3573 21 0 26
v_cancel_supplier_order 854 854 0 0.00 0 1162 4 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2350 14 0 0
v_max_id_clo_ord 5541 5541 0 0.00 0 790 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 1577 12 0 0
v_max_id_ord_sup 5614 5614 0 0.00 0 3640 5 0 0
v_max_non_paid_invoice 1634 1634 0 0.00 0 1853 5 0 0
v_max_non_paid_realizn 3708 3708 0 0.00 0 674 3 0 0
v_min_id_clo_ord 5541 5541 0 0.00 0 4769 10 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 4667 8 0 0
v_min_id_ord_sup 5614 5614 0 0.00 0 6778 11 0 0
v_min_non_paid_invoice 6010 6010 0 0.00 0 9150 25 0 0
v_min_non_paid_realizn 7089 7089 0 0.00 0 8380 27 0 0
v_our_firm 10274 10274 0 0.00 0 21 1 0 0
v_reserve_write_off 19796 19796 0 0.00 0 3345 6 0 0
x_lock_depdocs_on_canc_invoice 12141 12141 0 0.00 0 19903 119 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 9776 309 0 0
x_make_qstorno_1000_1200 5541 5541 0 0.00 1 44589 1153 0 0
x_make_qstorno_1200_2000 5615 5615 0 0.00 3 86706 3082 0 0
x_make_qstorno_2100_3300 461916 461916 0 0.00 0 59804 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-12-2022 19:40:50 to 26-12-2022 19:40:54.

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:39 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:41 13178 8192000000 9877792128 10183757824 969088976 10.578 100 82 164 0 996156144 5478736 21588432
17:10:57 23644 8192000000 9948203424 10267688960 1029795248 11.166 100 91 182 0 1060289424 7771312 22722864
17:12:24 14638 8192000000 9968979696 10296438784 1051504144 11.375 100 86 172 0 1082507632 9626112 21377376
17:13:45 11820 8192000000 10027743616 10369802240 1107856352 11.912 100 78 154 0 1136256336 9568208 18831776
17:15:00 4554 8192000000 10070055952 10425335808 1159812176 12.402 100 83 163 0 1190822768 12492544 18518048
17:16:07 5338 8192000000 10118143984 10489139200 1205767536 12.830 100 68 136 0 1233624800 12685504 15171760
17:17:14 2515 8192000000 10158734208 10533531648 1246581984 13.207 100 81 162 0 1277065808 13040144 17443680
17:18:18 1138 8192000000 10183451056 10566148096 1277060000 13.486 100 89 178 2 1308618704 11345664 20213040
17:19:21 2164 8192000000 10196807104 10581745664 1287101440 13.578 100 85 167 0 1316809280 11615568 18092272
17:20:25 1497 8192000000 10216190848 10605670400 1300606752 13.701 100 76 150 0 1328828208 12243136 15978320
17:21:28 26017 8192000000 10185424000 10602569728 1306034064 13.750 100 31 60 0 1319831840 6588992 7208784
17:22:56 6129 8192000000 10202832816 10612371456 1313619936 13.819 100 29 58 0 1327704368 7900096 6184336
17:24:04 8041 8192000000 10202730688 10620739584 1321039904 13.886 100 17 34 0 1334479456 9714240 3725312
17:25:12 14929 8192000000 10210670448 10627325952 1324432240 13.917 100 21 43 2 1334196448 5038384 4725824
17:26:28 24322 8192000000 10203526704 10626191360 1298875552 13.685 98 17 35 0 1307986176 5188304 3922320
17:27:55 4051 8192000000 10219354064 10626064384 1295961280 13.659 98 42 82 0 1311879264 7244336 8673648
17:29:02 9550 8192000000 10192656288 10602033152 1288157328 13.587 99 20 40 0 1298049648 5121600 4770720
17:30:13 62907 8192000000 10150626656 10553847808 1202952912 12.804 94 43 86 0 1218162256 4942432 10266912
17:32:18 7227 8192000000 10127898640 10524127232 1189973008 12.683 95 20 40 0 1199759184 5194336 4591840
17:33:27 6402 8192000000 10167749344 10567901184 1262585648 13.354 99 21 42 0 1272770592 4943216 5241728
17:34:33 10842 8192000000 10177512672 10586324992 1281920256 13.531 100 10 20 0 1289814704 5288800 2605648
17:35:44 34511 8192000000 10189882112 10602737664 1295291168 13.652 100 32 64 1 1309831904 6547904 7992832
17:37:20 8542 8192000000 10201520176 10616541184 1312326288 13.807 100 42 84 0 1327716496 5616976 9773232
17:38:30 31376 8192000000 10229409280 10646126592 1333374112 13.998 100 24 47 1 1344041792 5690832 4976848
17:40:09 11818 8192000000 10282680064 10696622080 1362342496 14.258 100 56 112 1 1393395664 18192240 12860928
17:41:27 30490 8192000000 10273175824 10699374592 1370486608 14.331 100 24 46 0 1388334544 12061360 5786576
17:43:00 31087 8192000000 10299567056 10725224448 1385812928 14.468 100 38 76 0 1415571888 20675056 9083904
17:44:37 16119 8192000000 10318640832 10740391936 1395318128 14.553 100 51 102 0 1428227472 20949840 11959504
17:45:57 4241 8192000000 10326077248 10749853696 1404108944 14.632 100 57 114 0 1439915120 22394976 13411200
17:47:09 8832 8192000000 10331567504 10757869568 1404831648 14.638 100 51 102 1 1443258016 25765264 12661104
17:48:25 18985 8192000000 10341979088 10770771968 1409313312 14.678 100 53 108 0 1454159760 31710256 13136192
17:49:48 3370 8192000000 10351800096 10769350656 1412121376 14.703 100 61 120 0 1456805696 30635328 14048992
17:50:58 2869 8192000000 10351353152 10764296192 1403986176 14.630 100 82 160 3 1459983136 35538864 20458096
17:52:06 2351 8192000000 10341154704 10751168512 1377564096 14.395 98 72 139 0 1433682784 38858432 17260256
17:53:17 3088 8192000000 10375875264 10786967552 1410936160 14.692 100 79 158 0 1476028064 44685776 20406128
17:54:26 5909 8192000000 10319772896 10732871680 1349526912 14.143 98 58 116 2 1411389536 46392624 15470000
17:55:35 4764 8192000000 10347404992 10759532544 1382137040 14.436 100 57 114 0 1450855232 53792960 14925232
17:56:50 2110 8192000000 10364569520 10772492288 1386099888 14.471 100 83 161 4 1461137136 55150352 19886896
17:57:58 10621 8192000000 10322820512 10747097088 1376489680 14.385 100 43 85 1 1434296512 46155328 11651504
17:59:18 7702 8192000000 10316330112 10741813248 1375702224 14.378 100 33 66 0 1421867008 37042864 9121920
18:00:33 9415 8192000000 10306343568 10732085248 1347782592 14.128 98 35 70 2 1396010080 39348912 8878576
18:01:45 21116 8192000000 10306090048 10742075392 1368415760 14.313 100 26 52 0 1413762720 37928624 7418336
18:03:08 23566 8192000000 10296569776 10741149696 1358111648 14.220 99 20 41 0 1405233344 41183664 5938032
18:04:41 30015 8192000000 10306043536 10762174464 1382403104 14.438 100 17 35 0 1422536080 35417056 4715920
18:07:02 65496 8192000000 10283373152 10726567936 1361005328 14.246 99 6 12 0 1379666128 17472752 1188048
18:09:08 110318 8192000000 10277861392 10720415744 1324109472 13.914 96 20 37 0 1339606912 11876080 3621360
18:12:01 102187 8192000000 10283944240 10717372416 1364949232 14.282 99 33 66 0 1378898576 6271872 7677472
18:14:53 69361 8192000000 10291601472 10748002304 1389070288 14.498 100 16 32 0 1398634384 6105248 3458848
18:17:07 96543 8192000000 10302104720 10759544832 1381459440 14.430 99 16 33 0 1398324784 12861808 4003536
18:19:45 53688 8192000000 10302688304 10759090176 1388736480 14.495 99 12 24 0 1405236000 13480192 3019328
18:21:57 53262 8192000000 10292259408 10776948736 1397330592 14.571 100 17 34 0 1418756496 17076256 4349648
18:23:54 82756 8192000000 10291952176 10753904640 1394394512 14.545 100 20 40 0 1408147104 9288176 4464416
18:26:34 40363 8192000000 10297756368 10758160384 1399034272 14.586 100 26 52 0 1416137056 11605552 5497232
18:28:31 69349 8192000000 10294433920 10762846208 1389584304 14.502 99 8 16 0 1404909264 13417600 1907360
18:30:40 94650 8192000000 10324952656 10777817088 1395564176 14.555 99 32 64 1 1423020304 18563232 8892896
18:33:46 87674 8192000000 10288744704 10737414144 1273160432 13.451 91 12 24 0 1286406752 10548304 2698016
18:36:21 50628 8192000000 10271782624 10733338624 1349998592 14.147 98 16 32 0 1369412704 15714288 3699824
18:38:30 104362 8192000000 10263580704 10703568896 1259526560 13.326 91 13 26 0 1275670144 13408560 2735024
18:41:17 76895 8192000000 10229152880 10665107456 1248190944 13.222 92 9 18 0 1260733408 10790752 1751712
18:43:41 71682 8192000000 10273433216 10719715328 1317411376 13.853 97 19 38 0 1337529472 16174016 3944080
18:47:16 39688 8192000000 10282040288 10712391680 1340726016 14.064 98 31 57 2 1356390160 9097856 6566288
18:48:56 99784 8192000000 10271148208 10724237312 1352091488 14.166 98 27 54 0 1366963360 8548000 6323872
18:51:53 44147 8192000000 10272438640 10731380736 1369505328 14.323 100 20 40 0 1381662528 7555360 4601840
18:54:24 41263 8192000000 10250828272 10711834624 1325069792 13.923 97 10 20 0 1334284976 7094208 2120976
18:56:09 68422 8192000000 10264926288 10724724736 1371758432 14.343 100 14 28 0 1384939776 9731792 3449552
18:59:12 53963 8192000000 10271482080 10730045440 1348456160 14.134 98 14 27 0 1359941920 8149824 3335936
19:01:19 80513 8192000000 10284231120 10743095296 1349965792 14.147 97 21 42 0 1370789456 15830336 4993328
19:03:47 72726 8192000000 10281691312 10739990528 1347531120 14.125 97 12 24 0 1361924928 11887488 2506320
19:06:00 52115 8192000000 10301371632 10746859520 1374977792 14.372 99 27 54 0 1389841728 8397728 6466208
19:07:52 54360 8192000000 10300497472 10748010496 1349355328 14.142 97 24 46 0 1365215408 11155648 4704432
19:09:49 74050 8192000000 10291339536 10751336448 1358936176 14.228 98 10 20 1 1382027872 21031072 2060624
19:12:03 107154 8192000000 10297748320 10754617344 1388549552 14.493 100 26 52 0 1410055616 15722992 5783072
19:14:52 70442 8192000000 10293321296 10755702784 1389310288 14.500 100 8 14 0 1398638832 7966752 1361792
19:17:14 68561 8192000000 10281701984 10735198208 1357092176 14.211 97 18 36 0 1369430704 8239936 4098592
19:19:32 49931 8192000000 10300210000 10754678784 1360583824 14.243 97 19 38 1 1374772432 9497408 4691200
19:21:27 74585 8192000000 10308834912 10776522752 1374443328 14.367 98 9 18 0 1389199056 12620992 2134736
19:23:46 75775 8192000000 10314729136 10772176896 1375810976 14.379 98 16 32 0 1392686720 13448416 3427328
19:26:05 55971 8192000000 10276152896 10744676352 1298502240 13.682 94 16 32 0 1321350080 18709568 4138272
19:28:01 201770 8192000000 10257498976 10704711680 1237236368 13.121 90 18 35 0 1260223648 19450496 3536784
19:32:25 55413 8192000000 10249729856 10700836864 1339476752 14.053 99 16 30 1 1351905632 9478352 2950528
19:34:21 37131 8192000000 10245550352 10700222464 1338525072 14.044 99 16 31 1 1354315824 11711872 4078880
19:35:58 40443 8192000000 10240740976 10697240576 1319306032 13.870 97 10 20 0 1335167504 13676784 2184688
19:38:02 43515 8192000000 9259444848 9540734976 419584544 4.872 31 4 6 0 422556672 2361664 610464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-12-2022 19:40:56 to 26-12-2022 19:41:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 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 26-12-2022 19:41:12 to 26-12-2022 19:41:14.

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          244505
MON$OLDEST_ACTIVE               244506
MON$OLDEST_SNAPSHOT             244506
MON$NEXT_TRANSACTION            244506
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                       2052768
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 ---
:701: 
:702: mscs-test-161.budzdorov.ru    Mon Dec 26 19:38:50 2022
:703:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:704: 
:705: 
:706: mscs-test-161.budzdorov.ru    Mon Dec 26 19:38:53 2022
:707:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:708: 
--- end of diff output ---

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

26.12.22 19:41:15. 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-26 19:41:18 No severe PSQL-related problems occured
26.12.22 19:41:19. End of report.