Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2022 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15407008 kB
MemAvailable 30500572 kB
Buffers 0 kB
Cached 16289768 kB
SwapCached 2220 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-13 22:38:31
DTS_END                         2022-10-13 22:38:31.7300
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/57734
TRN_ID                          242361
ATT_ID                          644
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: 9539.12 20184 1144694
customer order: creation 181.38 375 21766
customer order: refuse 15.33 335 1840
order to supplier: creation 48.12 1259 5774
order to supplier: removal 3.49 97206 419
invoice (draft): creation 46.52 3254 5582
invoice (draft): removal 55.73 674 6688
invoice accept: apply 42.68 17249 5121
invoice accept: cancel 49.75 722 5970
customer reserve: creation 3920.60 171 470472
customer reserve: removal 4870.10 55 584412
realization accept: apply 87.10 174 10452
realization accept: cancel 85.84 110 10301
payment from customer: creation 60.28 116 7234
payment from customer: removal 5.83 87 700
payment to supplier: creation 49.78 152 5973
payment to supplier: removal 3.61 90 433
service: total inventory turnovers 5.04 1027 605
service: total monetary turnovers 7.01 28 841
service: aggregate perf. data 0.89 19684 107
service: refresh index statistics 0.03 260919 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2022 22:39:36 to 13-10-2022 22:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3735
TEST_TIME 2 5496
TEST_TIME 3 5899
TEST_TIME 4 5939
TEST_TIME 5 6244
TEST_TIME 6 6472
TEST_TIME 7 6187
TEST_TIME 8 6009
TEST_TIME 9 5997
TEST_TIME 10 6536
TEST_TIME 11 6661
TEST_TIME 12 7109
TEST_TIME 13 7559
TEST_TIME 14 7632
TEST_TIME 15 7985
TEST_TIME 16 8159
TEST_TIME 17 8200
TEST_TIME 18 8271
TEST_TIME 19 8704
TEST_TIME 20 8809
TEST_TIME 21 8954
TEST_TIME 22 9330
TEST_TIME 23 9660
TEST_TIME 24 9790
TEST_TIME 25 9945
TEST_TIME 26 10556
TEST_TIME 27 10537
TEST_TIME 28 11211
TEST_TIME 29 11360
TEST_TIME 30 10986
TEST_TIME 31 11184
TEST_TIME 32 11148
TEST_TIME 33 11178
TEST_TIME 34 10928
TEST_TIME 35 10778
TEST_TIME 36 10652
TEST_TIME 37 10754
TEST_TIME 38 10578
TEST_TIME 39 10544
TEST_TIME 40 10397
TEST_TIME 41 10412
TEST_TIME 42 10309
TEST_TIME 43 10138
TEST_TIME 44 10116
TEST_TIME 45 10087
TEST_TIME 46 10025
TEST_TIME 47 10002
TEST_TIME 48 9882
TEST_TIME 49 9726
TEST_TIME 50 9728
TEST_TIME 51 9727
TEST_TIME 52 9754
TEST_TIME 53 9715
TEST_TIME 54 9775
TEST_TIME 55 9711
TEST_TIME 56 9740
TEST_TIME 57 9728
TEST_TIME 58 9724
TEST_TIME 59 9759
TEST_TIME 60 9804
TEST_TIME 61 9888
TEST_TIME 62 9884
TEST_TIME 63 9905
TEST_TIME 64 9767
TEST_TIME 65 9777
TEST_TIME 66 9729
TEST_TIME 67 9682
TEST_TIME 68 9640
TEST_TIME 69 9633
TEST_TIME 70 9575
TEST_TIME 71 9556
TEST_TIME 72 9501
TEST_TIME 73 9608
TEST_TIME 74 9571
TEST_TIME 75 9583
TEST_TIME 76 9614
TEST_TIME 77 9605
TEST_TIME 78 9576
TEST_TIME 79 9639
TEST_TIME 80 9715
TEST_TIME 81 9729
TEST_TIME 82 9716
TEST_TIME 83 9831
TEST_TIME 84 9838
TEST_TIME 85 9788
TEST_TIME 86 9716
TEST_TIME 87 9681
TEST_TIME 88 9644
TEST_TIME 89 9622
TEST_TIME 90 9594
TEST_TIME 91 9548
TEST_TIME 92 9503
TEST_TIME 93 9513
TEST_TIME 94 9583
TEST_TIME 95 9575
TEST_TIME 96 9644
TEST_TIME 97 9634
TEST_TIME 98 9598
TEST_TIME 99 9694
TEST_TIME 100 9719
TEST_TIME 101 9782
TEST_TIME 102 9831
TEST_TIME 103 9883
TEST_TIME 104 9904
TEST_TIME 105 9886
TEST_TIME 106 9802
TEST_TIME 107 9778
TEST_TIME 108 9762
TEST_TIME 109 9745
TEST_TIME 110 9693
TEST_TIME 111 9677
TEST_TIME 112 9634
TEST_TIME 113 9583
TEST_TIME 114 9565
TEST_TIME 115 9536
TEST_TIME 116 9585
TEST_TIME 117 9555
TEST_TIME 118 9543
TEST_TIME 119 9565
TEST_TIME 120 9542

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-10-2022 22:39:40 to 13-10-2022 22:39:43.

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 1582083 1582083 0 0.00 0 35116 6 0 0
doc_list_biud 1582083 1582083 0 0.00 0 57009 26 0 0
sp_add_invoice_to_stock 5258 5121 137 2.60 43 173518 17250 0 137
sp_cancel_adding_invoice 5970 5970 0 0.00 4 57139 722 0 0
sp_cancel_client_order 1840 1840 0 0.00 4 8327 335 0 0
sp_cancel_customer_reserve 584412 584412 0 0.00 0 26765 55 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 4517 87 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 3341 90 0 0
sp_cancel_supplier_invoice 6706 6688 18 0.26 1 21925 674 0 18
sp_cancel_supplier_order 419 419 0 0.00 4 1150966 97207 0 0
sp_cancel_write_off 10301 10301 0 0.00 1 14243 111 0 0
sp_client_order 21766 21766 0 0.00 3 29151 375 0 0
sp_customer_reserve 470472 470472 0 0.00 0 43730 171 0 0
sp_fill_shopping_cart 33120 33120 0 0.00 1 27756 39 0 0
sp_fill_shopping_cart_clo_res 470466 470466 0 0.00 0 16452 13 0 0
sp_get_clo_for_invoice 5116 5116 0 0.00 0 24219 278 0 0
sp_kill_cost_storno 17404 17404 0 0.00 0 14226 55 0 0
sp_kill_qstorno_ret_qs2qd 1186232 1186232 0 0.00 0 26762 30 0 0
sp_kill_qty_storno 625198 625198 0 0.00 0 57003 64 0 0
sp_lock_selected_doc 21549 21549 0 0.00 0 10605 7 0 0
sp_make_cost_storno 28775 28775 0 0.00 0 35107 57 0 0
sp_multiply_rows_for_pdistr 28775 28775 0 0.00 0 18181 30 0 0
sp_multiply_rows_for_qdistr 21766 21766 0 0.00 0 13571 162 0 0
sp_pay_from_customer 7234 7234 0 0.00 4 18193 116 0 0
sp_pay_to_supplier 5973 5973 0 0.00 4 20907 153 0 0
sp_payment_common 13207 13207 0 0.00 1 20877 35 0 0
sp_qd_handle_on_cancel_clo 1840 1840 0 0.00 0 8311 295 0 0
sp_qd_handle_on_invoice_upd_sts 11086 11086 0 0.00 0 56991 313 0 0
sp_qd_handle_on_reserve_upd_sts 10452 10452 0 0.00 0 9301 51 0 0
sp_reserve_write_off 10452 10452 0 0.00 6 13845 174 0 0
sp_supplier_invoice 5582 5582 0 0.00 7 85056 3254 0 0
sp_supplier_order 5774 5774 0 0.00 4 40037 1259 0 0
srv_aggregate_perf_data 107 107 0 0.00 2511 244540 19685 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1320 122603 44977 0 0
srv_find_qd_qs_mism 1096474 1096474 0 0.00 0 17832 5 0 0
srv_make_invnt_saldo 605 605 0 0.00 47 37360 1027 0 0
srv_make_money_saldo 841 841 0 0.00 1 1299 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 113682 441277 260920 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5121 5121 0 0.00 0 12527 171 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 4554 118 0 0
t$perf-norm:sp_cancel_client_order 1840 1840 0 0.00 0 4950 14 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 611 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 2265 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 442 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 856 15 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 25568 1705 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 1368 4 0 0
t$perf-norm:sp_client_order 21766 21766 0 0.00 0 9859 17 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 5733 11 0 0
t$perf-norm:sp_pay_from_customer 7234 7234 0 0.00 0 10693 13 0 0
t$perf-norm:sp_pay_to_supplier 5973 5973 0 0.00 0 6285 14 0 0
t$perf-norm:sp_reserve_write_off 10452 10452 0 0.00 0 6581 10 0 0
t$perf-norm:sp_supplier_invoice 5582 5582 0 0.00 0 6046 16 0 0
t$perf-norm:sp_supplier_order 5774 5774 0 0.00 0 2369 13 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 217 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1644 35 0 0
t$perf-norm:srv_make_invnt_saldo 605 605 0 0.00 0 5769 28 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 6695 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 19 8 0 0
v_add_invoice_to_stock 10494 10220 274 2.61 0 11230 21 0 274
v_all_customers 38982 38982 0 0.00 0 133 1 0 0
v_all_suppliers 31250 31250 0 0.00 0 70 1 0 0
v_all_wares 43532 43532 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1604 8 0 0
v_cancel_client_order 3680 3680 0 0.00 0 6145 7 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 2508 8 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 2811 9 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 2251 11 0 0
v_cancel_supplier_invoice 902 866 36 3.99 0 1485 18 0 36
v_cancel_supplier_order 838 838 0 0.00 0 10306 27 0 0
v_cancel_write_off 2118 2118 0 0.00 0 8979 18 0 0
v_max_id_clo_ord 5774 5774 0 0.00 0 15197 4 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 5328 21 0 0
v_max_id_ord_sup 5580 5580 0 0.00 0 6416 6 0 0
v_max_non_paid_invoice 1702 1702 0 0.00 0 4259 7 0 0
v_max_non_paid_realizn 4086 4086 0 0.00 0 4742 7 0 0
v_min_id_clo_ord 5774 5774 0 0.00 0 13143 9 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 6128 14 0 0
v_min_id_ord_sup 5580 5580 0 0.00 0 3583 5 0 0
v_min_non_paid_invoice 5973 5973 0 0.00 0 9877 30 0 0
v_min_non_paid_realizn 7234 7234 0 0.00 0 5466 17 0 0
v_our_firm 10844 10844 0 0.00 0 25 1 0 0
v_reserve_write_off 20904 20904 0 0.00 0 10296 9 0 0
x_lock_depdocs_on_canc_invoice 12225 12225 0 0.00 0 13663 115 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 15433 444 0 0
x_make_qstorno_1000_1200 5774 5774 0 0.00 1 40024 1176 0 0
x_make_qstorno_1200_2000 5582 5582 0 0.00 3 85047 3114 0 0
x_make_qstorno_2100_3300 470472 470472 0 0.00 0 41172 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2022 22:39:44 to 13-10-2022 22:39:48.

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
20:08:35 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:36 28253 8192000000 9920336048 10230018048 995172912 10.832 100 87 174 0 1024110560 6131072 22806576
20:11:13 26105 8192000000 9960302928 10283409408 1040971792 11.274 100 91 182 1 1072157712 8491280 22694640
20:12:44 15761 8192000000 9992382432 10325016576 1075035280 11.600 100 86 172 0 1106154368 10132032 20987056
20:14:15 7587 8192000000 10038214384 10382135296 1122730624 12.053 100 80 160 0 1152331568 11062176 18538768
20:15:26 6656 8192000000 10078128000 10433216512 1161435328 12.417 100 80 160 0 1192664112 13117744 18111040
20:16:36 2447 8192000000 10120053264 10485940224 1206504672 12.837 100 81 157 0 1236372496 12955376 16912448
20:17:40 1203 8192000000 10159661360 10530082816 1243856464 13.182 100 93 184 0 1278822240 14054544 20911232
20:18:44 1589 8192000000 10188256720 10566053888 1274907776 13.466 100 86 168 2 1304669888 11779840 17982272
20:19:46 32719 8192000000 10197112160 10593406976 1293346480 13.635 100 59 118 2 1315029312 8481104 13201728
20:21:21 6506 8192000000 10190735552 10600771584 1304255968 13.734 100 26 54 0 1316721232 6911568 5553696
20:22:27 4205 8192000000 10197994560 10614558720 1313728176 13.820 100 22 44 0 1325110768 6363040 5019552
20:23:33 13749 8192000000 10212825008 10631897088 1328189584 13.951 100 22 40 1 1337909328 5345392 4374352
20:24:47 57467 8192000000 10238345824 10647326720 1332953280 13.994 100 56 111 1 1351078912 6110928 12014704
20:26:45 3939 8192000000 10222491104 10633617408 1316597232 13.846 99 40 81 0 1335608352 10272816 8738304
20:27:51 11192 8192000000 10219547568 10639040512 1324089904 13.914 100 29 56 1 1341207920 10332816 6785200
20:29:02 11458 8192000000 10207528528 10628444160 1302020880 13.714 98 18 36 1 1311358352 5562224 3775248
20:30:20 5733 8192000000 10198302784 10609831936 1295852144 13.658 99 26 45 0 1306364784 5520560 4992080
20:31:29 4865 8192000000 10151366144 10555457536 1246193472 13.203 98 32 64 0 1260557840 7182192 7182176
20:32:35 52635 8192000000 10140934768 10542133248 1179366560 12.584 93 24 47 0 1189774592 4986176 5421856
20:34:29 10116 8192000000 10150120528 10562908160 1265201296 13.378 100 19 38 1 1275399888 5825504 4373088
20:35:43 8260 8192000000 10182550512 10590797824 1288575968 13.591 100 19 38 0 1298454304 4862816 5015520
20:36:52 12776 8192000000 10192285328 10605666304 1301588560 13.710 100 32 63 2 1314558048 4934320 8035168
20:38:05 17316 8192000000 10200260864 10621104128 1314077232 13.823 100 17 34 0 1322530848 4960080 3493536
20:39:33 8199 8192000000 10246076928 10655236096 1332064944 13.986 100 49 96 1 1352038912 10286032 9687936
20:40:48 8949 8192000000 10267827024 10679726080 1353269120 14.177 100 52 103 0 1378047328 13345072 11433136
20:42:02 28468 8192000000 10269350048 10695630848 1368764384 14.316 100 28 53 0 1386797696 11537920 6495392
20:43:33 6041 8192000000 10288214288 10713223168 1380643456 14.422 100 46 91 0 1404407936 12690112 11074368
20:44:44 10855 8192000000 10297700256 10722820096 1388945584 14.496 100 46 89 0 1413623056 14100496 10576976
20:45:58 4903 8192000000 10301559504 10725670912 1386704608 14.476 99 47 92 1 1416002960 18563440 10734912
20:47:05 8484 8192000000 10331973808 10758553600 1406182576 14.650 100 47 94 0 1448244704 30378000 11684128
20:48:23 2752 8192000000 10355452016 10773221376 1414224864 14.721 100 68 134 0 1457859856 27734320 15900672
20:49:29 6887 8192000000 10356275360 10778914816 1417945696 14.754 100 67 134 0 1465735552 31098240 16691616
20:50:41 12470 8192000000 10337764960 10761555968 1384231440 14.454 98 50 100 0 1428829360 31228416 13369504
20:52:01 2459 8192000000 10355627312 10773626880 1406113808 14.649 100 77 152 0 1458073616 33576048 18383760
20:53:08 1317 8192000000 10368561664 10780737536 1402257824 14.615 100 95 192 0 1464183472 39258320 22667328
20:54:13 3230 8192000000 10349257040 10766254080 1397218304 14.570 100 74 145 3 1454932592 39094512 18619776
20:55:24 8586 8192000000 10346109472 10757058560 1391178416 14.516 100 63 123 1 1445407648 39402864 14826368
20:56:37 4404 8192000000 10335809232 10748997632 1382803120 14.442 100 61 120 0 1443902208 45707888 15391200
20:57:48 21848 8192000000 10326358144 10749812736 1371433680 14.340 99 40 79 0 1435132800 52526976 11172144
20:59:14 7749 8192000000 10346043008 10769166336 1384789984 14.459 100 49 98 0 1450333552 52231360 13312208
21:00:24 9589 8192000000 10339315360 10767147008 1385352672 14.464 100 50 100 0 1449928880 51533456 13042752
21:01:47 24778 8192000000 10314512544 10746155008 1364787792 14.280 99 33 66 2 1414492448 40652656 9052000
21:03:22 31147 8192000000 10286067344 10719039488 1344207472 14.095 98 21 41 0 1386044480 35979104 5857904
21:04:54 27988 8192000000 10272419264 10708017152 1340219024 14.059 99 21 42 0 1379750992 33692272 5839696
21:06:23 25408 8192000000 10283821600 10718638080 1347516032 14.125 100 20 40 0 1389957616 37074496 5367088
21:09:31 81831 8192000000 10292500768 10721525760 1361790768 14.253 99 30 60 0 1382761152 13979008 6991376
21:11:54 65226 8192000000 10268089408 10697592832 1337386480 14.034 97 32 61 1 1350448960 6356816 6705664
21:14:02 34140 8192000000 10285590624 10712367104 1359421280 14.232 99 47 94 2 1380249920 10015680 10812960
21:15:47 51067 8192000000 10287443600 10730455040 1380593216 14.422 100 18 37 0 1391642128 7224208 3824704
21:18:14 62512 8192000000 10286061088 10747531264 1361066016 14.247 98 9 18 1 1374568464 11416880 2085568
21:20:53 60471 8192000000 10299852768 10751266816 1388512560 14.493 99 19 38 0 1407750256 13953024 5284672
21:23:17 93154 8192000000 10275929088 10738032640 1350341920 14.151 97 14 28 0 1365369840 11750976 3276944
21:25:52 66506 8192000000 10296235360 10755018752 1391982064 14.524 100 18 36 0 1403936112 7712912 4241136
21:27:58 62939 8192000000 10294821024 10772402176 1400199872 14.597 100 14 28 0 1415286800 11689936 3396992
21:30:09 58779 8192000000 10296925344 10756706304 1397567344 14.573 100 16 32 0 1411147808 9922224 3658240
21:32:10 51615 8192000000 10315347056 10769666048 1399696176 14.592 100 19 38 0 1412838816 8426224 4716416
21:34:02 72809 8192000000 10294679840 10752303104 1342661200 14.081 95 21 41 1 1363379440 15620624 5097616
21:36:18 48453 8192000000 10288400928 10749448192 1377259024 14.392 99 22 44 0 1395280096 13207904 4813168
21:38:09 40379 8192000000 10287463248 10742734848 1347298688 14.123 97 23 46 0 1371368336 18924512 5145136
21:39:55 94246 8192000000 10294435424 10749247488 1338762832 14.046 96 8 16 0 1355795952 15647424 1385696
21:42:29 84610 8192000000 10263583456 10719453184 1345356832 14.106 97 30 60 0 1360816768 8537552 6922384
21:44:59 32249 8192000000 10276010320 10720178176 1362413232 14.259 100 40 78 0 1390785680 18523584 9848864
21:46:49 51058 8192000000 10261842176 10725699584 1375116352 14.373 100 13 26 0 1390790864 12796000 2878512
21:48:40 39480 8192000000 10243146016 10695172096 1319339584 13.871 96 6 12 0 1330489792 9823248 1326960
21:50:23 49989 8192000000 10265758080 10730676224 1358961040 14.228 99 12 24 0 1376536304 15014528 2560736
21:52:30 20933 8192000000 10243477456 10690973696 1334164640 14.005 99 16 31 0 1348500640 10854880 3481120
21:53:52 82985 8192000000 10259890896 10710913024 1340309920 14.060 98 27 52 1 1356169136 10019232 5839984
21:56:20 53213 8192000000 10261690288 10704564224 1330521600 13.972 98 22 44 0 1353688640 18246432 4920608
21:58:16 74013 8192000000 10274521968 10734436352 1373325488 14.357 100 10 20 0 1389458736 13591680 2541568
22:00:31 21004 8192000000 10281191584 10715082752 1351792688 14.164 99 44 88 0 1373069968 10701408 10575872
22:01:52 89882 8192000000 10267807248 10710712320 1298734816 13.684 95 10 20 0 1323802960 23247712 1820432
22:04:27 122602 8192000000 10260912000 10707873792 1337275616 14.033 98 13 26 0 1345996512 6044304 2676592
22:07:32 58819 8192000000 10260619664 10708484096 1344784496 14.101 98 15 28 0 1355972576 7780272 3407808
22:09:44 59070 8192000000 10277895392 10736222208 1361037120 14.247 99 9 18 0 1374655280 11658512 1959648
22:11:58 53519 8192000000 10291282368 10744188928 1374457072 14.367 99 24 48 0 1398784704 18194496 6133136
22:13:51 51406 8192000000 10278067776 10738020352 1370576176 14.332 99 12 24 1 1383961488 10617488 2767824
22:15:43 35213 8192000000 10295076416 10746892288 1364015392 14.273 98 24 48 1 1380726880 11217680 5493808
22:17:20 84878 8192000000 10297479152 10761711616 1358420592 14.223 98 11 22 0 1381169792 20122688 2626512
22:20:04 74794 8192000000 10303705056 10769121280 1353719632 14.181 97 12 24 0 1372894320 16642640 2532048
22:22:19 112260 8192000000 10284425984 10731589632 1331903264 13.984 96 21 42 0 1350986016 14526336 4556416
22:25:13 94725 8192000000 10294179664 10740428800 1354003152 14.183 98 27 54 0 1374084672 13979648 6101872
22:27:50 54962 8192000000 10265595712 10729824256 1350905216 14.156 98 13 26 0 1362251376 8582560 2763600
22:29:45 55605 8192000000 10263420624 10710016000 1298508512 13.682 94 14 25 0 1308038784 6793680 2736592
22:32:01 63754 8192000000 10276405456 10734055424 1351784096 14.164 98 12 24 0 1366068208 11751392 2532720
22:34:06 48808 8192000000 10248770832 10714320896 1314257488 13.825 96 11 22 0 1328282752 10852992 3172272
22:35:55 43364 8192000000 10267798080 10718384128 1329432896 13.962 97 18 36 0 1348994336 15352608 4208832
22:37:41 54623 8192000000 9273739536 9560698880 399617472 4.651 30 3 6 0 405933552 5604224 711856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-10-2022 22:39:50 to 13-10-2022 22:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-10-2022 22:40:06 to 13-10-2022 22:40:08.

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          242856
MON$OLDEST_ACTIVE               242857
MON$OLDEST_SNAPSHOT             242857
MON$NEXT_TRANSACTION            242857
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                       2036704
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
--- end of diff output ---

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

13.10.22 22:40:09. 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-13 22:40:12 No severe PSQL-related problems occured
13.10.22 22:40:13. End of report.