Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.04.2022 07: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 16313696 kB
MemAvailable 31573696 kB
Buffers 0 kB
Cached 15423956 kB
SwapCached 3192 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-04-29 07:37:14
DTS_END                         2022-04-29 07:37:14.3810
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/36172
TRN_ID                          224230
ATT_ID                          617
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: 9003.68 23056 1080442
customer order: creation 173.30 445 20796
customer order: refuse 14.50 407 1740
order to supplier: creation 45.15 1359 5418
order to supplier: removal 3.49 159693 419
invoice (draft): creation 46.23 3093 5548
invoice (draft): removal 51.48 966 6177
invoice accept: apply 42.20 24702 5064
invoice accept: cancel 46.23 1165 5547
customer reserve: creation 3811.53 241 457384
customer reserve: removal 4482.23 96 537868
realization accept: apply 83.37 289 10004
realization accept: cancel 74.17 176 8900
payment from customer: creation 58.78 186 7053
payment from customer: removal 5.76 146 691
payment to supplier: creation 49.23 187 5907
payment to supplier: removal 3.37 125 404
service: total inventory turnovers 3.88 4267 465
service: total monetary turnovers 7.82 337 938
service: aggregate perf. data 0.95 16544 114
service: refresh index statistics 0.04 246696 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-04-2022 07:39:28 to 29-04-2022 07:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9263
TEST_TIME 2 6535
TEST_TIME 3 7224
TEST_TIME 4 6629
TEST_TIME 5 6211
TEST_TIME 6 6740
TEST_TIME 7 6893
TEST_TIME 8 6811
TEST_TIME 9 6946
TEST_TIME 10 6983
TEST_TIME 11 7237
TEST_TIME 12 7481
TEST_TIME 13 7475
TEST_TIME 14 7378
TEST_TIME 15 7412
TEST_TIME 16 7391
TEST_TIME 17 7697
TEST_TIME 18 7747
TEST_TIME 19 7666
TEST_TIME 20 7830
TEST_TIME 21 7946
TEST_TIME 22 8195
TEST_TIME 23 8389
TEST_TIME 24 8558
TEST_TIME 25 8504
TEST_TIME 26 9304
TEST_TIME 27 9415
TEST_TIME 28 9526
TEST_TIME 29 9969
TEST_TIME 30 10426
TEST_TIME 31 10880
TEST_TIME 32 11312
TEST_TIME 33 11447
TEST_TIME 34 11415
TEST_TIME 35 11279
TEST_TIME 36 11068
TEST_TIME 37 10847
TEST_TIME 38 10989
TEST_TIME 39 10965
TEST_TIME 40 10864
TEST_TIME 41 10725
TEST_TIME 42 10654
TEST_TIME 43 10625
TEST_TIME 44 10460
TEST_TIME 45 10429
TEST_TIME 46 10284
TEST_TIME 47 10220
TEST_TIME 48 10163
TEST_TIME 49 10328
TEST_TIME 50 10287
TEST_TIME 51 10176
TEST_TIME 52 10088
TEST_TIME 53 10059
TEST_TIME 54 10037
TEST_TIME 55 9928
TEST_TIME 56 9796
TEST_TIME 57 9774
TEST_TIME 58 9834
TEST_TIME 59 9825
TEST_TIME 60 9817
TEST_TIME 61 9781
TEST_TIME 62 9821
TEST_TIME 63 9897
TEST_TIME 64 9903
TEST_TIME 65 9974
TEST_TIME 66 9966
TEST_TIME 67 9951
TEST_TIME 68 9975
TEST_TIME 69 9952
TEST_TIME 70 9875
TEST_TIME 71 9972
TEST_TIME 72 9987
TEST_TIME 73 9883
TEST_TIME 74 9818
TEST_TIME 75 9718
TEST_TIME 76 9759
TEST_TIME 77 9728
TEST_TIME 78 9697
TEST_TIME 79 9634
TEST_TIME 80 9597
TEST_TIME 81 9532
TEST_TIME 82 9527
TEST_TIME 83 9474
TEST_TIME 84 9575
TEST_TIME 85 9540
TEST_TIME 86 9546
TEST_TIME 87 9521
TEST_TIME 88 9474
TEST_TIME 89 9523
TEST_TIME 90 9557
TEST_TIME 91 9568
TEST_TIME 92 9512
TEST_TIME 93 9476
TEST_TIME 94 9534
TEST_TIME 95 9463
TEST_TIME 96 9382
TEST_TIME 97 9404
TEST_TIME 98 9419
TEST_TIME 99 9370
TEST_TIME 100 9299
TEST_TIME 101 9272
TEST_TIME 102 9276
TEST_TIME 103 9286
TEST_TIME 104 9257
TEST_TIME 105 9273
TEST_TIME 106 9322
TEST_TIME 107 9318
TEST_TIME 108 9333
TEST_TIME 109 9294
TEST_TIME 110 9284
TEST_TIME 111 9297
TEST_TIME 112 9295
TEST_TIME 113 9321
TEST_TIME 114 9302
TEST_TIME 115 9248
TEST_TIME 116 9205
TEST_TIME 117 9216
TEST_TIME 118 9169
TEST_TIME 119 9106
TEST_TIME 120 9032

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-04-2022 07:39:32 to 29-04-2022 07:39:35.

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 1503519 1503519 0 0.00 0 24024 13 0 0
doc_list_biud 1503514 1503514 0 0.00 0 37742 40 0 0
sp_add_invoice_to_stock 5166 5064 102 1.97 52 205836 24702 0 102
sp_cancel_adding_invoice 5547 5547 0 0.00 5 85179 1166 0 0
sp_cancel_client_order 1740 1740 0 0.00 7 10092 407 0 0
sp_cancel_customer_reserve 537868 537868 0 0.00 1 37748 97 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 6515 146 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 3580 126 0 0
sp_cancel_supplier_invoice 6184 6177 7 0.11 2 19377 967 0 7
sp_cancel_supplier_order 419 419 0 0.00 15 1646627 159694 0 0
sp_cancel_write_off 8900 8900 0 0.00 2 10668 176 0 0
sp_client_order 20796 20796 0 0.00 6 26141 446 0 0
sp_customer_reserve 457384 457384 0 0.00 1 158762 241 0 0
sp_fill_shopping_cart 31754 31754 0 0.00 1 16135 81 0 0
sp_fill_shopping_cart_clo_res 457350 457350 0 0.00 0 19298 19 0 0
sp_get_clo_for_invoice 5017 5017 0 0.00 0 36085 331 0 0
sp_kill_cost_storno 15542 15542 0 0.00 0 6589 72 0 0
sp_kill_qstorno_ret_qs2qd 1091232 1091232 0 0.00 0 37734 42 0 0
sp_kill_qty_storno 575671 575671 0 0.00 0 37741 97 0 0
sp_lock_selected_doc 20798 20798 0 0.00 0 8208 8 0 0
sp_make_cost_storno 27980 27980 0 0.00 0 15514 78 0 0
sp_multiply_rows_for_pdistr 27980 27980 0 0.00 0 23976 38 0 0
sp_multiply_rows_for_qdistr 20796 20796 0 0.00 0 12627 169 0 0
sp_pay_from_customer 7053 7053 0 0.00 6 24035 186 0 0
sp_pay_to_supplier 5907 5907 0 0.00 6 8889 188 0 0
sp_payment_common 12960 12960 0 0.00 2 8806 47 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 10042 342 0 0
sp_qd_handle_on_invoice_upd_sts 10563 10563 0 0.00 0 27521 506 0 0
sp_qd_handle_on_reserve_upd_sts 10004 10004 0 0.00 0 15790 73 0 0
sp_reserve_write_off 10004 10004 0 0.00 9 16396 290 0 0
sp_supplier_invoice 5548 5548 0 0.00 9 110391 3094 0 0
sp_supplier_order 5418 5418 0 0.00 8 117816 1360 0 0
srv_aggregate_perf_data 114 114 0 0.00 2287 290105 16545 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 794 148910 41580 0 0
srv_find_qd_qs_mism 1032582 1032582 0 0.00 0 12057 10 0 0
srv_make_invnt_saldo 465 465 0 0.00 98 181913 4267 0 0
srv_make_money_saldo 938 938 0 0.00 1 18692 337 0 0
srv_recalc_idx_stat 5 5 0 0.00 52660 358782 246697 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5068 5068 0 0.00 1 32720 855 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 5975 397 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 2493 28 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 5747 46 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 2624 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 412 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1052 28 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 105015 6590 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 4184 28 0 0
t$perf-norm:sp_client_order 20796 20796 0 0.00 0 9654 36 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 4165 20 0 0
t$perf-norm:sp_pay_from_customer 7053 7053 0 0.00 0 9541 22 0 0
t$perf-norm:sp_pay_to_supplier 5907 5907 0 0.00 0 9511 21 0 0
t$perf-norm:sp_reserve_write_off 10004 10004 0 0.00 0 17285 25 0 0
t$perf-norm:sp_supplier_invoice 5548 5548 0 0.00 0 6163 31 0 0
t$perf-norm:sp_supplier_order 5418 5418 0 0.00 0 6170 35 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 304 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1470 48 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 1182 16 0 0
t$perf-norm:srv_make_money_saldo 938 938 0 0.00 0 5986 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 234 66 0 0
v_add_invoice_to_stock 10236 10032 204 1.99 0 5874 26 0 204
v_all_customers 37886 37886 0 0.00 0 138 3 0 0
v_all_suppliers 30666 30666 0 0.00 0 64 4 0 0
v_all_wares 41592 41592 0 0.00 0 88 4 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 1459 15 0 0
v_cancel_client_order 3480 3480 0 0.00 0 3535 11 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 3990 18 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 1 3553 15 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 3511 21 0 0
v_cancel_supplier_invoice 836 822 14 1.67 0 3391 25 0 14
v_cancel_supplier_order 838 838 0 0.00 0 4971 21 0 0
v_cancel_write_off 1992 1992 0 0.00 0 2355 22 0 0
v_max_id_clo_ord 5418 5418 0 0.00 0 10159 7 0 0
v_max_id_clo_res 982 982 0 0.00 0 2303 13 0 0
v_max_id_ord_sup 5539 5539 0 0.00 0 6918 7 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 120 7 0 0
v_max_non_paid_realizn 3665 3665 0 0.00 0 585 6 0 0
v_min_id_clo_ord 5418 5418 0 0.00 0 5047 10 0 0
v_min_id_clo_res 982 982 0 0.00 0 7859 24 0 0
v_min_id_ord_sup 5539 5539 0 0.00 0 5927 8 0 0
v_min_non_paid_invoice 5906 5906 0 0.00 0 8797 19 0 0
v_min_non_paid_realizn 7053 7053 0 0.00 0 7489 22 0 0
v_our_firm 10482 10482 0 0.00 0 70 4 0 0
v_reserve_write_off 20008 20008 0 0.00 0 6088 13 0 0
x_lock_depdocs_on_canc_invoice 11313 11313 0 0.00 0 11189 115 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 7986 321 0 0
x_make_qstorno_1000_1200 5418 5418 0 0.00 3 112753 1196 0 0
x_make_qstorno_1200_2000 5548 5548 0 0.00 4 110364 2873 0 0
x_make_qstorno_2100_3300 457384 457384 0 0.00 0 158749 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-04-2022 07:39:36 to 29-04-2022 07:39:40.

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:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:18 21570 8192000000 9722430832 10023624704 800146080 8.898 100 85 171 0 828830688 7456432 21228176
05:09:44 25490 8192000000 9770237568 10078937088 847522464 9.375 100 91 182 0 880981696 11602768 21856464
05:11:15 20096 8192000000 9804233424 10123931648 871997328 9.620 100 83 166 0 913546720 22371984 19177408
05:12:43 8504 8192000000 9875688720 10211557376 927925008 10.174 100 73 146 0 986374688 43168384 15281296
05:13:56 5895 8192000000 9937634432 10286231552 968432032 10.571 100 77 154 0 1045735584 60815952 16487600
05:15:07 3082 8192000000 9975289984 10332454912 1009543584 10.971 100 93 183 1 1096765312 68311216 18910512
05:16:13 2405 8192000000 10002958128 10371633152 1033915168 11.206 100 82 160 0 1119349328 69696496 15737664
05:17:18 953 8192000000 10018884192 10390810624 1048231488 11.344 100 97 189 1 1137805840 70183888 19390464
05:18:22 1129 8192000000 10011567248 10386313216 1062383008 11.479 100 95 179 1 1136788688 56261104 18144576
05:19:27 1180 8192000000 10028311520 10410569728 1063459136 11.490 100 89 174 0 1150294832 69535328 17300368
05:20:30 1030 8192000000 10053488768 10436378624 1067667136 11.530 100 97 181 0 1180561552 94086832 18807584
05:21:33 1328 8192000000 10064176224 10450796544 1072645936 11.577 100 86 168 0 1186733888 96953488 17134464
05:22:35 1110 8192000000 10056604720 10435907584 1076229200 11.612 100 95 181 3 1176238976 82115328 17894448
05:23:38 1148 8192000000 10064787920 10451222528 1081796080 11.665 100 97 194 0 1188191376 86569200 19826096
05:24:40 1636 8192000000 10042593920 10432770048 1083504000 11.681 100 81 158 3 1164138736 64697136 15937600
05:25:43 1055 8192000000 10041418064 10434023424 1086876048 11.713 100 89 172 2 1167594608 62638768 18079792
05:26:45 972 8192000000 10046952928 10431320064 1079191760 11.640 99 95 178 2 1171170976 73752400 18226816
05:27:47 943 8192000000 10063918656 10452045824 1091128144 11.753 100 91 180 0 1186904320 76502448 19273728
05:28:49 3677 8192000000 10036425232 10426122240 1088605248 11.729 100 63 121 1 1146120016 45411520 12103248
05:29:53 1165 8192000000 10005979568 10388545536 1056500752 11.423 100 93 172 0 1130009408 55799328 17709328
05:30:56 1362 8192000000 10010158480 10392625152 1064417888 11.499 100 98 192 1 1131861600 48223632 19220080
05:31:57 1295 8192000000 10011218944 10386755584 1050510672 11.366 100 94 177 2 1134960288 65605280 18844336
05:33:00 1097 8192000000 9989625920 10354966528 1009986896 10.975 98 93 181 0 1113320160 84977248 18356016
05:34:01 617 8192000000 10017408912 10393305088 1051814752 11.378 100 88 172 1 1140650112 71331632 17503728
05:35:02 1339 8192000000 9972014992 10339950592 1021206464 11.084 99 75 148 0 1090849168 55098464 14544240
05:36:04 1583 8192000000 9996545760 10379132928 1049465632 11.356 100 73 140 1 1118425728 53581504 15378592
05:37:07 1252 8192000000 10003840464 10379452416 1061621520 11.472 100 88 163 2 1126141136 47598640 16920976
05:38:17 14449 8192000000 10017958864 10403246080 1079706832 11.645 100 70 133 0 1140535312 47813232 13015248
05:39:38 7028 8192000000 10053819808 10441736192 1088075856 11.724 100 65 123 1 1175733568 74908320 12749392
05:40:50 1814 8192000000 10089099648 10476843008 1107063120 11.905 100 84 161 0 1207468784 82997184 17408480
05:41:55 22983 8192000000 10111586848 10509549568 1123763264 12.063 100 51 98 2 1229088368 94595856 10729248
05:43:24 1447 8192000000 10137468736 10535219200 1132175056 12.142 100 75 143 0 1261231264 114897328 14158880
05:44:29 1119 8192000000 10199960512 10593153024 1152227072 12.330 100 93 179 3 1315607536 142153776 21226688
05:45:36 1817 8192000000 10214503792 10612342784 1161042608 12.413 100 88 172 0 1333685136 153511104 19131424
05:46:40 2065 8192000000 10276411808 10674647040 1170386144 12.500 100 95 174 1 1391497536 202622720 18488672
05:47:44 3263 8192000000 10306540288 10712997888 1174645072 12.540 100 97 191 0 1419223232 222516592 22061568
05:49:02 1568 8192000000 10338131664 10742030336 1193553168 12.716 100 94 183 5 1448921328 234973136 20395024
05:50:07 1345 8192000000 10352074320 10756378624 1189891680 12.682 100 95 185 2 1465570592 253891376 21787536
05:51:14 930 8192000000 10383834720 10789343232 1208413744 12.854 100 93 184 1 1491302160 261572080 21316336
05:52:17 1289 8192000000 10430310128 10834280448 1199586864 12.772 100 90 177 3 1536100976 315560256 20953856
05:53:22 1058 8192000000 10451010000 10864021504 1200079216 12.777 100 93 183 0 1558207296 336567328 21560752
05:54:26 904 8192000000 10496902272 10918375424 1202427040 12.799 100 96 191 1 1601800720 375564448 23809232
05:55:31 1855 8192000000 10536110160 10957148160 1205329440 12.826 100 95 188 2 1641213088 413170000 22713648
05:56:36 786 8192000000 10556771264 10961649664 1193771792 12.718 100 95 189 1 1663746512 446516640 23458080
05:57:39 953 8192000000 10590107472 11026206720 1209268800 12.862 100 98 192 1 1698594960 465687776 23638384
05:58:43 941 8192000000 10571393744 11014418432 1201487216 12.790 100 95 188 0 1676644000 450689792 24466992
05:59:48 977 8192000000 10586779120 11034771456 1195914512 12.738 100 86 171 2 1689670416 472434512 21321392
06:00:54 7706 8192000000 10573078640 11024125952 1185713056 12.643 100 58 112 0 1674838992 474197584 14928352
06:02:04 10793 8192000000 10582207760 11036987392 1182175376 12.610 99 54 106 1 1669431200 473322304 13933520
06:03:27 5381 8192000000 10490316240 10959286272 1170672112 12.503 99 55 110 1 1586384352 401703376 14008864
06:04:37 30116 8192000000 10470064256 10946650112 1173463296 12.529 99 39 78 0 1558345056 374707456 10174304
06:06:11 10519 8192000000 10419726352 10921222144 1170418320 12.501 100 35 70 1 1538356256 358885376 9052560
06:07:23 30628 8192000000 10305135840 10755584000 1129449696 12.116 97 30 60 1 1375795040 238392208 7953136
06:09:03 50980 8192000000 10099852240 10552926208 1118831584 12.016 98 19 38 0 1220689472 97633648 4224240
06:10:55 61022 8192000000 10041181824 10472513536 1055937232 11.418 93 20 40 0 1104356656 44286368 4133056
06:12:57 140871 8192000000 10073636128 10540875776 1101127632 11.848 97 38 69 1 1175289152 66179568 7981952
06:16:34 82155 8192000000 10064254688 10504433664 1118985200 12.017 99 16 31 1 1170041424 47212608 3843616
06:18:59 148894 8192000000 10075814192 10515513344 1066660128 11.520 94 26 52 2 1134878576 62463280 5755168
06:22:30 13253 8192000000 10108059472 10537267200 1110643648 11.939 98 38 75 0 1183085632 64731552 7710432
06:23:44 11775 8192000000 10159117152 10570027008 1125263328 12.077 100 68 130 3 1243372800 102753504 15355968
06:24:58 23906 8192000000 10191979328 10622992384 1126822768 12.091 100 42 83 3 1281681936 145198272 9660896
06:26:23 54167 8192000000 10089596528 10601590784 1128171600 12.104 100 14 28 1 1226062128 93952064 3938464
06:28:22 34766 8192000000 10086087520 10527424512 1119098976 12.018 99 28 56 0 1182347280 56148544 7099760
06:29:56 55492 8192000000 10077563792 10561142784 1134808512 12.167 100 27 55 2 1220791136 79317952 6664672
06:32:03 61690 8192000000 10126848880 10622132224 1144797216 12.261 100 14 26 1 1228596800 80711056 3088528
06:34:22 64766 8192000000 10074271264 10568040448 1108229104 11.916 97 6 12 1 1174745216 65210704 1305408
06:36:28 61362 8192000000 10102131424 10545459200 1118482912 12.013 98 28 50 1 1189195904 65391664 5321328
06:38:30 78162 8192000000 10133597904 10599456768 1143261184 12.246 100 23 46 1 1197275088 48737360 5276544
06:41:13 74255 8192000000 10105072064 10589511680 1135530160 12.173 98 13 26 0 1194032864 55918208 2584496
06:43:28 58289 8192000000 10108157360 10557771776 1076625360 11.615 93 24 48 0 1146365216 64629472 5110384
06:45:32 51175 8192000000 10140383088 10601713664 1132771520 12.147 99 13 26 2 1220677808 84952528 2953760
06:47:25 21058 8192000000 10168813104 10595733504 1133445888 12.154 100 41 80 3 1251494560 109231280 8817392
06:49:01 68012 8192000000 10065244640 10509623296 1108497440 11.918 98 48 96 1 1175045184 56623584 9924160
06:51:29 66332 8192000000 10098105104 10530639872 1101799056 11.855 97 36 73 1 1168999344 60118016 7082272
06:53:37 50877 8192000000 10059298608 10503999488 1108828608 11.921 98 15 28 1 1185421584 73518464 3074512
06:56:02 70972 8192000000 10088756400 10545029120 1122708128 12.053 99 18 36 1 1172989440 46225856 4055456
06:58:41 80570 8192000000 10110257760 10590969856 1131917136 12.139 99 15 30 2 1254011776 118447344 3647296
07:01:05 83838 8192000000 10058229088 10503073792 1114400832 11.974 98 21 41 0 1145141824 26597088 4143904
07:03:32 45033 8192000000 10046706144 10500894720 1101998880 11.857 99 26 52 0 1155234672 47907360 5328432
07:05:19 33269 8192000000 10101986528 10526920704 1109352112 11.926 100 41 82 1 1188100160 70220864 8527184
07:06:54 47785 8192000000 10092678176 10553384960 1105669584 11.891 99 28 56 0 1195420416 83973024 5777808
07:08:44 48828 8192000000 10036030048 10471464960 1069444928 11.547 96 23 45 0 1099606896 26059280 4102688
07:10:34 23901 8192000000 10107448720 10533277696 1094308288 11.784 99 44 88 1 1186852320 83960448 8583584
07:12:03 139284 8192000000 10090324256 10564939776 1116194240 11.991 100 26 52 0 1182224480 60062496 5967744
07:15:31 36850 8192000000 10035433296 10482290688 1103722912 11.873 98 18 36 0 1124452912 17135968 3594032
07:17:09 88967 8192000000 10068181840 10545328128 1103139744 11.867 98 6 12 1 1133573440 29372448 1061248
07:19:40 74666 8192000000 10077264352 10555772928 1092310256 11.765 97 14 29 1 1149941664 54599936 3031472
07:21:55 52652 8192000000 10109353008 10580549632 1136521760 12.183 100 37 72 1 1213405600 69119264 7764576
07:23:52 114818 8192000000 10069647408 10538057728 1116316368 11.992 98 16 32 2 1174152560 54314512 3521680
07:26:48 22534 8192000000 10105238336 10538512384 1143495840 12.248 100 56 108 1 1199958512 46139200 10323472
07:28:12 59710 8192000000 10095802896 10560237568 1133952880 12.159 99 28 56 0 1191153744 51558288 5642576
07:30:15 57700 8192000000 10101389104 10593705984 1136571088 12.183 99 39 76 1 1197054480 52787984 7695408
07:32:18 127214 8192000000 10084443616 10539200512 1122337408 12.049 98 24 48 0 1173508368 46484656 4686304
07:35:39 79889 8192000000 10116286720 10584002560 1130979536 12.131 99 10 20 1 1197422144 64184160 2258448

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-04-2022 07:39:42 to 29-04-2022 07:39:58.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-04-2022 07:39:59 to 29-04-2022 07:40:01.

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          225246
MON$OLDEST_ACTIVE               225247
MON$OLDEST_SNAPSHOT             225247
MON$NEXT_TRANSACTION            225247
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                       2071648
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:1544: 
:1545: mscs-test-161.budzdorov.ru    Fri Apr 29 07:38:03 2022
:1546:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1547: 
--- end of diff output ---

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

29.04.22 07:40:02. 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-04-29 07:40:04 No severe PSQL-related problems occured
29.04.22 07:40:05. End of report.