Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.02.2023 07:37
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 14913604 kB
MemAvailable 30378696 kB
Buffers 0 kB
Cached 16763788 kB
SwapCached 3568 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2023-02-06 07:37:23
DTS_END                         2023-02-06 07:37:23.9350
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(68:17)
IP                              ::1/38484
TRN_ID                          216718
ATT_ID                          618
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: 8555.44 22444 1026653
customer order: creation 171.78 507 20613
customer order: refuse 14.37 484 1724
order to supplier: creation 45.68 1464 5481
order to supplier: removal 3.26 172699 391
invoice (draft): creation 45.63 3748 5475
invoice (draft): removal 46.19 1137 5543
invoice accept: apply 41.47 29252 4976
invoice accept: cancel 41.93 1564 5031
customer reserve: creation 3757.30 283 450876
customer reserve: removal 4109.13 107 493096
realization accept: apply 82.58 311 9909
realization accept: cancel 67.60 190 8112
payment from customer: creation 58.35 201 7002
payment from customer: removal 5.78 157 693
payment to supplier: creation 48.71 221 5845
payment to supplier: removal 3.35 140 402
service: total inventory turnovers 3.76 4489 451
service: total monetary turnovers 7.63 424 915
service: aggregate perf. data 0.93 15610 112
service: refresh index statistics 0.05 215895 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-02-2023 07:38:19 to 06-02-2023 07:38:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 14071
TEST_TIME 2 9669
TEST_TIME 3 8705
TEST_TIME 4 8216
TEST_TIME 5 7817
TEST_TIME 6 7545
TEST_TIME 7 7461
TEST_TIME 8 7413
TEST_TIME 9 7277
TEST_TIME 10 7564
TEST_TIME 11 7483
TEST_TIME 12 7361
TEST_TIME 13 7494
TEST_TIME 14 7457
TEST_TIME 15 7313
TEST_TIME 16 7412
TEST_TIME 17 7417
TEST_TIME 18 7666
TEST_TIME 19 7721
TEST_TIME 20 7833
TEST_TIME 21 8008
TEST_TIME 22 8176
TEST_TIME 23 8106
TEST_TIME 24 8174
TEST_TIME 25 8357
TEST_TIME 26 8530
TEST_TIME 27 8667
TEST_TIME 28 8611
TEST_TIME 29 8640
TEST_TIME 30 8656
TEST_TIME 31 8493
TEST_TIME 32 8473
TEST_TIME 33 8377
TEST_TIME 34 8573
TEST_TIME 35 8852
TEST_TIME 36 9334
TEST_TIME 37 9528
TEST_TIME 38 9802
TEST_TIME 39 10003
TEST_TIME 40 10325
TEST_TIME 41 10391
TEST_TIME 42 10215
TEST_TIME 43 9984
TEST_TIME 44 9960
TEST_TIME 45 10029
TEST_TIME 46 9954
TEST_TIME 47 9906
TEST_TIME 48 9826
TEST_TIME 49 9799
TEST_TIME 50 9653
TEST_TIME 51 9623
TEST_TIME 52 9539
TEST_TIME 53 9533
TEST_TIME 54 9446
TEST_TIME 55 9348
TEST_TIME 56 9214
TEST_TIME 57 9206
TEST_TIME 58 9087
TEST_TIME 59 8998
TEST_TIME 60 8940
TEST_TIME 61 8943
TEST_TIME 62 8889
TEST_TIME 63 8886
TEST_TIME 64 8832
TEST_TIME 65 8717
TEST_TIME 66 8728
TEST_TIME 67 8823
TEST_TIME 68 8766
TEST_TIME 69 8863
TEST_TIME 70 8913
TEST_TIME 71 8884
TEST_TIME 72 8852
TEST_TIME 73 8813
TEST_TIME 74 8850
TEST_TIME 75 8886
TEST_TIME 76 8939
TEST_TIME 77 8889
TEST_TIME 78 8913
TEST_TIME 79 8904
TEST_TIME 80 9001
TEST_TIME 81 8911
TEST_TIME 82 8881
TEST_TIME 83 8930
TEST_TIME 84 8852
TEST_TIME 85 8843
TEST_TIME 86 8786
TEST_TIME 87 8718
TEST_TIME 88 8692
TEST_TIME 89 8647
TEST_TIME 90 8726
TEST_TIME 91 8744
TEST_TIME 92 8726
TEST_TIME 93 8761
TEST_TIME 94 8740
TEST_TIME 95 8731
TEST_TIME 96 8721
TEST_TIME 97 8733
TEST_TIME 98 8791
TEST_TIME 99 8766
TEST_TIME 100 8828
TEST_TIME 101 8846
TEST_TIME 102 8831
TEST_TIME 103 8831
TEST_TIME 104 8769
TEST_TIME 105 8716
TEST_TIME 106 8696
TEST_TIME 107 8655
TEST_TIME 108 8615
TEST_TIME 109 8581
TEST_TIME 110 8522
TEST_TIME 111 8514
TEST_TIME 112 8534
TEST_TIME 113 8513
TEST_TIME 114 8530
TEST_TIME 115 8586
TEST_TIME 116 8588
TEST_TIME 117 8558
TEST_TIME 118 8653
TEST_TIME 119 8610
TEST_TIME 120 8590

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-02-2023 07:38:23 to 06-02-2023 07:38:26.

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 1442487 1442487 0 0.00 0 19725 15 0 0
doc_list_biud 1442473 1442473 0 0.00 0 52147 44 0 0
sp_add_invoice_to_stock 5099 4976 123 2.41 25 223029 29252 0 123
sp_cancel_adding_invoice 5031 5031 0 0.00 8 93319 1564 0 0
sp_cancel_client_order 1724 1724 0 0.00 6 12152 484 0 0
sp_cancel_customer_reserve 493096 493096 0 0.00 1 31750 108 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 4 3633 158 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 4 3212 140 0 0
sp_cancel_supplier_invoice 5552 5543 9 0.16 3 52156 1137 0 9
sp_cancel_supplier_order 391 391 0 0.00 7 1335290 172699 0 0
sp_cancel_write_off 8112 8112 0 0.00 2 17013 191 0 0
sp_client_order 20613 20613 0 0.00 5 26554 508 0 0
sp_customer_reserve 450876 450876 0 0.00 1 52848 283 0 0
sp_fill_shopping_cart 31558 31558 0 0.00 1 30864 96 0 0
sp_fill_shopping_cart_clo_res 450844 450844 0 0.00 0 16740 21 0 0
sp_get_clo_for_invoice 4931 4931 0 0.00 0 24980 306 0 0
sp_kill_cost_storno 14238 14238 0 0.00 0 15220 91 0 0
sp_kill_qstorno_ret_qs2qd 1000238 1000238 0 0.00 0 52143 48 0 0
sp_kill_qty_storno 528736 528736 0 0.00 0 52146 110 0 0
sp_lock_selected_doc 20444 20444 0 0.00 0 6792 7 0 0
sp_make_cost_storno 27687 27687 0 0.00 0 18167 85 0 0
sp_multiply_rows_for_pdistr 27686 27686 0 0.00 0 9866 46 0 0
sp_multiply_rows_for_qdistr 20613 20613 0 0.00 0 26516 189 0 0
sp_pay_from_customer 7002 7002 0 0.00 5 19730 201 0 0
sp_pay_to_supplier 5845 5845 0 0.00 7 18216 222 0 0
sp_payment_common 12847 12847 0 0.00 2 12492 52 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 1 12140 408 0 0
sp_qd_handle_on_invoice_upd_sts 9961 9961 0 0.00 0 40022 611 0 0
sp_qd_handle_on_reserve_upd_sts 9909 9909 0 0.00 0 15026 71 0 0
sp_reserve_write_off 9909 9909 0 0.00 8 19481 312 0 0
sp_supplier_invoice 5475 5475 0 0.00 9 115220 3749 0 0
sp_supplier_order 5481 5481 0 0.00 7 77838 1465 0 0
srv_aggregate_perf_data 112 112 0 0.00 2241 159090 15611 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 702 102843 31452 0 0
srv_find_qd_qs_mism 978652 978652 0 0.00 0 18558 12 0 0
srv_make_invnt_saldo 451 451 0 0.00 29 56150 4489 0 0
srv_make_money_saldo 915 915 0 0.00 1 10127 424 0 0
srv_recalc_idx_stat 6 6 0 0.00 37700 377379 215896 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4985 4985 0 0.00 1 13634 1165 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 2 6929 610 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 4424 45 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 834 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 708 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 1362 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 806 35 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 94088 7512 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 5192 33 0 0
t$perf-norm:sp_client_order 20614 20614 0 0.00 0 11246 44 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 1373 17 0 0
t$perf-norm:sp_pay_from_customer 7002 7002 0 0.00 0 4974 21 0 0
t$perf-norm:sp_pay_to_supplier 5845 5845 0 0.00 0 5278 27 0 0
t$perf-norm:sp_reserve_write_off 9910 9910 0 0.00 0 7721 30 0 0
t$perf-norm:sp_supplier_invoice 5475 5475 0 0.00 0 7141 39 0 0
t$perf-norm:sp_supplier_order 5481 5481 0 0.00 0 7093 41 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 1370 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 354 12 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 758 16 0 0
t$perf-norm:srv_make_money_saldo 915 915 0 0.00 0 1648 9 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 8 354 130 0 0
v_add_invoice_to_stock 10087 9841 246 2.43 0 12265 29 0 246
v_all_customers 37254 37254 0 0.00 0 70 4 0 0
v_all_suppliers 30425 30425 0 0.00 0 61 5 0 0
v_all_wares 41222 41222 0 0.00 0 77 4 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 2713 19 0 0
v_cancel_client_order 3448 3448 0 0.00 0 1326 10 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 3091 17 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 1 3447 18 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 2685 18 0 0
v_cancel_supplier_invoice 740 722 18 2.43 0 2870 28 0 18
v_cancel_supplier_order 782 782 0 0.00 0 694 13 0 0
v_cancel_write_off 1892 1892 0 0.00 0 16759 34 0 0
v_max_id_clo_ord 5478 5478 0 0.00 0 1401 6 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 1919 14 0 0
v_max_id_ord_sup 5468 5468 0 0.00 0 3694 7 0 0
v_max_non_paid_invoice 1578 1578 0 0.00 0 876 9 0 0
v_max_non_paid_realizn 3780 3780 0 0.00 0 4906 9 0 0
v_min_id_clo_ord 5478 5478 0 0.00 0 1054 9 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 11698 33 0 0
v_min_id_ord_sup 5468 5468 0 0.00 0 10644 16 0 0
v_min_non_paid_invoice 5844 5844 0 0.00 0 7490 18 0 0
v_min_non_paid_realizn 7002 7002 0 0.00 0 12488 18 0 0
v_our_firm 10412 10412 0 0.00 0 90 5 0 0
v_reserve_write_off 19818 19818 0 0.00 0 15993 16 0 0
x_lock_depdocs_on_canc_invoice 10213 10213 0 0.00 0 12222 142 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 5729 396 0 0
x_make_qstorno_1000_1200 5481 5481 0 0.00 3 77817 1276 0 0
x_make_qstorno_1200_2000 5475 5475 0 0.00 5 114990 3468 0 0
x_make_qstorno_2100_3300 450876 450876 0 0.00 0 52840 247 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 06-02-2023 07:38:27 to 06-02-2023 07:38:32.

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
05:07:28 8 8192000000 8807782848 8822710272 11942736 0.145 4 4 4 0 12205504 175824 86944
05:08:30 20361 8192000000 9721201632 10021941248 805930976 8.956 100 86 173 0 833942320 7090224 20921120
05:09:57 17310 8192000000 9774457616 10082770944 846006992 9.360 100 90 178 0 878748832 11576816 21165024
05:11:20 15999 8192000000 9803248304 10121789440 872363216 9.624 100 87 174 0 911295472 18461840 20470416
05:12:53 12279 8192000000 9868778224 10207383552 923478496 10.130 100 82 164 1 984800768 43392416 17929856
05:14:15 4727 8192000000 9913982272 10258735104 962120576 10.510 100 84 168 1 1033190976 53427328 17643072
05:15:23 3745 8192000000 9976195760 10329882624 993441808 10.815 100 94 184 0 1088880544 76682176 18756560
05:16:29 1630 8192000000 9993943216 10352844800 1012447088 10.999 100 88 161 0 1111607248 83048768 16111392
05:17:33 1684 8192000000 10017235664 10386866176 1031595104 11.184 100 93 187 0 1136277296 86396128 18286064
05:18:36 858 8192000000 10008158176 10381221888 1048339264 11.345 100 97 191 0 1132748224 64324224 20084736
05:19:39 1482 8192000000 10052543440 10426703872 1062925408 11.484 100 99 186 1 1173571376 90446336 20199632
05:20:43 1539 8192000000 10077074736 10453032960 1070908256 11.561 100 99 197 0 1194846096 104513072 19424768
05:21:46 1223 8192000000 10037092704 10416951296 1070908240 11.561 100 93 176 1 1154040128 64537120 18594768
05:22:48 2002 8192000000 10046102576 10428477440 1076394464 11.613 100 84 163 2 1165925696 72333328 17197904
05:23:52 1119 8192000000 10053212752 10437267456 1085928304 11.704 100 98 185 2 1174163072 68377760 19857008
05:24:54 1143 8192000000 10055490160 10448257024 1088678688 11.730 100 76 152 0 1178208944 74111024 15419232
05:25:56 2946 8192000000 10044340752 10432278528 1093367360 11.775 100 71 133 1 1160356784 54358256 12631168
05:27:00 1735 8192000000 10054707792 10444451840 1099743824 11.835 100 87 168 0 1177537648 61809472 15984352
05:28:03 1004 8192000000 10022653920 10410848256 1086632896 11.711 99 81 159 3 1148686384 44715856 17337632
05:29:04 1243 8192000000 10056344720 10451374080 1097210960 11.811 100 74 146 2 1176037424 63572096 15254368
05:30:07 1232 8192000000 10047022352 10433560576 1094358720 11.784 100 84 163 2 1167642720 56851392 16432608
05:31:09 1010 8192000000 10046146240 10428235776 1072584784 11.577 100 92 175 1 1170081696 78259824 19237088
05:32:10 1078 8192000000 10022581120 10409607168 1063838368 11.493 100 86 157 3 1145254880 65183856 16232656
05:33:12 1835 8192000000 10006412048 10387947520 1064033168 11.495 99 79 150 0 1126456976 46570336 15853472
05:34:15 1163 8192000000 10028682768 10407186432 1062015680 11.476 100 94 176 0 1151236128 70789120 18431328
05:35:17 2567 8192000000 10006804976 10384601088 1041055840 11.275 99 72 133 3 1123033376 68119088 13858448
05:36:21 1174 8192000000 9973334256 10339102720 1019149632 11.064 100 91 170 7 1097205856 62028912 16027312
05:37:23 1081 8192000000 10007723936 10379165696 1041801040 11.282 100 89 174 0 1130311200 71386256 17123904
05:38:30 1545 8192000000 10034355328 10417479680 1069872320 11.551 100 77 148 0 1160108976 75713808 14522848
05:39:39 8711 8192000000 10044548160 10427351040 1079738480 11.645 100 79 149 1 1165593792 71107040 14748272
05:40:56 3521 8192000000 10063621488 10449207296 1082653728 11.673 100 88 160 1 1183078768 85389552 15035488
05:42:08 8884 8192000000 10088959024 10472128512 1098213504 11.821 100 86 161 1 1201304000 87112928 15977568
05:43:20 3089 8192000000 10075873392 10473762816 1108861440 11.922 100 85 152 2 1194737456 69332336 16543680
05:44:28 1503 8192000000 10109236944 10505879552 1114214208 11.972 100 88 170 0 1236190320 102928384 19047728
05:45:34 5328 8192000000 10133347680 10524246016 1125911056 12.083 100 75 140 2 1248273536 107723024 14639456
05:46:42 950 8192000000 10165867392 10566668288 1136312960 12.181 100 84 154 2 1282434960 130800128 15321872
05:47:47 6082 8192000000 10158559952 10575495168 1144846704 12.261 100 64 125 0 1276346592 117827056 13672832
05:48:57 1741 8192000000 10189138704 10591977472 1159238976 12.396 100 76 148 2 1303030320 125261520 18529824
05:50:03 1842 8192000000 10213413536 10636918784 1170393936 12.501 100 79 153 0 1329409696 141836176 17179584
05:51:09 4473 8192000000 10239836704 10644717568 1172672000 12.522 100 77 152 1 1346472768 156989632 16811136
05:52:19 1350 8192000000 10289067872 10691604480 1179650960 12.587 100 90 169 1 1392900384 195065232 18184192
05:53:26 756 8192000000 10295121344 10698637312 1165435456 12.454 99 91 174 1 1407733504 221834896 20463152
05:54:30 1246 8192000000 10334913552 10752557056 1175978624 12.553 100 96 188 1 1449083200 251328384 21776192
05:55:37 955 8192000000 10357080736 10761428992 1186235024 12.648 100 95 183 2 1464567568 257731312 20601232
05:56:41 1006 8192000000 10355002320 10772336640 1184380800 12.631 100 85 160 0 1464819216 262658848 17779568
05:57:48 692 8192000000 10381343776 10807455744 1193021520 12.711 100 94 187 0 1483913296 269224400 21667376
05:58:52 1135 8192000000 10365668688 10791608320 1183634304 12.624 100 99 196 2 1470738704 264282224 22822176
05:59:58 1742 8192000000 10397297392 10811604992 1195715328 12.737 100 89 174 0 1505108816 287685168 21708320
06:01:03 1308 8192000000 10402240352 10829975552 1176433536 12.557 100 87 172 1 1508424752 311904560 20086656
06:02:08 1463 8192000000 10405570976 10850791424 1180395904 12.594 100 90 173 0 1507924816 307101632 20427280
06:03:14 1278 8192000000 10408890736 10849251328 1193051360 12.712 100 95 185 1 1511391232 297063744 21276128
06:04:18 744 8192000000 10444897680 10865238016 1192496752 12.707 100 95 180 0 1544537696 331498752 20542192
06:05:22 8296 8192000000 10382030944 10823110656 1154079840 12.348 99 58 107 2 1496520912 329481824 12959248
06:06:35 5415 8192000000 10400845440 10845339648 1162386848 12.426 99 52 102 0 1496160192 320575104 13198240
06:07:55 13400 8192000000 10476042032 10901065728 1178723648 12.578 100 46 90 0 1576115216 386020464 11371104
06:09:14 10280 8192000000 10501608464 10956353536 1161984784 12.422 98 44 88 0 1587900976 415004672 10911520
06:10:34 4158 8192000000 10557836784 10997374976 1163748944 12.438 99 60 120 0 1643948688 465359040 14840704
06:11:43 2274 8192000000 10515038112 11021381632 1174088976 12.535 100 59 116 0 1627456880 439098064 14269840
06:12:48 15408 8192000000 10449090864 10939588608 1164615312 12.446 99 52 95 1 1544002080 367258560 12128208
06:14:10 33325 8192000000 10357694896 10852106240 1143991840 12.253 99 24 48 0 1461268640 310699440 6577360
06:15:51 22035 8192000000 10245419024 10719064064 1153381728 12.341 100 45 90 1 1381350208 216864704 11103776
06:17:18 35697 8192000000 10160285888 10633588736 1113915200 11.969 97 25 44 0 1237592864 118913024 4764640
06:19:06 85089 8192000000 10146286528 10578874368 1122696736 12.052 100 45 88 1 1237717552 104454496 10566320
06:21:46 66507 8192000000 10100854640 10547392512 1136221840 12.180 100 40 78 0 1202500240 57109168 9169232
06:23:58 102813 8192000000 10100924784 10600292352 1115466928 11.984 98 17 34 0 1181368416 62080384 3821104
06:28:04 63405 8192000000 10063027312 10533003264 1115594464 11.985 98 36 68 0 1170546576 46851440 8100672
06:30:24 57858 8192000000 10053298560 10519609344 1105628048 11.891 98 6 12 0 1142352560 35420960 1303552
06:32:28 67052 8192000000 10075666272 10521104384 1121600640 12.042 99 20 37 1 1186346736 60772112 3973984
06:34:51 92551 8192000000 10063218880 10537480192 1131243712 12.133 100 21 40 1 1176021008 40757440 4019856
06:37:52 41902 8192000000 10121262048 10588848128 1134796816 12.167 100 39 76 0 1216471520 73012576 8662128
06:40:05 30712 8192000000 10078075888 10564530176 1112722304 11.958 98 19 38 0 1175668704 59159936 3786464
06:41:41 52403 8192000000 10115581984 10581274624 1134914384 12.168 100 13 25 0 1226630704 89136064 2580256
06:43:53 35413 8192000000 10084046688 10579529728 1125149312 12.076 99 10 20 0 1214294576 87086512 2058752
06:45:32 41420 8192000000 10113719296 10562482176 1128766864 12.110 99 17 34 0 1181735760 49625792 3343104
06:47:14 74142 8192000000 10062972160 10529120256 1095692752 11.797 96 15 30 0 1143759168 44822544 3243872
06:49:33 7159 8192000000 10095715856 10520629248 1135303936 12.171 100 48 91 0 1187875200 43279744 9291520
06:50:41 29401 8192000000 10128884096 10586050560 1127418480 12.097 99 60 119 1 1214995856 75947696 11629680
06:52:13 29703 8192000000 10112903040 10576658432 1116728336 11.996 99 42 82 0 1209381184 84633392 8019456
06:53:46 31402 8192000000 10066318752 10501398528 1094753760 11.788 97 40 78 0 1133002464 30305648 7943056
06:55:20 53158 8192000000 10111041568 10583224320 1117097744 12.000 99 30 60 0 1208327664 85214208 6015712
06:57:17 91433 8192000000 10130715360 10607656960 1109077040 11.924 98 17 34 0 1196715472 84348640 3289792
07:00:12 77462 8192000000 10063664816 10544525312 1099311936 11.831 98 26 52 1 1186335856 81399648 5624272
07:02:32 73664 8192000000 10081642560 10540679168 1105402048 11.889 98 14 28 0 1183265424 75387456 2475920
07:05:04 76578 8192000000 10131790752 10607198208 1131763552 12.138 100 19 38 0 1258090624 121855616 4471456
07:07:27 81157 8192000000 10046462544 10496512000 1080130800 11.649 96 21 38 0 1135492448 50878768 4482880
07:09:57 28180 8192000000 10063633168 10522378240 1113440544 11.965 99 35 70 0 1175519824 54790416 7288864
07:11:28 19351 8192000000 10055795648 10475945984 1090102336 11.744 97 60 109 1 1146802256 45367728 11332192
07:12:53 52084 8192000000 10109625136 10542862336 1107717696 11.911 98 31 62 0 1186695520 72368976 6608848
07:14:45 48086 8192000000 10116913664 10559541248 1134683408 12.165 100 22 45 0 1216130704 76756128 4691168
07:16:35 17647 8192000000 10147537488 10578378752 1137236368 12.190 99 45 88 0 1230717648 84657904 8823376
07:17:54 58663 8192000000 10081538336 10510356480 1133041584 12.150 99 30 57 0 1162820832 23526656 6252592
07:19:53 47377 8192000000 10088926784 10540388352 1107557008 11.909 97 41 82 0 1199348112 82639360 9151744
07:21:43 58100 8192000000 10062796576 10493333504 1117054832 11.999 98 40 78 1 1158007120 33039184 7913104
07:23:42 72584 8192000000 10065531104 10514468864 1117559552 12.004 98 15 30 1 1160904448 39921104 3423792
07:26:04 77486 8192000000 10119706096 10590879744 1149257776 12.303 100 9 18 0 1221428400 69911184 2259440
07:28:37 61603 8192000000 10106204352 10559516672 1125212112 12.076 98 16 30 1 1180747728 52647888 2887728
07:30:49 69409 8192000000 10143135760 10591481856 1101043360 11.848 96 24 49 0 1174229392 67502864 5683168
07:32:59 48215 8192000000 10116409872 10557718528 1136839040 12.186 99 27 52 0 1206323184 63891376 5592768
07:34:55 20029 8192000000 10155198448 10610507776 1139800736 12.214 100 40 80 0 1260004432 111771344 8432352
07:36:29 41733 8192000000 10112846864 10559033344 1126637280 12.090 99 27 54 0 1204556384 71675664 6243440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-02-2023 07:38:34 to 06-02-2023 07:38:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-02-2023 07:38:51 to 06-02-2023 07:38:53.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          214780
MON$OLDEST_ACTIVE               217396
MON$OLDEST_SNAPSHOT             217396
MON$NEXT_TRANSACTION            217396
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-30 07:30:08.6030 GMT
MON$PAGES                       2080416
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2895 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2895 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:186: 
:187: mscs-test-161.budzdorov.ru    Mon Feb  6 07:37:25 2023
:188:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:189: 
:190: 
:191: mscs-test-161.budzdorov.ru    Mon Feb  6 07:37:26 2023
:192:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:193: 
--- end of diff output ---

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

06.02.23 07:38:54. There are 3 logs in /var/tmp/logs-oltp40 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
2023-02-06 07:38:57 No severe PSQL-related problems occured
06.02.23 07:38:58. End of report.