Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.06.2022 10:38
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 16201528 kB
MemAvailable 31208464 kB
Buffers 0 kB
Cached 15530988 kB
SwapCached 2500 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.2
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 2022-06-29 10:37:18
DTS_END                         2022-06-29 10:37:18.7610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39856
TRN_ID                          218282
ATT_ID                          647
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: 8565.29 26940 1027835
customer order: creation 171.67 489 20600
customer order: refuse 14.46 482 1735
order to supplier: creation 44.38 1442 5326
order to supplier: removal 3.28 176936 394
invoice (draft): creation 44.24 3094 5309
invoice (draft): removal 48.35 1051 5802
invoice accept: apply 40.26 27837 4831
invoice accept: cancel 42.73 1281 5127
customer reserve: creation 3675.65 270 441078
customer reserve: removal 4190.72 108 502886
realization accept: apply 83.03 295 9963
realization accept: cancel 78.43 182 9411
payment from customer: creation 57.66 179 6919
payment from customer: removal 5.45 166 654
payment to supplier: creation 48.84 199 5861
payment to supplier: removal 3.47 123 416
service: total inventory turnovers 3.87 4041 464
service: total monetary turnovers 7.85 320 942
service: aggregate perf. data 0.93 13733 112
service: refresh index statistics 0.04 306574 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-06-2022 10:38:40 to 29-06-2022 10:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5805
TEST_TIME 2 4208
TEST_TIME 3 4851
TEST_TIME 4 4710
TEST_TIME 5 5025
TEST_TIME 6 5022
TEST_TIME 7 4918
TEST_TIME 8 5119
TEST_TIME 9 5638
TEST_TIME 10 5810
TEST_TIME 11 5899
TEST_TIME 12 6019
TEST_TIME 13 6129
TEST_TIME 14 6199
TEST_TIME 15 6689
TEST_TIME 16 6726
TEST_TIME 17 7001
TEST_TIME 18 7365
TEST_TIME 19 7413
TEST_TIME 20 7500
TEST_TIME 21 7881
TEST_TIME 22 7993
TEST_TIME 23 8333
TEST_TIME 24 8341
TEST_TIME 25 8651
TEST_TIME 26 8655
TEST_TIME 27 8985
TEST_TIME 28 9111
TEST_TIME 29 9346
TEST_TIME 30 9604
TEST_TIME 31 9677
TEST_TIME 32 10320
TEST_TIME 33 10555
TEST_TIME 34 10707
TEST_TIME 35 10501
TEST_TIME 36 10388
TEST_TIME 37 10178
TEST_TIME 38 9978
TEST_TIME 39 9889
TEST_TIME 40 9888
TEST_TIME 41 9826
TEST_TIME 42 9763
TEST_TIME 43 9867
TEST_TIME 44 9725
TEST_TIME 45 9616
TEST_TIME 46 9619
TEST_TIME 47 9514
TEST_TIME 48 9522
TEST_TIME 49 9352
TEST_TIME 50 9384
TEST_TIME 51 9235
TEST_TIME 52 9232
TEST_TIME 53 9368
TEST_TIME 54 9278
TEST_TIME 55 9272
TEST_TIME 56 9197
TEST_TIME 57 9144
TEST_TIME 58 9134
TEST_TIME 59 9190
TEST_TIME 60 9115
TEST_TIME 61 9319
TEST_TIME 62 9290
TEST_TIME 63 9221
TEST_TIME 64 9203
TEST_TIME 65 9197
TEST_TIME 66 9080
TEST_TIME 67 9028
TEST_TIME 68 8972
TEST_TIME 69 8928
TEST_TIME 70 8890
TEST_TIME 71 8836
TEST_TIME 72 8801
TEST_TIME 73 8758
TEST_TIME 74 8708
TEST_TIME 75 8746
TEST_TIME 76 8689
TEST_TIME 77 8722
TEST_TIME 78 8704
TEST_TIME 79 8670
TEST_TIME 80 8681
TEST_TIME 81 8677
TEST_TIME 82 8839
TEST_TIME 83 9025
TEST_TIME 84 9053
TEST_TIME 85 9006
TEST_TIME 86 8985
TEST_TIME 87 8958
TEST_TIME 88 8927
TEST_TIME 89 8945
TEST_TIME 90 8897
TEST_TIME 91 8904
TEST_TIME 92 8874
TEST_TIME 93 8832
TEST_TIME 94 8792
TEST_TIME 95 8748
TEST_TIME 96 8733
TEST_TIME 97 8728
TEST_TIME 98 8686
TEST_TIME 99 8688
TEST_TIME 100 8694
TEST_TIME 101 8676
TEST_TIME 102 8670
TEST_TIME 103 8658
TEST_TIME 104 8712
TEST_TIME 105 8711
TEST_TIME 106 8749
TEST_TIME 107 8740
TEST_TIME 108 8786
TEST_TIME 109 8815
TEST_TIME 110 8789
TEST_TIME 111 8791
TEST_TIME 112 8774
TEST_TIME 113 8732
TEST_TIME 114 8748
TEST_TIME 115 8741
TEST_TIME 116 8684
TEST_TIME 117 8669
TEST_TIME 118 8651
TEST_TIME 119 8643
TEST_TIME 120 8589

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-06-2022 10:38:43 to 29-06-2022 10:38:47.

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 1434417 1434417 0 0.00 0 22527 15 0 0
doc_list_biud 1434417 1434417 0 0.00 0 77223 44 0 0
sp_add_invoice_to_stock 4962 4831 131 2.64 28 260392 27838 0 131
sp_cancel_adding_invoice 5127 5127 0 0.00 6 86033 1281 0 0
sp_cancel_client_order 1735 1735 0 0.00 6 47015 483 0 0
sp_cancel_customer_reserve 502886 502886 0 0.00 1 57795 108 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 7651 167 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 4 4503 123 0 0
sp_cancel_supplier_invoice 5811 5802 9 0.15 3 47051 1052 0 9
sp_cancel_supplier_order 394 394 0 0.00 9 1331892 176937 0 0
sp_cancel_write_off 9411 9411 0 0.00 2 8303 182 0 0
sp_client_order 20600 20600 0 0.00 5 51590 489 0 0
sp_customer_reserve 441078 441078 0 0.00 0 131958 270 0 0
sp_fill_shopping_cart 31231 31231 0 0.00 1 19098 85 0 0
sp_fill_shopping_cart_clo_res 441045 441045 0 0.00 0 18701 21 0 0
sp_get_clo_for_invoice 4798 4798 0 0.00 0 45928 321 0 0
sp_kill_cost_storno 15608 15608 0 0.00 0 22520 83 0 0
sp_kill_qstorno_ret_qs2qd 1021379 1021379 0 0.00 0 47004 48 0 0
sp_kill_qty_storno 540116 540116 0 0.00 0 77222 109 0 0
sp_lock_selected_doc 20441 20441 0 0.00 0 8425 7 0 0
sp_make_cost_storno 27541 27541 0 0.00 0 12489 79 0 0
sp_multiply_rows_for_pdistr 27541 27541 0 0.00 0 12725 39 0 0
sp_multiply_rows_for_qdistr 20600 20600 0 0.00 0 51569 200 0 0
sp_pay_from_customer 6919 6919 0 0.00 5 15465 180 0 0
sp_pay_to_supplier 5861 5861 0 0.00 6 9261 199 0 0
sp_payment_common 12780 12780 0 0.00 2 7839 44 0 0
sp_qd_handle_on_cancel_clo 1735 1735 0 0.00 0 46974 402 0 0
sp_qd_handle_on_invoice_upd_sts 9925 9925 0 0.00 0 77221 560 0 0
sp_qd_handle_on_reserve_upd_sts 9963 9963 0 0.00 0 46868 68 0 0
sp_reserve_write_off 9963 9963 0 0.00 8 47018 295 0 0
sp_supplier_invoice 5309 5309 0 0.00 9 124526 3094 0 0
sp_supplier_order 5326 5326 0 0.00 7 68059 1442 0 0
srv_aggregate_perf_data 112 112 0 0.00 2128 83981 13734 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 790 166278 36737 0 0
srv_find_qd_qs_mism 980360 980360 0 0.00 0 14922 11 0 0
srv_make_invnt_saldo 464 464 0 0.00 50 56989 4041 0 0
srv_make_money_saldo 942 942 0 0.00 1 9406 320 0 0
srv_recalc_idx_stat 5 5 0 0.00 124797 484189 306575 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 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 4834 4834 0 0.00 1 13624 974 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 2 7695 458 0 0
t$perf-norm:sp_cancel_client_order 1735 1735 0 0.00 0 4909 34 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 4235 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 3456 28 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 1574 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 1819 38 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 111413 7982 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 12171 46 0 0
t$perf-norm:sp_client_order 20600 20600 0 0.00 0 9494 41 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 570 13 0 0
t$perf-norm:sp_pay_from_customer 6919 6919 0 0.00 0 13511 26 0 0
t$perf-norm:sp_pay_to_supplier 5861 5861 0 0.00 0 7075 23 0 0
t$perf-norm:sp_reserve_write_off 9963 9963 0 0.00 0 12853 28 0 0
t$perf-norm:sp_supplier_invoice 5309 5309 0 0.00 0 9490 37 0 0
t$perf-norm:sp_supplier_order 5326 5326 0 0.00 0 9398 39 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 628 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 171 7 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 1060 19 0 0
t$perf-norm:srv_make_money_saldo 942 942 0 0.00 0 545 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 182 74 0 0
v_add_invoice_to_stock 9858 9596 262 2.65 0 14620 26 0 262
v_all_customers 37756 37756 0 0.00 0 127 4 0 0
v_all_suppliers 29946 29946 0 0.00 0 128 4 0 0
v_all_wares 41200 41200 0 0.00 0 94 4 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 294 13 0 0
v_cancel_client_order 3470 3470 0 0.00 0 3785 10 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 7625 22 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 735 11 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 3358 16 0 0
v_cancel_supplier_invoice 868 850 18 2.07 0 4633 34 0 18
v_cancel_supplier_order 788 788 0 0.00 0 12837 35 0 0
v_cancel_write_off 1978 1978 0 0.00 0 2817 24 0 0
v_max_id_clo_ord 5326 5326 0 0.00 0 9836 7 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 3332 22 0 0
v_max_id_ord_sup 5305 5305 0 0.00 0 2685 8 0 0
v_max_non_paid_invoice 1519 1519 0 0.00 0 2059 9 0 0
v_max_non_paid_realizn 3541 3541 0 0.00 0 2669 8 0 0
v_min_id_clo_ord 5326 5326 0 0.00 0 2488 9 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 6213 16 0 0
v_min_id_ord_sup 5305 5305 0 0.00 0 11081 15 0 0
v_min_non_paid_invoice 5861 5861 0 0.00 0 4421 14 0 0
v_min_non_paid_realizn 6919 6919 0 0.00 0 7789 17 0 0
v_our_firm 10200 10200 0 0.00 0 69 5 0 0
v_reserve_write_off 19926 19926 0 0.00 0 14923 15 0 0
x_lock_depdocs_on_canc_invoice 10504 10504 0 0.00 0 25405 130 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 6541 400 0 0
x_make_qstorno_1000_1200 5326 5326 0 0.00 2 66171 1264 0 0
x_make_qstorno_1200_2000 5309 5309 0 0.00 3 120465 2865 0 0
x_make_qstorno_2100_3300 441078 441078 0 0.00 0 131957 237 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-06-2022 10:38:48 to 29-06-2022 10:38:52.

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
08:07:22 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:24 22629 8192000000 9731908128 10033934336 808458304 8.982 100 86 171 1 837238032 7548944 21230784
08:09:54 16185 8192000000 9773436480 10084528128 848195472 9.382 100 92 184 0 882609328 12498032 21915824
08:11:14 21697 8192000000 9812826560 10133790720 873521856 9.635 100 84 168 0 914294272 21795856 18976560
08:12:40 8136 8192000000 9864635408 10195415040 916168352 10.058 100 79 158 0 971700752 38496896 17035504
08:13:55 3416 8192000000 9922219296 10262011904 951268368 10.404 100 90 175 0 1037221952 67763568 18190016
08:15:01 2372 8192000000 9953163568 10299772928 971492304 10.601 100 94 180 0 1070350192 80440496 18417392
08:16:06 1903 8192000000 9960974544 10315911168 1009130064 10.967 100 89 175 0 1081871600 54454688 18286848
08:17:10 1435 8192000000 10008297360 10373439488 1028015824 11.149 100 88 168 2 1121688000 76458960 17213216
08:18:14 917 8192000000 10017566496 10391044096 1051917824 11.379 100 97 186 1 1143866656 72805616 19143216
08:19:15 1105 8192000000 10031313376 10406187008 1062107072 11.477 100 94 183 0 1151090048 71141904 17841072
08:20:19 6814 8192000000 10027030320 10404745216 1069418192 11.547 100 77 150 1 1148698656 66007424 13273040
08:21:27 5819 8192000000 10065286864 10449457152 1073476288 11.585 100 77 149 1 1185794336 97929376 14388672
08:22:34 11849 8192000000 10034159168 10416631808 1080604272 11.653 100 88 163 4 1156710016 59801344 16304400
08:23:46 1166 8192000000 10066089264 10453819392 1093831104 11.779 100 94 185 4 1186272864 73925376 18516384
08:24:49 1093 8192000000 10070690080 10458390528 1094832112 11.789 100 97 184 5 1189497056 75356800 19308144
08:25:52 1156 8192000000 10074046336 10462916608 1097086432 11.810 100 95 181 2 1196143280 80866752 18190096
08:26:54 1064 8192000000 10073712624 10463744000 1100709760 11.844 100 93 177 2 1197415424 79253200 17452464
08:27:57 1091 8192000000 10056956464 10444787712 1090075168 11.743 100 98 178 1 1180551904 71364816 19111920
08:28:59 930 8192000000 10055553808 10446508032 1088399840 11.727 100 94 174 0 1176128864 69703696 18025328
08:30:00 1843 8192000000 10022187088 10420494336 1091739152 11.759 100 71 131 2 1145359424 39233568 14386704
08:31:03 1311 8192000000 10006341648 10395447296 1071380928 11.565 99 84 163 2 1131352352 42858752 17112672
08:32:08 1041 8192000000 10022413552 10409881600 1080724528 11.654 100 94 179 0 1151418432 53214384 17479520
08:33:10 1064 8192000000 10041944768 10421678080 1065962336 11.514 100 98 183 1 1165257168 80215904 19078928
08:34:12 970 8192000000 10025492192 10404380672 1047140960 11.333 100 88 168 0 1144602576 79284624 18176992
08:35:13 1275 8192000000 9968100752 10340941824 1018318768 11.056 99 84 159 0 1091715120 56236352 17160000
08:36:15 1070 8192000000 9997181552 10375487488 1044951600 11.312 100 75 135 1 1115940160 56925616 14062944
08:37:18 4005 8192000000 10003804464 10388422656 1044745360 11.310 99 74 131 4 1115040176 58277552 12017264
08:38:32 32801 8192000000 10022830512 10403725312 1074767648 11.598 100 64 117 2 1145298368 58815088 11715632
08:40:15 6159 8192000000 10052983920 10438590464 1093965424 11.780 100 73 138 3 1170362512 61177280 15219808
08:41:24 9181 8192000000 10114991152 10507464704 1107117104 11.905 100 75 150 1 1221002896 98741168 15144624
08:42:36 3332 8192000000 10133651264 10537697280 1118787328 12.016 100 64 126 1 1254121856 120707536 14626992
08:43:42 5513 8192000000 10163034608 10555949056 1132558208 12.145 100 81 157 0 1282478736 133045312 16875216
08:44:54 1226 8192000000 10208548880 10602876928 1144656960 12.259 100 90 169 0 1321381328 157356496 19367872
08:46:05 4816 8192000000 10227439120 10642411520 1149870800 12.308 100 76 148 2 1336171600 170381552 15919248
08:47:16 3109 8192000000 10218927056 10626994176 1165830960 12.458 100 68 129 0 1328331296 147997568 14502768
08:48:22 1080 8192000000 10264370048 10666991616 1170409056 12.501 100 87 170 0 1378334560 189881168 18044336
08:49:30 1373 8192000000 10316432576 10715660288 1183231136 12.620 100 92 181 3 1424698240 221521360 19945744
08:50:35 1556 8192000000 10346498960 10757455872 1181632240 12.605 100 91 172 2 1462124528 259014640 21477648
08:51:42 1352 8192000000 10372495984 10803220480 1187270384 12.658 100 99 193 1 1494029344 285174560 21584400
08:52:46 1498 8192000000 10375812560 10802888704 1185531840 12.642 100 92 161 3 1492110016 287746880 18831296
08:53:53 903 8192000000 10406753360 10831822848 1188804368 12.672 100 92 179 0 1514737216 303845152 22087696
08:54:56 1401 8192000000 10414689040 10856759296 1194762016 12.728 100 94 184 1 1523165824 306858016 21545792
08:55:59 1023 8192000000 10432717408 10846257152 1183189440 12.620 100 96 190 0 1541539424 334997120 23352864
08:57:04 895 8192000000 10424111808 10874740736 1177186832 12.564 100 98 188 0 1530251696 330424096 22640768
08:58:08 2144 8192000000 10445845136 10864807936 1173789920 12.532 100 91 179 0 1547841584 351494144 22557520
08:59:13 1493 8192000000 10432873712 10857992192 1157105984 12.376 100 96 191 3 1538967760 359476720 22385056
09:00:17 825 8192000000 10455491072 10892599296 1169145936 12.489 100 98 190 1 1561584736 368605408 23833392
09:01:21 1400 8192000000 10433329520 10863751168 1176769296 12.560 100 95 190 2 1538763152 339537024 22456832
09:02:25 779 8192000000 10458949088 10909003776 1176495744 12.558 100 95 181 2 1563683888 364492512 22695632
09:03:27 11148 8192000000 10436931504 10880954368 1166562960 12.465 100 49 96 0 1542202368 363538912 12100496
09:04:48 5155 8192000000 10379621152 10876219392 1169313696 12.490 100 51 98 0 1484574304 303318064 11942544
09:05:59 16738 8192000000 10383240560 10838863872 1168857120 12.486 100 42 82 1 1513751344 334728320 10165904
09:07:17 27791 8192000000 10333987296 10795999232 1157959776 12.384 100 32 64 0 1450232656 284383456 7889424
09:09:11 24544 8192000000 10205268800 10772135936 1126422384 12.088 98 26 52 0 1366697056 233965792 6308880
09:10:38 79452 8192000000 10057375360 10501242880 1113786112 11.968 98 17 27 0 1180722080 64208528 2727440
09:13:08 166273 8192000000 10144256560 10618261504 1149620496 12.306 100 37 73 1 1263396048 105721856 8053696
09:16:59 17202 8192000000 10156675280 10623528960 1141351968 12.228 100 46 91 0 1253869952 102461984 10056000
09:18:34 16519 8192000000 10124859600 10560225280 1141663152 12.231 100 50 85 2 1218184208 66678640 9842416
09:19:52 130173 8192000000 10097041696 10555269120 1098248560 11.821 96 17 32 1 1173625200 71626944 3749696
09:23:04 38664 8192000000 10101332992 10547224576 1122428096 12.050 99 40 71 2 1193553520 63350096 7775328
09:24:46 53814 8192000000 10104819472 10608926720 1119277120 12.020 98 22 42 0 1195695600 70418608 5999872
09:27:14 26471 8192000000 10153330240 10603102208 1102578448 11.862 97 40 80 0 1215686384 104423584 8684352
09:28:42 53449 8192000000 10127759600 10590289920 1113049856 11.961 99 32 64 0 1233930928 112881920 7999152
09:30:41 45788 8192000000 10055247264 10501099520 1097740688 11.816 98 31 59 1 1127050176 23021936 6287552
09:32:34 8795 8192000000 10090574672 10533572608 1121178816 12.038 100 53 102 0 1192236256 60835328 10222112
09:33:44 58038 8192000000 10148149456 10587918336 1122436576 12.050 98 34 68 0 1207910352 78360432 7113344
09:35:43 57000 8192000000 10153109648 10641031168 1131328240 12.134 99 23 46 0 1270335104 133705760 5301104
09:37:41 99076 8192000000 10070454064 10519543808 1111997472 11.951 98 15 30 0 1179831632 64589360 3244800
09:40:22 14344 8192000000 10081325120 10500866048 1110948560 11.941 98 45 88 0 1143972944 24458976 8565408
09:41:37 50116 8192000000 10104347696 10558947328 1110744432 11.939 99 46 92 0 1188935648 69202800 8988416
09:44:47 26222 8192000000 10054984032 10509774848 1123410800 12.059 100 26 46 1 1158519872 30676256 4432816
09:46:14 50131 8192000000 10056148112 10520526848 1111800560 11.949 98 11 21 0 1153155200 39301136 2053504
09:48:04 69784 8192000000 10072937536 10529210368 1120428224 12.031 99 16 31 0 1162456064 38636096 3391744
09:50:16 67096 8192000000 10125870736 10573606912 1142433408 12.238 100 21 41 0 1218106208 71111408 4561392
09:52:32 27794 8192000000 10130365840 10579451904 1130811408 12.129 98 41 75 3 1222737616 83682064 8244144
09:54:07 48851 8192000000 10161271648 10612117504 1120439696 12.031 97 35 70 0 1249162784 120506272 8216816
09:55:57 26171 8192000000 10218862944 10669166592 1143432080 12.248 99 40 81 0 1306706032 154316000 8957952
09:57:30 29507 8192000000 10249035024 10688344064 1145078896 12.263 99 39 78 1 1348542416 194775152 8688368
09:59:01 61631 8192000000 10213148464 10783821824 1125602400 12.080 98 18 36 0 1342760656 212258656 4899600
10:01:39 74107 8192000000 10153424752 10592088064 1101455904 11.851 97 30 60 0 1206431840 98806288 6169648
10:03:56 81857 8192000000 10072565232 10500968448 1106879312 11.903 98 42 82 1 1157692640 42395232 8418096
10:06:20 39678 8192000000 10055037168 10521825280 1094882768 11.789 98 22 45 1 1155250720 55614432 4753520
10:08:09 82793 8192000000 10036665744 10514976768 1092512896 11.767 98 7 14 0 1122740336 28750400 1477040
10:10:33 87969 8192000000 10063985728 10519875584 1118876112 12.016 100 31 63 0 1162776512 36968928 6931472
10:13:17 60860 8192000000 10089618400 10553479168 1139515040 12.211 100 19 33 1 1208108320 65173792 3419488
10:15:19 70710 8192000000 10042904656 10524139520 1100348016 11.841 97 16 32 0 1146440400 43221184 2871200
10:17:30 36203 8192000000 10065364640 10516320256 1113005408 11.961 98 32 63 0 1150726512 31077664 6643440
10:19:07 11480 8192000000 10100327280 10545102848 1136680304 12.184 100 56 107 1 1214059104 66320672 11058128
10:20:23 63375 8192000000 10085310944 10576510976 1132506128 12.145 100 37 73 0 1222803200 83030576 7266496
10:22:29 20473 8192000000 10135993664 10578653184 1146379328 12.275 100 47 94 1 1227621232 71233808 10008096
10:23:52 51219 8192000000 10153218352 10610569216 1119765792 12.025 98 31 62 0 1195344432 69210272 6368368
10:25:43 54762 8192000000 10081203696 10552922112 1125598944 12.080 99 17 31 0 1167191680 38581680 3011056
10:27:38 17851 8192000000 10122358880 10551230464 1137174192 12.189 100 58 114 0 1192190544 43082096 11934256
10:28:59 122362 8192000000 10081562000 10557571072 1097979664 11.818 97 24 46 0 1202214912 98965312 5269936
10:32:34 86799 8192000000 10057845552 10518499328 1107910048 11.913 98 9 17 0 1144548688 34995392 1643248
10:35:01 113186 8192000000 10047470560 10511556608 1099362112 11.832 97 16 28 1 1132407600 28574720 4470768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-06-2022 10:38:54 to 29-06-2022 10:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 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 29-06-2022 10:39:11 to 29-06-2022 10:39:13.

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          217917
MON$OLDEST_ACTIVE               219527
MON$OLDEST_SNAPSHOT             219527
MON$NEXT_TRANSACTION            219527
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                       2034592
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2793 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2793 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2793 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2793 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Jun 29 10:37:20 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed Jun 29 10:37:21 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Wed Jun 29 10:37:22 2022
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Wed Jun 29 10:37:23 2022
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
--- end of diff output ---

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

29.06.22 10:39:14. 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
2022-06-29 10:39:16 No severe PSQL-related problems occured
29.06.22 10:39:18. End of report.