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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15500928 kB
MemAvailable 30497632 kB
Buffers 0 kB
Cached 16244648 kB
SwapCached 2080 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-23 16:38:26
DTS_END                         2022-10-23 16:38:26.2910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35456
TRN_ID                          250996
ATT_ID                          708
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: 9216.12 23963 1105934
customer order: creation 182.18 343 21861
customer order: refuse 15.69 381 1883
order to supplier: creation 49.18 1232 5902
order to supplier: removal 3.53 92242 424
invoice (draft): creation 48.83 3443 5859
invoice (draft): removal 51.25 789 6150
invoice accept: apply 43.93 17415 5272
invoice accept: cancel 46.08 827 5529
customer reserve: creation 3970.42 176 476450
customer reserve: removal 4496.70 56 539604
realization accept: apply 88.94 162 10673
realization accept: cancel 80.58 112 9669
payment from customer: creation 63.83 99 7660
payment from customer: removal 6.21 74 745
payment to supplier: creation 52.12 131 6254
payment to supplier: removal 3.63 73 435
service: total inventory turnovers 5.28 1108 634
service: total monetary turnovers 7.02 28 842
service: aggregate perf. data 0.70 27441 84
service: refresh index statistics 0.03 333127 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-10-2022 16:40:36 to 23-10-2022 16:40:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4658
TEST_TIME 2 4930
TEST_TIME 3 5334
TEST_TIME 4 5589
TEST_TIME 5 5896
TEST_TIME 6 5916
TEST_TIME 7 5797
TEST_TIME 8 5753
TEST_TIME 9 5752
TEST_TIME 10 5708
TEST_TIME 11 6094
TEST_TIME 12 6099
TEST_TIME 13 6138
TEST_TIME 14 6497
TEST_TIME 15 6504
TEST_TIME 16 6959
TEST_TIME 17 7053
TEST_TIME 18 7157
TEST_TIME 19 7874
TEST_TIME 20 8201
TEST_TIME 21 8404
TEST_TIME 22 8275
TEST_TIME 23 8921
TEST_TIME 24 9242
TEST_TIME 25 9350
TEST_TIME 26 9679
TEST_TIME 27 9705
TEST_TIME 28 9696
TEST_TIME 29 10823
TEST_TIME 30 10650
TEST_TIME 31 10672
TEST_TIME 32 10468
TEST_TIME 33 10333
TEST_TIME 34 10156
TEST_TIME 35 10115
TEST_TIME 36 10154
TEST_TIME 37 10017
TEST_TIME 38 9960
TEST_TIME 39 9899
TEST_TIME 40 10010
TEST_TIME 41 9951
TEST_TIME 42 9848
TEST_TIME 43 9740
TEST_TIME 44 9664
TEST_TIME 45 9619
TEST_TIME 46 9447
TEST_TIME 47 9592
TEST_TIME 48 9649
TEST_TIME 49 9756
TEST_TIME 50 9672
TEST_TIME 51 9771
TEST_TIME 52 9772
TEST_TIME 53 9783
TEST_TIME 54 9776
TEST_TIME 55 9728
TEST_TIME 56 9729
TEST_TIME 57 9872
TEST_TIME 58 9823
TEST_TIME 59 9924
TEST_TIME 60 9808
TEST_TIME 61 9679
TEST_TIME 62 9658
TEST_TIME 63 9690
TEST_TIME 64 9671
TEST_TIME 65 9608
TEST_TIME 66 9542
TEST_TIME 67 9568
TEST_TIME 68 9579
TEST_TIME 69 9510
TEST_TIME 70 9593
TEST_TIME 71 9604
TEST_TIME 72 9589
TEST_TIME 73 9580
TEST_TIME 74 9582
TEST_TIME 75 9609
TEST_TIME 76 9692
TEST_TIME 77 9647
TEST_TIME 78 9673
TEST_TIME 79 9722
TEST_TIME 80 9798
TEST_TIME 81 9816
TEST_TIME 82 9710
TEST_TIME 83 9628
TEST_TIME 84 9562
TEST_TIME 85 9592
TEST_TIME 86 9571
TEST_TIME 87 9516
TEST_TIME 88 9472
TEST_TIME 89 9456
TEST_TIME 90 9398
TEST_TIME 91 9337
TEST_TIME 92 9410
TEST_TIME 93 9387
TEST_TIME 94 9426
TEST_TIME 95 9417
TEST_TIME 96 9440
TEST_TIME 97 9426
TEST_TIME 98 9442
TEST_TIME 99 9444
TEST_TIME 100 9443
TEST_TIME 101 9484
TEST_TIME 102 9457
TEST_TIME 103 9515
TEST_TIME 104 9528
TEST_TIME 105 9548
TEST_TIME 106 9544
TEST_TIME 107 9584
TEST_TIME 108 9497
TEST_TIME 109 9476
TEST_TIME 110 9420
TEST_TIME 111 9474
TEST_TIME 112 9433
TEST_TIME 113 9395
TEST_TIME 114 9386
TEST_TIME 115 9360
TEST_TIME 116 9338
TEST_TIME 117 9329
TEST_TIME 118 9312
TEST_TIME 119 9294
TEST_TIME 120 9221

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-10-2022 16:40:40 to 23-10-2022 16:40: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 1547413 1547413 0 0.00 0 15058 6 0 0
doc_list_biud 1547397 1547397 0 0.00 0 26333 26 0 0
sp_add_invoice_to_stock 5398 5272 126 2.33 49 184091 17415 0 126
sp_cancel_adding_invoice 5529 5529 0 0.00 4 75491 828 0 0
sp_cancel_client_order 1883 1883 0 0.00 4 12231 382 0 0
sp_cancel_customer_reserve 539604 539604 0 0.00 0 26334 56 0 0
sp_cancel_pay_from_customer 745 745 0 0.00 2 5116 74 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 5014 74 0 0
sp_cancel_supplier_invoice 6157 6150 7 0.11 1 24686 790 0 7
sp_cancel_supplier_order 424 424 0 0.00 7 1145781 92243 0 0
sp_cancel_write_off 9669 9669 0 0.00 1 11489 113 0 0
sp_client_order 21861 21861 0 0.00 3 18655 343 0 0
sp_customer_reserve 476450 476450 0 0.00 0 58989 177 0 0
sp_fill_shopping_cart 33616 33616 0 0.00 1 13926 40 0 0
sp_fill_shopping_cart_clo_res 476440 476440 0 0.00 0 13718 14 0 0
sp_get_clo_for_invoice 5262 5262 0 0.00 0 19543 280 0 0
sp_kill_cost_storno 16378 16378 0 0.00 0 10077 51 0 0
sp_kill_qstorno_ret_qs2qd 1095451 1095451 0 0.00 0 26326 31 0 0
sp_kill_qty_storno 579194 579194 0 0.00 0 26332 67 0 0
sp_lock_selected_doc 22134 22134 0 0.00 0 6603 5 0 0
sp_make_cost_storno 29849 29849 0 0.00 0 10389 50 0 0
sp_multiply_rows_for_pdistr 29849 29849 0 0.00 0 4928 26 0 0
sp_multiply_rows_for_qdistr 21861 21861 0 0.00 0 11036 155 0 0
sp_pay_from_customer 7660 7660 0 0.00 4 11881 99 0 0
sp_pay_to_supplier 6254 6254 0 0.00 4 9566 132 0 0
sp_payment_common 13911 13911 0 0.00 2 11872 28 0 0
sp_qd_handle_on_cancel_clo 1883 1883 0 0.00 0 12226 352 0 0
sp_qd_handle_on_invoice_upd_sts 10791 10791 0 0.00 0 22151 336 0 0
sp_qd_handle_on_reserve_upd_sts 10673 10673 0 0.00 0 19582 62 0 0
sp_reserve_write_off 10673 10673 0 0.00 6 19594 162 0 0
sp_supplier_invoice 5859 5859 0 0.00 7 108735 3443 0 0
sp_supplier_order 5902 5902 0 0.00 5 33499 1233 0 0
srv_aggregate_perf_data 84 84 0 0.00 2488 445616 27442 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1810 110451 42591 0 0
srv_find_qd_qs_mism 1055758 1055758 0 0.00 0 15057 6 0 0
srv_make_invnt_saldo 634 634 0 0.00 37 162870 1109 0 0
srv_make_money_saldo 842 842 0 0.00 0 1260 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 152848 431564 333127 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5272 5272 0 0.00 0 7494 162 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 0 5611 91 0 0
t$perf-norm:sp_cancel_client_order 1883 1883 0 0.00 0 8847 22 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 2277 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 745 745 0 0.00 0 1657 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1505 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 4551 19 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 26914 1660 0 0
t$perf-norm:sp_cancel_write_off 1083 1083 0 0.00 0 1524 7 0 0
t$perf-norm:sp_client_order 21861 21861 0 0.00 0 14171 17 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 7055 14 0 0
t$perf-norm:sp_pay_from_customer 7660 7660 0 0.00 0 3871 6 0 0
t$perf-norm:sp_pay_to_supplier 6254 6254 0 0.00 0 2797 7 0 0
t$perf-norm:sp_reserve_write_off 10673 10673 0 0.00 0 5108 10 0 0
t$perf-norm:sp_supplier_invoice 5859 5859 0 0.00 0 6796 16 0 0
t$perf-norm:sp_supplier_order 5902 5902 0 0.00 0 7111 16 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 284 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 504 15 0 0
t$perf-norm:srv_make_invnt_saldo 634 634 0 0.00 0 10371 48 0 0
t$perf-norm:srv_make_money_saldo 842 842 0 0.00 0 8239 32 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 18 6 0 0
v_add_invoice_to_stock 10774 10522 252 2.33 0 6492 16 0 252
v_all_customers 40080 40080 0 0.00 0 20 1 0 0
v_all_suppliers 32510 32510 0 0.00 0 43 1 0 0
v_all_wares 43718 43718 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 2292 9 0 0
v_cancel_client_order 3766 3766 0 0.00 0 2787 7 0 0
v_cancel_customer_prepayment 1490 1490 0 0.00 0 2398 8 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 3521 10 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 976 4 0 0
v_cancel_supplier_invoice 882 868 14 1.58 0 1938 17 0 14
v_cancel_supplier_order 848 848 0 0.00 0 2305 10 0 0
v_cancel_write_off 2166 2166 0 0.00 0 1311 14 0 0
v_max_id_clo_ord 5902 5902 0 0.00 0 1067 2 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 1922 9 0 0
v_max_id_ord_sup 5855 5855 0 0.00 0 4921 4 0 0
v_max_non_paid_invoice 1755 1755 0 0.00 0 146 2 0 0
v_max_non_paid_realizn 4061 4061 0 0.00 0 2087 4 0 0
v_min_id_clo_ord 5902 5902 0 0.00 0 3867 12 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 3161 12 0 0
v_min_id_ord_sup 5855 5855 0 0.00 0 5585 8 0 0
v_min_non_paid_invoice 6253 6253 0 0.00 0 9541 21 0 0
v_min_non_paid_realizn 7658 7658 0 0.00 0 11866 19 0 0
v_our_firm 10832 10832 0 0.00 0 20 1 0 0
v_reserve_write_off 21346 21346 0 0.00 0 3527 5 0 0
x_lock_depdocs_on_canc_invoice 11245 11245 0 0.00 0 20314 125 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 19505 459 0 0
x_make_qstorno_1000_1200 5902 5902 0 0.00 2 33492 1143 0 0
x_make_qstorno_1200_2000 5859 5859 0 0.00 3 108725 3307 0 0
x_make_qstorno_2100_3300 476450 476450 0 0.00 0 58987 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2022 16:40:44 to 23-10-2022 16:40: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
14:08:30 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:31 18953 8192000000 9900020464 10212024320 988059392 10.763 100 80 160 0 1015029520 6124672 20845456
14:11:02 13866 8192000000 9941868800 10263638016 1029198656 11.161 100 86 172 0 1058412208 7259088 21954464
14:12:22 14966 8192000000 9986282528 10316267520 1069063696 11.543 100 85 170 0 1099675200 9375968 21235536
14:13:42 9597 8192000000 10016848144 10359844864 1102391328 11.860 100 73 146 0 1130373472 10550080 17432064
14:15:01 4263 8192000000 10071452480 10428424192 1160061680 12.404 100 71 139 0 1187389712 11648496 15679536
14:16:10 6238 8192000000 10110672528 10476814336 1204845296 12.821 100 77 149 0 1233835264 12654448 16335520
14:17:19 2924 8192000000 10158150080 10534543360 1249874672 13.237 100 77 150 1 1278719264 11896000 16948592
14:18:23 1501 8192000000 10195669552 10576584704 1284069264 13.550 100 89 176 1 1316630096 13381216 19179616
14:19:25 2087 8192000000 10210235200 10595606528 1303206608 13.724 100 88 175 1 1334912656 12727248 18978800
14:20:29 1603 8192000000 10215935504 10609655808 1310255888 13.788 100 68 132 2 1334192656 10065968 13870800
14:21:32 4366 8192000000 10196289648 10610135040 1310901024 13.794 100 27 54 0 1324206864 7319792 5986048
14:22:38 8297 8192000000 10210090336 10620645376 1318341616 13.862 100 22 44 0 1329817056 5958208 5517232
14:23:50 9288 8192000000 10208006384 10627735552 1328332720 13.952 100 20 41 0 1337955712 5178064 4444928
14:25:00 14123 8192000000 10215738576 10637254656 1336929040 14.030 100 12 24 0 1346060800 6383856 2747904
14:26:16 9338 8192000000 10253726672 10659725312 1339641472 14.054 100 63 123 0 1360947776 6382848 14923456
14:27:27 6383 8192000000 10222827792 10644365312 1333180240 13.996 100 22 43 0 1343222512 5211280 4830992
14:28:34 8557 8192000000 10192342272 10607022080 1275121616 13.468 97 19 38 0 1285345616 6150160 4073840
14:29:46 31545 8192000000 10197028624 10594021376 1273412256 13.453 98 64 128 1 1300385520 11446224 15527040
14:31:18 6969 8192000000 10159679072 10565578752 1241449872 13.160 97 25 50 0 1252796944 5141792 6205280
14:32:28 8545 8192000000 10128029296 10532466688 1214303488 12.909 97 20 40 1 1224947728 5945856 4698384
14:33:37 62311 8192000000 10160060784 10553909248 1217799280 12.941 96 28 56 0 1230021840 5533056 6689504
14:35:40 7837 8192000000 10177732176 10589114368 1289462144 13.599 100 28 56 1 1302384864 6765056 6157664
14:36:49 9881 8192000000 10194132224 10608193536 1301798784 13.712 100 22 43 1 1311958960 5278512 4881664
14:37:59 10224 8192000000 10214697424 10629263360 1321155408 13.887 100 15 30 1 1329274592 5015056 3104128
14:39:11 50794 8192000000 10246468224 10663272448 1341397872 14.070 100 38 77 0 1356279344 6088624 8792848
14:41:05 34386 8192000000 10271504960 10693427200 1364205792 14.275 100 47 94 0 1384497152 9563696 10727664
14:42:50 7706 8192000000 10288801104 10714730496 1380961520 14.425 100 43 85 3 1407061392 16132832 9967040
14:44:03 7836 8192000000 10287030304 10709983232 1366557952 14.296 99 44 86 0 1390327888 13941952 9827984
14:45:16 11306 8192000000 10296129136 10721935360 1372918608 14.353 99 43 85 1 1398424832 15931936 9574288
14:46:30 11072 8192000000 10308489760 10736635904 1394725168 14.548 100 51 102 0 1428416528 21223344 12468016
14:48:02 6309 8192000000 10314622752 10742652928 1397710560 14.575 100 50 97 1 1431611808 22277152 11624096
14:49:14 1982 8192000000 10332818608 10750029824 1397209152 14.570 100 77 153 0 1443289520 29255776 16824592
14:50:19 7521 8192000000 10314448880 10733015040 1383141696 14.445 100 49 98 1 1426042368 30963072 11937600
14:51:32 7894 8192000000 10336266080 10753208320 1393203728 14.534 100 75 149 1 1447993824 36230640 18559456
14:52:46 1809 8192000000 10343521504 10755825664 1396630064 14.565 100 74 148 0 1450857232 36351776 17875392
14:53:59 2745 8192000000 10334004096 10742398976 1370170896 14.329 99 85 169 3 1434695296 43038224 21486176
14:55:10 2341 8192000000 10310660592 10715508736 1348955184 14.138 99 64 128 1 1407396320 41740704 16700432
14:56:19 2630 8192000000 10344895392 10754048000 1386783328 14.477 100 69 135 0 1450531552 46674768 17073456
14:57:28 2924 8192000000 10326913680 10728824832 1361559040 14.251 100 87 174 1 1431319392 48101072 21659280
14:58:37 3270 8192000000 10330301136 10739290112 1372484320 14.349 100 74 146 0 1434143488 42947744 18711424
14:59:42 13925 8192000000 10336874672 10761355264 1379635888 14.413 100 34 65 1 1440390464 51372160 9382416
15:01:07 6262 8192000000 10359726304 10788274176 1390886752 14.514 100 50 98 1 1463890640 58662960 14340928
15:02:15 11311 8192000000 10343657760 10769252352 1378427232 14.402 99 44 85 0 1438949136 48931664 11590240
15:03:53 26111 8192000000 10323232912 10760458240 1373877312 14.362 99 27 53 0 1418056992 36281344 7898336
15:05:25 28705 8192000000 10305859184 10748788736 1383496096 14.448 100 19 38 0 1422416688 33318352 5602240
15:07:12 41067 8192000000 10240053376 10680471552 1292289584 13.625 96 13 26 0 1332580000 36224544 4065872
15:08:58 56948 8192000000 10277161904 10725421056 1358407984 14.223 99 26 51 2 1378288896 14619616 5261296
15:10:55 86761 8192000000 10302864016 10753150976 1395855360 14.558 100 28 58 0 1412058096 9184224 7018512
15:13:49 102657 8192000000 10303225472 10766254080 1403989744 14.631 100 21 40 0 1423496368 14546048 4960576
15:16:34 33514 8192000000 10303230144 10760273920 1398179840 14.579 100 29 58 0 1418129824 13579696 6370288
15:18:16 84764 8192000000 10300394864 10775941120 1388009776 14.488 99 16 32 0 1408824976 17406944 3408256
15:20:57 49279 8192000000 10292188496 10752942080 1384976176 14.461 99 7 14 0 1398433680 11740512 1716992
15:22:46 26792 8192000000 10294747184 10751856640 1381804992 14.433 99 8 15 0 1395662224 12272240 1584992
15:24:14 39203 8192000000 10312714080 10764189696 1376209360 14.383 98 19 37 0 1403608304 23232112 4166832
15:26:01 62730 8192000000 10310966352 10778562560 1378943872 14.407 98 15 30 1 1402526480 20041888 3540720
15:28:07 36868 8192000000 10308571648 10761646080 1397930896 14.577 100 27 52 0 1416273536 12838944 5503696
15:29:48 40697 8192000000 10302940368 10759319552 1365440016 14.286 98 13 25 0 1383975888 15765696 2770176
15:31:29 59916 8192000000 10283007168 10733477888 1340494160 14.062 97 24 48 1 1355025472 9053056 5478256
15:33:30 10552 8192000000 10305802656 10748153856 1388598816 14.493 100 59 113 2 1410359040 8060688 13699536
15:34:44 79273 8192000000 10281431232 10739642368 1311354768 13.798 95 19 38 0 1329183248 13744528 4083952
15:37:14 68331 8192000000 10268801248 10703331328 1279092720 13.505 93 12 24 0 1293826176 12035296 2698160
15:39:24 68647 8192000000 10259708928 10702204928 1334755312 14.010 99 45 90 0 1361670528 16660128 10255088
15:41:56 58889 8192000000 10238728080 10702798848 1318270784 13.861 98 12 24 0 1337102752 16350704 2481264
15:43:55 74720 8192000000 10221601824 10679377920 1301346816 13.707 96 10 19 0 1313731568 10283152 2101600
15:46:52 49905 8192000000 10275705168 10719014912 1355461824 14.197 99 34 65 1 1371579968 8877456 7240688
15:48:42 55042 8192000000 10270317168 10717749248 1356525312 14.206 100 14 28 0 1372072672 12039008 3508352
15:50:39 28939 8192000000 10250618128 10696773632 1318415008 13.862 97 18 36 0 1329365824 7415504 3535312
15:52:13 23631 8192000000 10277106400 10708967424 1332051344 13.986 98 48 96 1 1358486208 16093024 10341840
15:53:36 78691 8192000000 10262957152 10720980992 1338559456 14.044 99 13 26 0 1355279888 14115952 2604480
15:55:56 49682 8192000000 10296023120 10734903296 1358042032 14.220 99 26 51 0 1378044464 14311264 5691168
15:57:47 80098 8192000000 10277522912 10731044864 1330063296 13.968 97 9 18 0 1359994592 27829600 2101696
16:00:08 59353 8192000000 10278263168 10725052416 1370146192 14.328 100 13 26 0 1386612160 13290416 3175552
16:02:32 6657 8192000000 10282378112 10723373056 1360387824 14.241 99 38 75 2 1375330640 5857072 9085744
16:03:56 44395 8192000000 10256383408 10713632768 1362078464 14.256 99 8 16 0 1371028752 7179648 1770640
16:05:43 68129 8192000000 10272483216 10730205184 1359444192 14.232 98 8 16 0 1368193296 6407536 2341568
16:07:51 43948 8192000000 10282848032 10733064192 1365455376 14.286 98 23 45 2 1379136880 8283872 5397632
16:09:47 67697 8192000000 10280383024 10748071936 1377789072 14.397 99 24 48 0 1398866992 15513296 5564624
16:12:12 77486 8192000000 10269675024 10723270656 1328894432 13.957 95 14 28 0 1342290976 10337616 3058928
16:14:36 45984 8192000000 10277498016 10727780352 1320187424 13.878 95 21 42 0 1333610352 8594608 4828320
16:16:22 23094 8192000000 10287896368 10736521216 1367383824 14.304 99 28 56 0 1382894800 9380752 6130224
16:17:46 102642 8192000000 10274880912 10724581376 1307286528 13.761 94 15 30 0 1324122960 13322528 3513904
16:20:29 33069 8192000000 10292747776 10731933696 1363837424 14.272 99 29 58 0 1379886592 10101104 5948064
16:22:04 110451 8192000000 10274950064 10723299328 1270933952 13.430 92 14 28 0 1286525760 12507936 3083872
16:25:06 93590 8192000000 10281024000 10750603264 1356236336 14.204 99 5 10 0 1381250896 24124176 890384
16:28:13 55638 8192000000 10272872896 10724528128 1358414096 14.223 99 16 32 1 1376277568 14326912 3536560
16:30:08 41846 8192000000 10241825264 10704396288 1323621904 13.909 97 9 17 0 1336425456 11012624 1790928
16:33:01 34087 8192000000 10271155680 10721542144 1357602848 14.216 99 21 40 0 1369026592 6900672 4523072
16:34:35 89391 8192000000 10259301808 10716397568 1347156624 14.122 99 26 52 0 1362844352 9474016 6213712
16:37:14 74460 8192000000 10157699472 10600271872 1234845200 13.099 90 8 15 0 1246404400 9984944 1574256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-10-2022 16:40:50 to 23-10-2022 16:41:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2022 16:41:06 to 23-10-2022 16:41: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          249064
MON$OLDEST_ACTIVE               251149
MON$OLDEST_SNAPSHOT             251149
MON$NEXT_TRANSACTION            251149
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                       2024000
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.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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'

23.10.22 16:41:10. 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-23 16:41:12 No severe PSQL-related problems occured
23.10.22 16:41:13. End of report.