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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16488756 kB
MemAvailable 31502160 kB
Buffers 0 kB
Cached 15257988 kB
SwapCached 2644 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-05-13 10:37:16
DTS_END                         2022-05-13 10:37:16.5900
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/51872
TRN_ID                          214937
ATT_ID                          595
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: 8966.58 22163 1075990
customer order: creation 170.31 468 20437
customer order: refuse 14.45 381 1734
order to supplier: creation 44.20 1364 5304
order to supplier: removal 3.54 157656 425
invoice (draft): creation 44.53 3286 5344
invoice (draft): removal 50.61 949 6073
invoice accept: apply 40.78 25873 4894
invoice accept: cancel 45.51 1087 5461
customer reserve: creation 3763.93 249 451671
customer reserve: removal 4502.71 97 540325
realization accept: apply 81.48 285 9778
realization accept: cancel 78.39 161 9407
payment from customer: creation 57.23 190 6868
payment from customer: removal 5.96 133 715
payment to supplier: creation 47.13 197 5655
payment to supplier: removal 3.14 115 377
service: total inventory turnovers 4.54 3452 545
service: total monetary turnovers 7.20 257 864
service: aggregate perf. data 0.90 20326 108
service: refresh index statistics 0.04 226730 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-05-2022 10:39:35 to 13-05-2022 10:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9829
TEST_TIME 2 6268
TEST_TIME 3 6446
TEST_TIME 4 6818
TEST_TIME 5 6874
TEST_TIME 6 7045
TEST_TIME 7 6492
TEST_TIME 8 6522
TEST_TIME 9 6619
TEST_TIME 10 6778
TEST_TIME 11 6906
TEST_TIME 12 7184
TEST_TIME 13 7160
TEST_TIME 14 7571
TEST_TIME 15 7455
TEST_TIME 16 7385
TEST_TIME 17 7491
TEST_TIME 18 7423
TEST_TIME 19 7584
TEST_TIME 20 7612
TEST_TIME 21 7663
TEST_TIME 22 8165
TEST_TIME 23 8324
TEST_TIME 24 8508
TEST_TIME 25 8662
TEST_TIME 26 8935
TEST_TIME 27 8755
TEST_TIME 28 9559
TEST_TIME 29 9855
TEST_TIME 30 10555
TEST_TIME 31 11038
TEST_TIME 32 10937
TEST_TIME 33 10734
TEST_TIME 34 10646
TEST_TIME 35 10691
TEST_TIME 36 10580
TEST_TIME 37 10380
TEST_TIME 38 10211
TEST_TIME 39 10096
TEST_TIME 40 10008
TEST_TIME 41 10109
TEST_TIME 42 10060
TEST_TIME 43 10035
TEST_TIME 44 9954
TEST_TIME 45 9862
TEST_TIME 46 9831
TEST_TIME 47 9858
TEST_TIME 48 9691
TEST_TIME 49 9652
TEST_TIME 50 9543
TEST_TIME 51 9444
TEST_TIME 52 9402
TEST_TIME 53 9309
TEST_TIME 54 9324
TEST_TIME 55 9303
TEST_TIME 56 9280
TEST_TIME 57 9486
TEST_TIME 58 9478
TEST_TIME 59 9558
TEST_TIME 60 9570
TEST_TIME 61 9507
TEST_TIME 62 9537
TEST_TIME 63 9539
TEST_TIME 64 9598
TEST_TIME 65 9654
TEST_TIME 66 9546
TEST_TIME 67 9462
TEST_TIME 68 9435
TEST_TIME 69 9432
TEST_TIME 70 9353
TEST_TIME 71 9287
TEST_TIME 72 9214
TEST_TIME 73 9202
TEST_TIME 74 9249
TEST_TIME 75 9241
TEST_TIME 76 9256
TEST_TIME 77 9240
TEST_TIME 78 9240
TEST_TIME 79 9226
TEST_TIME 80 9258
TEST_TIME 81 9231
TEST_TIME 82 9237
TEST_TIME 83 9320
TEST_TIME 84 9355
TEST_TIME 85 9347
TEST_TIME 86 9354
TEST_TIME 87 9319
TEST_TIME 88 9288
TEST_TIME 89 9276
TEST_TIME 90 9208
TEST_TIME 91 9177
TEST_TIME 92 9113
TEST_TIME 93 9162
TEST_TIME 94 9141
TEST_TIME 95 9154
TEST_TIME 96 9118
TEST_TIME 97 9097
TEST_TIME 98 9076
TEST_TIME 99 9085
TEST_TIME 100 9094
TEST_TIME 101 9061
TEST_TIME 102 9301
TEST_TIME 103 9278
TEST_TIME 104 9221
TEST_TIME 105 9233
TEST_TIME 106 9242
TEST_TIME 107 9233
TEST_TIME 108 9293
TEST_TIME 109 9333
TEST_TIME 110 9255
TEST_TIME 111 9209
TEST_TIME 112 9179
TEST_TIME 113 9205
TEST_TIME 114 9191
TEST_TIME 115 9151
TEST_TIME 116 9130
TEST_TIME 117 9109
TEST_TIME 118 9066
TEST_TIME 119 9059
TEST_TIME 120 8993

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-05-2022 10:39:38 to 13-05-2022 10:39:42.

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 1493382 1493382 0 0.00 0 22274 14 0 0
doc_list_biud 1493368 1493368 0 0.00 0 38954 40 0 0
sp_add_invoice_to_stock 5022 4894 128 2.54 23 191531 25873 0 128
sp_cancel_adding_invoice 5461 5461 0 0.00 5 83466 1088 0 0
sp_cancel_client_order 1734 1734 0 0.00 6 12749 381 0 0
sp_cancel_customer_reserve 540325 540325 0 0.00 1 32188 97 0 0
sp_cancel_pay_from_customer 715 715 0 0.00 4 9954 133 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 4 5738 115 0 0
sp_cancel_supplier_invoice 6089 6073 16 0.26 2 31989 950 0 16
sp_cancel_supplier_order 425 425 0 0.00 10 1255866 157656 0 0
sp_cancel_write_off 9407 9407 0 0.00 2 25502 162 0 0
sp_client_order 20437 20437 0 0.00 6 35324 469 0 0
sp_customer_reserve 451671 451671 0 0.00 0 83061 250 0 0
sp_fill_shopping_cart 31071 31071 0 0.00 2 23344 76 0 0
sp_fill_shopping_cart_clo_res 451631 451631 0 0.00 0 23547 18 0 0
sp_get_clo_for_invoice 4846 4846 0 0.00 0 36249 306 0 0
sp_kill_cost_storno 15960 15960 0 0.00 0 11909 71 0 0
sp_kill_qstorno_ret_qs2qd 1096555 1096555 0 0.00 0 38951 42 0 0
sp_kill_qty_storno 578048 578048 0 0.00 0 38953 97 0 0
sp_lock_selected_doc 20334 20334 0 0.00 0 9710 10 0 0
sp_make_cost_storno 27146 27146 0 0.00 0 22263 85 0 0
sp_multiply_rows_for_pdistr 27145 27145 0 0.00 0 11073 39 0 0
sp_multiply_rows_for_qdistr 20436 20436 0 0.00 0 14253 173 0 0
sp_pay_from_customer 6868 6868 0 0.00 5 43962 191 0 0
sp_pay_to_supplier 5655 5655 0 0.00 6 15662 197 0 0
sp_payment_common 12522 12522 0 0.00 2 43954 49 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 12705 295 0 0
sp_qd_handle_on_invoice_upd_sts 10306 10306 0 0.00 0 36341 483 0 0
sp_qd_handle_on_reserve_upd_sts 9778 9778 0 0.00 0 8772 58 0 0
sp_reserve_write_off 9778 9778 0 0.00 7 22286 285 0 0
sp_supplier_invoice 5344 5344 0 0.00 10 97771 3286 0 0
sp_supplier_order 5304 5304 0 0.00 8 57271 1365 0 0
srv_aggregate_perf_data 108 108 0 0.00 2357 189748 20327 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 747 122426 34665 0 0
srv_find_qd_qs_mism 1028922 1028922 0 0.00 0 17880 11 0 0
srv_make_invnt_saldo 545 545 0 0.00 51 132909 3452 0 0
srv_make_money_saldo 864 864 0 0.00 1 9013 258 0 0
srv_recalc_idx_stat 5 5 0 0.00 78876 387181 226730 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4898 4898 0 0.00 1 13220 868 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 7366 411 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 3148 30 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 911 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 715 715 0 0.00 0 1169 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 2748 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 8324 78 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 1 90182 6312 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 4826 22 0 0
t$perf-norm:sp_client_order 20438 20438 0 0.00 0 11583 36 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 3324 21 0 0
t$perf-norm:sp_pay_from_customer 6868 6868 0 0.00 0 6952 25 0 0
t$perf-norm:sp_pay_to_supplier 5655 5655 0 0.00 0 6749 22 0 0
t$perf-norm:sp_reserve_write_off 9778 9778 0 0.00 0 11769 25 0 0
t$perf-norm:sp_supplier_invoice 5344 5344 0 0.00 0 8369 34 0 0
t$perf-norm:sp_supplier_order 5304 5304 0 0.00 0 5312 31 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 498 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 246 6 0 0
t$perf-norm:srv_make_invnt_saldo 545 545 0 0.00 0 720 12 0 0
t$perf-norm:srv_make_money_saldo 864 864 0 0.00 0 1289 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 189 78 0 0
v_add_invoice_to_stock 9934 9678 256 2.57 0 5910 25 0 256
v_all_customers 37146 37146 0 0.00 0 94 3 0 0
v_all_suppliers 29534 29534 0 0.00 0 63 4 0 0
v_all_wares 40868 40868 0 0.00 0 154 4 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 5699 20 0 0
v_cancel_client_order 3468 3468 0 0.00 0 5779 13 0 0
v_cancel_customer_prepayment 1430 1430 0 0.00 0 4343 15 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 5780 15 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 484 10 0 0
v_cancel_supplier_invoice 758 726 32 4.22 0 930 25 0 32
v_cancel_supplier_order 850 850 0 0.00 0 1894 14 0 0
v_cancel_write_off 2106 2106 0 0.00 0 1921 21 0 0
v_max_id_clo_ord 5298 5298 0 0.00 0 472 4 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 2247 13 0 0
v_max_id_ord_sup 5336 5336 0 0.00 0 4283 7 0 0
v_max_non_paid_invoice 1521 1521 0 0.00 0 277 7 0 0
v_max_non_paid_realizn 3666 3666 0 0.00 0 2060 8 0 0
v_min_id_clo_ord 5298 5298 0 0.00 0 1999 7 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 2139 12 0 0
v_min_id_ord_sup 5336 5336 0 0.00 0 4271 9 0 0
v_min_non_paid_invoice 5654 5654 0 0.00 0 6032 17 0 0
v_min_non_paid_realizn 6868 6868 0 0.00 0 43949 24 0 0
v_our_firm 10122 10122 0 0.00 0 54 4 0 0
v_reserve_write_off 19547 19547 0 0.00 0 7582 13 0 0
x_lock_depdocs_on_canc_invoice 11171 11171 0 0.00 0 21829 116 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 15116 379 0 0
x_make_qstorno_1000_1200 5304 5304 0 0.00 2 57242 1221 0 0
x_make_qstorno_1200_2000 5344 5344 0 0.00 4 96108 3072 0 0
x_make_qstorno_2100_3300 451670 451670 0 0.00 0 83057 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-05-2022 10:39:43 to 13-05-2022 10:39:47.

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:20 5 8192000000 8799252064 8810582016 5260816 0.064 2 1 2 0 5360240 63648 35776
08:08:21 22372 8192000000 9714827552 10009075712 788952000 8.784 100 87 174 0 818078880 7793056 21333824
08:09:50 19658 8192000000 9754199776 10059468800 828959008 9.189 100 86 172 0 860994576 11753088 20282480
08:11:15 18200 8192000000 9789337296 10106277888 857417136 9.474 100 85 170 0 898732400 21400608 19914656
08:12:39 13017 8192000000 9844051648 10175705088 901837696 9.917 100 83 166 0 957080288 36985280 18257312
08:13:56 3071 8192000000 9907662864 10246455296 942861216 10.321 100 84 164 0 1024786896 65142544 16783136
08:15:02 3782 8192000000 9938731120 10292486144 986425200 10.747 100 84 161 2 1055165504 51633200 17107104
08:16:07 1494 8192000000 10009972736 10373718016 1017090864 11.044 100 89 176 0 1126182256 92016320 17075072
08:17:15 1470 8192000000 10018765680 10390458368 1045838608 11.321 100 91 172 1 1136412048 73112144 17461296
08:18:17 1084 8192000000 10015254848 10391031808 1056967808 11.427 100 97 182 1 1141428768 64842432 19618528
08:19:19 1985 8192000000 10043936992 10424762368 1067376336 11.527 100 90 168 0 1159955504 76338080 16241088
08:20:23 1126 8192000000 10063285328 10443493376 1073827856 11.589 100 96 192 2 1184671008 92149296 18693856
08:21:24 9431 8192000000 10016335472 10406854656 1078938608 11.637 100 70 134 2 1147690768 55972928 12779232
08:22:34 1276 8192000000 10032296272 10422136832 1086337696 11.708 100 91 176 0 1157310832 51659872 19313264
08:23:37 970 8192000000 10049020528 10444275712 1088920544 11.732 100 81 159 0 1174731168 69914608 15896016
08:24:39 1319 8192000000 10086555280 10471469056 1089302928 11.736 100 98 195 3 1205332944 96494064 19535952
08:25:42 1286 8192000000 10055316720 10438467584 1089762608 11.740 100 97 191 2 1176116352 66727456 19626288
08:26:44 1174 8192000000 10061775776 10444185600 1093335568 11.774 100 92 177 0 1183454608 71339568 18779472
08:27:46 5725 8192000000 10054369360 10445524992 1079960352 11.647 99 62 122 2 1173426688 80910800 12555536
08:28:52 4223 8192000000 10042518464 10432753664 1088413984 11.728 100 73 139 1 1161840016 59132240 14293792
08:29:57 2835 8192000000 10024801984 10417102848 1076286176 11.612 100 68 135 3 1148504576 58839984 13378416
08:31:01 1945 8192000000 10038387328 10433400832 1068910160 11.542 100 78 150 0 1165652752 80670048 16072544
08:32:05 4218 8192000000 10000658368 10375827456 1064370832 11.498 100 86 149 4 1125319792 46896928 14052032
08:33:09 1467 8192000000 10022951472 10401390592 1057458176 11.432 100 86 155 2 1143345328 69987728 15899424
08:34:12 3568 8192000000 9977002560 10354798592 1026777760 11.137 99 55 106 0 1099903952 61759760 11366432
08:35:16 1054 8192000000 10001808144 10371567616 1034030016 11.207 100 95 178 1 1123885712 70990416 18865280
08:36:18 1327 8192000000 9998505824 10369634304 1033167088 11.199 99 96 189 0 1122471648 69574704 19729856
08:37:20 14686 8192000000 9990525632 10379485184 1049354784 11.354 100 53 102 1 1116207888 56168064 10685040
08:38:51 45840 8192000000 10029831376 10411819008 1064141056 11.496 99 64 124 0 1131397424 55149280 12107088
08:41:01 5578 8192000000 10086143568 10473525248 1098363472 11.822 100 78 147 1 1207863872 94262496 15237904
08:42:10 2141 8192000000 10117855472 10508640256 1110652736 11.939 100 82 155 0 1234707104 107301888 16752480
08:43:17 2615 8192000000 10109660896 10509324288 1116795872 11.997 100 72 136 1 1235592000 104961920 13834208
08:44:24 9142 8192000000 10160444208 10562347008 1124598896 12.070 100 60 117 0 1270424032 133570880 12254256
08:45:42 950 8192000000 10197230224 10588106752 1143287136 12.246 100 92 179 2 1310871952 145464592 22120224
08:46:45 1656 8192000000 10242226928 10640609280 1149799648 12.308 100 94 181 0 1356696496 186581984 20314864
08:47:50 1079 8192000000 10269356336 10667540480 1163673600 12.438 100 94 180 2 1382537232 198307808 20555824
08:48:53 1242 8192000000 10318974656 10727530496 1172504560 12.520 100 95 184 1 1441757536 247939056 21313920
08:49:58 1353 8192000000 10327278256 10734514176 1182593184 12.614 100 94 183 3 1438220944 234284784 21342976
08:51:05 953 8192000000 10349036512 10779369472 1190418592 12.687 100 98 193 0 1457592432 244560800 22613040
08:52:10 4154 8192000000 10337653264 10744971264 1196021280 12.739 100 80 160 0 1450787184 236111360 18654544
08:53:25 6324 8192000000 10424000688 10839146496 1199353488 12.770 100 72 144 0 1530363904 313772336 17238080
08:54:35 1038 8192000000 10473810816 10900582400 1214462640 12.910 100 98 191 0 1587249920 349201040 23586240
08:55:41 1011 8192000000 10493258496 10921172992 1218677232 12.949 100 96 183 0 1599701728 358294592 22729904
08:56:45 1160 8192000000 10565917728 10992304128 1229052480 13.045 100 97 195 2 1673055632 419731168 24271984
08:57:49 813 8192000000 10593564128 11004997632 1224840544 13.006 100 99 198 1 1695570576 446732128 23997904
08:58:52 737 8192000000 10580441840 11054870528 1211695056 12.885 100 87 172 0 1687334304 453004608 22634640
08:59:55 10470 8192000000 10528625184 11010412544 1195412720 12.734 100 52 100 1 1646822704 438120592 13289392
09:01:10 2994 8192000000 10557037568 11016060928 1203394016 12.808 100 63 126 1 1658503648 439441296 15668336
09:02:16 8078 8192000000 10540868128 10977927168 1203749472 12.811 100 62 120 0 1647094064 427392672 15951920
09:03:26 27090 8192000000 10502136928 10971656192 1191747280 12.700 100 33 66 1 1638489760 437611056 9131424
09:04:55 12003 8192000000 10451292816 10992640000 1165176096 12.452 99 35 70 0 1586364416 411427328 9760992
09:06:38 37924 8192000000 10262909920 10742935552 1140123376 12.217 99 15 30 0 1421985024 277637120 4224528
09:08:17 54484 8192000000 10082341152 10504593408 1082407712 11.670 94 40 58 3 1110019760 22191888 5420160
09:10:13 6249 8192000000 10096527520 10521034752 1145510720 12.267 100 71 136 2 1190667392 29651712 15504960
09:11:19 94545 8192000000 10088146336 10559574016 1128748192 12.110 99 26 52 3 1183454592 48901200 5805200
09:14:43 122424 8192000000 10108368000 10567720960 1115038320 11.980 97 28 56 2 1171634512 49840864 6755328
09:17:46 50046 8192000000 10154542288 10644197376 1152306064 12.331 100 26 52 0 1287485184 129177952 6001168
09:19:41 25654 8192000000 10111855104 10562600960 1120522816 12.032 98 36 71 0 1202196032 74262608 7410608
09:21:21 9688 8192000000 10075698720 10536382464 1132423760 12.144 99 37 70 1 1170951680 31423952 7103968
09:22:31 41734 8192000000 10096886224 10561216512 1127440320 12.097 98 13 26 2 1196751168 66395824 2915024
09:24:13 68874 8192000000 10047675968 10525036544 1104628912 11.882 98 15 30 1 1144917184 36727648 3560624
09:26:24 54727 8192000000 10056093328 10517245952 1096367376 11.803 98 29 58 3 1125238736 23254336 5617024
09:28:58 63718 8192000000 10061510752 10526453760 1101358544 11.851 99 14 28 1 1137389248 33273504 2757200
09:31:02 52297 8192000000 10119433200 10577973248 1119475568 12.022 99 20 38 1 1193696704 70348176 3872960
09:32:55 33968 8192000000 10156528400 10610716672 1135715040 12.175 100 38 76 2 1260024480 116224496 8084944
09:34:34 25793 8192000000 10105317808 10615709696 1138051168 12.197 100 20 40 1 1263505760 120874688 4579904
09:36:05 60124 8192000000 10087701312 10570199040 1109413024 11.927 98 30 56 4 1176339696 60666528 6260144
09:38:17 17775 8192000000 10120392848 10554515456 1132963072 12.149 100 45 90 0 1200128864 57777120 9388672
09:39:47 24267 8192000000 10155190464 10596102144 1137139552 12.189 100 46 92 2 1234268464 87321952 9806960
09:41:14 37711 8192000000 10125935744 10607644672 1123911536 12.064 99 25 50 0 1236305904 106646496 5747872
09:42:52 93070 8192000000 10093997120 10525188096 1096958640 11.809 97 46 87 1 1136803024 30432384 9412000
09:45:26 23382 8192000000 10111219584 10540371968 1115965600 11.989 99 33 66 0 1208107552 85513824 6628128
09:46:57 44085 8192000000 10048463936 10525634560 1123144928 12.057 100 5 10 1 1165154304 41064176 945200
09:49:50 117085 8192000000 10095249024 10627813376 1140722240 12.222 100 19 38 0 1241961664 97121664 4117760
09:53:12 19347 8192000000 10090094960 10519019520 1129093664 12.113 99 58 113 2 1183563888 43169280 11300944
09:54:33 31244 8192000000 10101661248 10553004032 1129098336 12.113 99 32 63 1 1197526880 61513952 6914592
09:56:05 30972 8192000000 10090909664 10533273600 1126903424 12.092 99 42 84 2 1191997312 56818976 8274912
09:57:37 28347 8192000000 10096172032 10515472384 1062541984 11.481 94 42 84 0 1136133216 65403952 8187280
09:59:06 30166 8192000000 10113002032 10552582144 1102871392 11.865 100 46 92 0 1225148784 112948320 9329072
10:00:39 25266 8192000000 10101024192 10549604352 1103479808 11.871 98 35 69 0 1193859328 83491680 6887840
10:02:04 40818 8192000000 10138562064 10567294976 1114276880 11.973 99 35 70 0 1210048256 88372336 7399040
10:04:33 87771 8192000000 10045662288 10480963584 1094143744 11.782 97 44 86 1 1138357504 34995664 9218096
10:07:01 73549 8192000000 10085013568 10545446912 1127858448 12.101 100 17 34 1 1186360352 54930944 3570960
10:09:15 82183 8192000000 10063852640 10528014336 1109621712 11.929 98 15 31 1 1156527120 43878448 3026960
10:11:38 71686 8192000000 10064294704 10512654336 1120158608 12.028 98 18 36 0 1172184192 47834256 4191328
10:13:51 23767 8192000000 10116453856 10546421760 1133399568 12.153 99 33 66 2 1208736256 68378896 6957792
10:15:18 27386 8192000000 10166557824 10600333312 1130036432 12.122 98 39 78 1 1249903488 111805920 8061136
10:16:46 86130 8192000000 10189794208 10677071872 1147122176 12.282 99 24 48 1 1297104880 144454320 5528384
10:19:21 58845 8192000000 10069731840 10570043392 1105337952 11.888 98 17 34 1 1219974368 111023216 3613200
10:21:23 1655 8192000000 10091372752 10504720384 1137283296 12.190 100 82 162 2 1184826400 29293648 18249456
10:22:27 33815 8192000000 10111996240 10543132672 1120594480 12.033 99 59 114 1 1200716944 68331744 11790720
10:24:09 55354 8192000000 10121936592 10553081856 1088149168 11.725 96 18 36 2 1178799728 86835904 3814656
10:26:05 64252 8192000000 10065929552 10595348480 1102866176 11.865 98 8 16 1 1163952464 59383536 1702752
10:28:41 82337 8192000000 10098365936 10572546048 1130816672 12.129 100 20 40 1 1224683200 89404832 4461696
10:31:11 37299 8192000000 10073956288 10540445696 1126930448 12.092 99 24 48 0 1164366864 32228112 5208304
10:32:49 76062 8192000000 10094998096 10580684800 1142558960 12.240 100 12 25 0 1186872400 41500528 2812912
10:35:07 46199 8192000000 10104257920 10563485696 1131113904 12.132 99 12 24 1 1188525072 54624800 2786368
10:37:08 23755 8192000000 9397793744 9738555392 473037072 5.459 42 3 6 0 517382368 43759360 585936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

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

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          218094
MON$OLDEST_ACTIVE               218095
MON$OLDEST_SNAPSHOT             218095
MON$NEXT_TRANSACTION            218095
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                       2040032
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:55: 
:56: mscs-test-161.budzdorov.ru    Fri May 13 10:37:33 2022
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Fri May 13 10:37:35 2022
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Fri May 13 10:37:39 2022
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
--- end of diff output ---

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

13.05.22 10:40:09. 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-05-13 10:40:11 No severe PSQL-related problems occured
13.05.22 10:40:13. End of report.