Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.10.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 15101508 kB
MemAvailable 30511592 kB
Buffers 0 kB
Cached 16578044 kB
SwapCached 2288 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-08 10:37:23
DTS_END                         2022-10-08 10:37:23.7280
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/44298
TRN_ID                          211971
ATT_ID                          607
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: 8921.91 24635 1070629
customer order: creation 165.80 469 19896
customer order: refuse 13.82 454 1658
order to supplier: creation 43.92 1440 5270
order to supplier: removal 3.45 184682 414
invoice (draft): creation 43.83 3076 5259
invoice (draft): removal 51.79 1081 6215
invoice accept: apply 39.48 27833 4738
invoice accept: cancel 46.47 1292 5576
customer reserve: creation 3707.78 261 444933
customer reserve: removal 4513.60 110 541632
realization accept: apply 79.97 293 9596
realization accept: cancel 88.20 170 10584
payment from customer: creation 57.17 184 6860
payment from customer: removal 5.47 151 656
payment to supplier: creation 46.43 207 5571
payment to supplier: removal 3.15 147 378
service: total inventory turnovers 3.58 4563 429
service: total monetary turnovers 7.09 376 851
service: aggregate perf. data 0.91 14921 109
service: refresh index statistics 0.03 250981 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-10-2022 10:38:28 to 08-10-2022 10:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6413
TEST_TIME 2 5266
TEST_TIME 3 5973
TEST_TIME 4 5973
TEST_TIME 5 5671
TEST_TIME 6 5640
TEST_TIME 7 6245
TEST_TIME 8 6494
TEST_TIME 9 6577
TEST_TIME 10 6531
TEST_TIME 11 6714
TEST_TIME 12 6893
TEST_TIME 13 6774
TEST_TIME 14 6662
TEST_TIME 15 6629
TEST_TIME 16 6644
TEST_TIME 17 6761
TEST_TIME 18 6792
TEST_TIME 19 6824
TEST_TIME 20 7031
TEST_TIME 21 7113
TEST_TIME 22 7268
TEST_TIME 23 7262
TEST_TIME 24 7153
TEST_TIME 25 7238
TEST_TIME 26 7542
TEST_TIME 27 7880
TEST_TIME 28 7840
TEST_TIME 29 7991
TEST_TIME 30 8022
TEST_TIME 31 8137
TEST_TIME 32 8459
TEST_TIME 33 8834
TEST_TIME 34 9158
TEST_TIME 35 9561
TEST_TIME 36 9839
TEST_TIME 37 9814
TEST_TIME 38 10102
TEST_TIME 39 10564
TEST_TIME 40 10416
TEST_TIME 41 10528
TEST_TIME 42 10370
TEST_TIME 43 10215
TEST_TIME 44 10213
TEST_TIME 45 10131
TEST_TIME 46 10199
TEST_TIME 47 10155
TEST_TIME 48 10053
TEST_TIME 49 9928
TEST_TIME 50 9800
TEST_TIME 51 9747
TEST_TIME 52 9714
TEST_TIME 53 9754
TEST_TIME 54 9698
TEST_TIME 55 9610
TEST_TIME 56 9602
TEST_TIME 57 9718
TEST_TIME 58 9691
TEST_TIME 59 9572
TEST_TIME 60 9556
TEST_TIME 61 9489
TEST_TIME 62 9419
TEST_TIME 63 9300
TEST_TIME 64 9192
TEST_TIME 65 9187
TEST_TIME 66 9175
TEST_TIME 67 9154
TEST_TIME 68 9221
TEST_TIME 69 9212
TEST_TIME 70 9203
TEST_TIME 71 9268
TEST_TIME 72 9259
TEST_TIME 73 9266
TEST_TIME 74 9293
TEST_TIME 75 9409
TEST_TIME 76 9374
TEST_TIME 77 9371
TEST_TIME 78 9390
TEST_TIME 79 9380
TEST_TIME 80 9305
TEST_TIME 81 9242
TEST_TIME 82 9184
TEST_TIME 83 9182
TEST_TIME 84 9107
TEST_TIME 85 9055
TEST_TIME 86 9048
TEST_TIME 87 9046
TEST_TIME 88 9073
TEST_TIME 89 9040
TEST_TIME 90 9049
TEST_TIME 91 9012
TEST_TIME 92 9009
TEST_TIME 93 9033
TEST_TIME 94 9108
TEST_TIME 95 9133
TEST_TIME 96 9188
TEST_TIME 97 9234
TEST_TIME 98 9216
TEST_TIME 99 9286
TEST_TIME 100 9262
TEST_TIME 101 9244
TEST_TIME 102 9258
TEST_TIME 103 9200
TEST_TIME 104 9159
TEST_TIME 105 9112
TEST_TIME 106 9115
TEST_TIME 107 9062
TEST_TIME 108 9059
TEST_TIME 109 9019
TEST_TIME 110 8988
TEST_TIME 111 8981
TEST_TIME 112 8976
TEST_TIME 113 8992
TEST_TIME 114 8998
TEST_TIME 115 8987
TEST_TIME 116 8974
TEST_TIME 117 9049
TEST_TIME 118 9026
TEST_TIME 119 9007
TEST_TIME 120 8951

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-10-2022 10:38:32 to 08-10-2022 10:38: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 1480833 1480833 0 0.00 0 15767 14 0 0
doc_list_biud 1480823 1480823 0 0.00 0 35251 46 0 0
sp_add_invoice_to_stock 4823 4738 85 1.76 58 215037 27833 0 85
sp_cancel_adding_invoice 5576 5576 0 0.00 6 67495 1292 0 0
sp_cancel_client_order 1658 1658 0 0.00 6 13740 455 0 0
sp_cancel_customer_reserve 541632 541632 0 0.00 1 20500 110 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 5986 151 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 4 7859 147 0 0
sp_cancel_supplier_invoice 6221 6215 6 0.09 2 24154 1081 0 6
sp_cancel_supplier_order 414 414 0 0.00 37 1664013 184682 0 0
sp_cancel_write_off 10584 10584 0 0.00 2 12141 171 0 0
sp_client_order 19896 19896 0 0.00 5 14211 470 0 0
sp_customer_reserve 444933 444933 0 0.00 1 95361 261 0 0
sp_fill_shopping_cart 30411 30411 0 0.00 2 11776 79 0 0
sp_fill_shopping_cart_clo_res 444905 444905 0 0.00 0 21464 18 0 0
sp_get_clo_for_invoice 4706 4706 0 0.00 0 26436 283 0 0
sp_kill_cost_storno 17194 17194 0 0.00 0 10609 82 0 0
sp_kill_qstorno_ret_qs2qd 1100477 1100477 0 0.00 0 24146 48 0 0
sp_kill_qty_storno 580378 580378 0 0.00 0 35251 110 0 0
sp_lock_selected_doc 19868 19868 0 0.00 0 7385 9 0 0
sp_make_cost_storno 26732 26732 0 0.00 0 15749 90 0 0
sp_multiply_rows_for_pdistr 26729 26729 0 0.00 0 6130 40 0 0
sp_multiply_rows_for_qdistr 19895 19895 0 0.00 0 10650 174 0 0
sp_pay_from_customer 6860 6860 0 0.00 5 9451 184 0 0
sp_pay_to_supplier 5571 5571 0 0.00 6 12805 207 0 0
sp_payment_common 12430 12430 0 0.00 2 9611 48 0 0
sp_qd_handle_on_cancel_clo 1658 1658 0 0.00 0 13719 383 0 0
sp_qd_handle_on_invoice_upd_sts 10279 10279 0 0.00 0 35249 561 0 0
sp_qd_handle_on_reserve_upd_sts 9596 9596 0 0.00 0 18948 65 0 0
sp_reserve_write_off 9596 9596 0 0.00 9 19008 294 0 0
sp_supplier_invoice 5259 5259 0 0.00 8 81055 3077 0 0
sp_supplier_order 5270 5270 0 0.00 8 59869 1441 0 0
srv_aggregate_perf_data 109 109 0 0.00 2216 240903 14921 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 754 125568 32953 0 0
srv_find_qd_qs_mism 1023884 1023884 0 0.00 0 10602 10 0 0
srv_make_invnt_saldo 429 429 0 0.00 65 60024 4563 0 0
srv_make_money_saldo 851 851 0 0.00 1 17338 377 0 0
srv_recalc_idx_stat 4 4 0 0.00 51828 441513 250981 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4745 4745 0 0.00 0 12006 1016 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 5608 490 0 0
t$perf-norm:sp_cancel_client_order 1658 1658 0 0.00 0 6178 35 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 1347 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 2346 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 1049 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 1006 38 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 1 106129 8042 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 2705 27 0 0
t$perf-norm:sp_client_order 19896 19896 0 0.00 0 10369 41 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 2947 19 0 0
t$perf-norm:sp_pay_from_customer 6860 6860 0 0.00 0 9658 22 0 0
t$perf-norm:sp_pay_to_supplier 5571 5571 0 0.00 0 8834 22 0 0
t$perf-norm:sp_reserve_write_off 9596 9596 0 0.00 0 3750 24 0 0
t$perf-norm:sp_supplier_invoice 5259 5259 0 0.00 0 4156 33 0 0
t$perf-norm:sp_supplier_order 5270 5270 0 0.00 0 6572 35 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 706 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 16 3 0 0
t$perf-norm:srv_make_invnt_saldo 429 429 0 0.00 0 706 19 0 0
t$perf-norm:srv_make_money_saldo 851 851 0 0.00 0 274 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 168 71 0 0
v_add_invoice_to_stock 9566 9396 170 1.77 0 5130 25 0 170
v_all_customers 37142 37142 0 0.00 0 285 4 0 0
v_all_suppliers 29662 29662 0 0.00 0 149 4 0 0
v_all_wares 39784 39784 0 0.00 0 288 4 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1974 17 0 0
v_cancel_client_order 3316 3316 0 0.00 0 922 9 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 3745 15 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 871 10 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 581 11 0 0
v_cancel_supplier_invoice 862 850 12 1.39 0 1659 24 0 12
v_cancel_supplier_order 828 828 0 0.00 0 696 12 0 0
v_cancel_write_off 1958 1958 0 0.00 0 4305 24 0 0
v_max_id_clo_ord 5267 5267 0 0.00 0 187 5 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 2384 12 0 0
v_max_id_ord_sup 5252 5252 0 0.00 0 4964 8 0 0
v_max_non_paid_invoice 1258 1258 0 0.00 0 103 8 0 0
v_max_non_paid_realizn 3329 3329 0 0.00 0 2082 8 0 0
v_min_id_clo_ord 5267 5267 0 0.00 0 1502 9 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 1917 10 0 0
v_min_id_ord_sup 5252 5252 0 0.00 0 3998 8 0 0
v_min_non_paid_invoice 5570 5570 0 0.00 0 9589 20 0 0
v_min_non_paid_realizn 6860 6860 0 0.00 0 6679 18 0 0
v_our_firm 9704 9704 0 0.00 0 97 5 0 0
v_reserve_write_off 19192 19192 0 0.00 0 2151 10 0 0
x_lock_depdocs_on_canc_invoice 11366 11366 0 0.00 0 15196 130 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 3494 363 0 0
x_make_qstorno_1000_1200 5270 5270 0 0.00 2 59850 1272 0 0
x_make_qstorno_1200_2000 5259 5259 0 0.00 4 81041 2861 0 0
x_make_qstorno_2100_3300 444931 444931 0 0.00 0 95237 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-10-2022 10:38:36 to 08-10-2022 10:38: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
08:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:28 35136 8192000000 9726249952 10025775104 794730384 8.843 100 86 172 0 824704352 8456176 21517792
08:10:14 22349 8192000000 9763381264 10072018944 836526992 9.265 100 91 182 0 871951616 13571216 21853408
08:11:45 15945 8192000000 9806249664 10128257024 873850016 9.638 100 79 155 0 911145344 19676224 17619104
08:13:12 8882 8192000000 9866178656 10199678976 915486464 10.052 100 78 154 0 975045584 42949904 16609216
08:14:24 4315 8192000000 9920727248 10262814720 946279552 10.355 100 83 164 2 1033557424 69293776 17984096
08:15:32 1532 8192000000 9974295360 10328375296 981873984 10.702 100 87 167 0 1089235712 90868288 16493440
08:16:35 849 8192000000 9987533616 10348048384 1020155296 11.074 100 97 189 1 1107584672 68078768 19350608
08:17:38 1204 8192000000 10016892960 10389270528 1044201760 11.305 100 93 179 1 1137349408 75105392 18042256
08:18:41 898 8192000000 10030595280 10405085184 1054868576 11.407 100 88 168 0 1150981824 78604528 17508720
08:19:44 1180 8192000000 10048331312 10427183104 1058611184 11.443 100 94 187 0 1165901552 88885344 18405024
08:20:49 1300 8192000000 10030102592 10407428096 1066031488 11.514 100 89 173 1 1149651136 66143984 17475664
08:21:51 1123 8192000000 10050895408 10432380928 1071247808 11.564 100 94 181 2 1171900048 82420992 18231248
08:22:54 1347 8192000000 10030112592 10414215168 1075680176 11.606 100 97 184 0 1155159808 61593648 17885984
08:23:58 1048 8192000000 10059774384 10448007168 1081559920 11.662 100 95 188 0 1183354720 81769792 20025008
08:25:00 4016 8192000000 10041764400 10428518400 1083821328 11.684 100 63 115 1 1166899456 72026976 11051152
08:26:04 3365 8192000000 10065585584 10459455488 1089971552 11.742 100 86 160 4 1184958928 79181504 15805872
08:27:09 1226 8192000000 10077220432 10470711296 1095747872 11.797 100 99 189 3 1199984016 84815584 19420560
08:28:11 1792 8192000000 10044403456 10435534848 1093676352 11.778 100 77 147 4 1163710928 55031776 15002800
08:29:14 3642 8192000000 10030681408 10419183616 1085024128 11.695 100 64 117 1 1148793408 51662672 12106608
08:30:20 4056 8192000000 10000395744 10384080896 1063954528 11.494 99 69 120 2 1132675984 56585584 12135872
08:31:25 1168 8192000000 10040296976 10422284288 1068913664 11.542 99 95 184 4 1160547520 72719200 18914656
08:32:27 1098 8192000000 10049606672 10431131648 1073786128 11.588 100 95 181 1 1173714800 81150528 18778144
08:33:29 1104 8192000000 9999420688 10374754304 1039940352 11.264 100 93 179 2 1121282944 62148032 19194560
08:34:31 1099 8192000000 10020775408 10393645056 1045407664 11.317 100 100 192 2 1144888512 80420304 19060544
08:35:34 2591 8192000000 10000479152 10378792960 1034716080 11.214 100 74 133 3 1130185024 82403280 13065664
08:36:38 1114 8192000000 9971477904 10349699072 1032529888 11.193 100 75 134 3 1099949168 52937584 14481696
08:37:42 885 8192000000 10005763248 10377613312 1045376016 11.316 100 92 168 2 1115322608 53846432 16100160
08:38:54 1235 8192000000 10001490784 10373595136 1047745184 11.339 100 90 169 0 1126557168 61118528 17693456
08:40:00 1508 8192000000 10037035488 10423894016 1064462272 11.499 100 78 148 0 1157324912 77339216 15523424
08:41:07 3283 8192000000 10065555552 10455146496 1087634256 11.720 100 62 122 0 1183357472 83424992 12298224
08:42:15 11048 8192000000 10089825392 10480750592 1102146896 11.858 100 69 134 0 1202914720 86489376 14278448
08:43:29 2087 8192000000 10147211392 10540077056 1118963408 12.017 100 87 158 0 1265706848 128601312 18142128
08:44:38 7781 8192000000 10139525328 10550009856 1130361664 12.125 100 76 147 1 1265588528 119232176 15994688
08:45:48 1192 8192000000 10179218496 10578038784 1145380432 12.266 100 86 166 1 1291014096 128229808 17403856
08:46:53 1168 8192000000 10222466160 10615681024 1149759184 12.307 100 93 182 0 1336813680 167925248 19129248
08:47:59 1268 8192000000 10238985824 10633338880 1156081472 12.367 100 95 180 1 1351701376 175745856 19874048
08:49:05 5831 8192000000 10274296656 10676736000 1164627376 12.447 100 76 150 0 1386842848 205681280 16534192
08:50:16 3580 8192000000 10290190128 10690588672 1173913184 12.533 100 89 164 1 1402122384 210154448 18054752
08:51:25 912 8192000000 10338632000 10738036736 1178074512 12.572 100 90 176 1 1441875600 244081360 19719728
08:52:32 2164 8192000000 10374734320 10776948736 1187958224 12.664 100 93 173 0 1479702560 271905792 19838544
08:53:39 1674 8192000000 10358225264 10781466624 1187191104 12.657 100 80 149 0 1468044864 262582832 18270928
08:54:47 4524 8192000000 10411556768 10827481088 1200060064 12.777 100 76 151 0 1519941024 302045312 17835648
08:56:01 897 8192000000 10444682240 10872254464 1209521216 12.865 100 94 187 1 1554932608 323328048 22083344
08:57:06 1546 8192000000 10474883328 10908778496 1213104064 12.898 100 97 184 1 1582486800 345812160 23570576
08:58:12 747 8192000000 10494898336 10920144896 1218892944 12.951 100 95 188 0 1600373904 359472656 22008304
08:59:18 916 8192000000 10510549760 10935447552 1213225504 12.899 100 79 156 2 1619040448 386904224 18910720
09:00:24 2006 8192000000 10533830960 10959171584 1206272624 12.835 100 73 146 0 1641950016 418122592 17554800
09:01:54 2905 8192000000 10541436320 10985906176 1200818944 12.784 100 90 170 0 1659329872 438180256 20330672
09:02:59 1185 8192000000 10562654144 11029626880 1219510992 12.957 100 89 165 0 1667214944 425591008 22112944
09:04:05 842 8192000000 10523211088 11008229376 1206959120 12.841 100 58 112 1 1637313472 416144736 14209616
09:05:11 3167 8192000000 10507503248 10948800512 1176776224 12.560 98 75 135 0 1601290704 407088144 17426336
09:06:27 3529 8192000000 10511254032 10977050624 1191363856 12.696 100 62 124 1 1619875664 412071104 16440704
09:07:35 11042 8192000000 10535914704 11015622656 1187806784 12.663 99 49 98 0 1668112784 467595792 12710208
09:08:48 1285 8192000000 10536055552 11044655104 1204673968 12.820 100 79 152 0 1641661440 418301104 18686368
09:09:54 10208 8192000000 10546229040 11015278592 1185682720 12.643 100 42 84 0 1649263920 452347088 11234112
09:11:09 25417 8192000000 10420526864 10913714176 1182674272 12.615 100 38 72 2 1545771920 353649840 9447808
09:12:39 24730 8192000000 10299685040 10811461632 1159205568 12.396 100 31 61 0 1419832592 252731040 7895984
09:14:05 29655 8192000000 10281770848 10739367936 1145922784 12.271 99 41 80 1 1362725680 206851696 9951200
09:15:38 38805 8192000000 10100265264 10663804928 1108231776 11.916 97 11 22 0 1278703936 167725104 2747056
09:17:20 82783 8192000000 10070245008 10555174912 1123318288 12.058 99 14 26 0 1199844672 72976192 3550192
09:19:44 75856 8192000000 10084838112 10508009472 1065783840 11.512 93 20 39 1 1123523168 53415904 4323424
09:22:01 49573 8192000000 10091684352 10511437824 1107339344 11.907 98 24 46 1 1176242464 64287984 4615136
09:23:53 48946 8192000000 10142109632 10600173568 1138144160 12.198 100 28 55 0 1237437664 92635376 6658128
09:25:43 125568 8192000000 10135997424 10587283456 1102223024 11.859 98 29 54 0 1212327792 103849280 6255488
09:28:59 72350 8192000000 10080099504 10543972352 1122859664 12.054 99 26 50 3 1197389360 69586896 4942800
09:31:24 35894 8192000000 10105297728 10554036224 1140695008 12.222 100 21 40 2 1216162016 71913984 3553024
09:33:12 48961 8192000000 10149577440 10624466944 1137254336 12.190 100 29 58 0 1252676736 109251104 6171296
09:35:07 43123 8192000000 10061972736 10560176128 1113477568 11.965 98 13 27 0 1167271168 51102864 2690736
09:36:59 25716 8192000000 10087016768 10539331584 1131486992 12.135 100 42 83 1 1178526336 39066224 7973120
09:38:31 14813 8192000000 10042199040 10502213632 1127524256 12.098 99 3 6 0 1143819872 15966976 328640
09:39:46 46511 8192000000 10047605808 10509709312 1116111424 11.990 98 11 22 0 1157343488 38970080 2261984
09:41:33 22157 8192000000 10070859312 10507747328 1117728560 12.006 98 37 72 2 1176409664 51204560 7476544
09:42:56 88351 8192000000 10110705616 10601811968 1128304944 12.105 99 11 22 0 1205548704 75019392 2224368
09:45:37 45593 8192000000 10137549248 10580955136 1147600768 12.287 100 27 54 0 1228574528 75335040 5638720
09:47:25 86128 8192000000 10113728736 10604036096 1136759168 12.185 99 13 26 0 1198113344 58296608 3057568
09:50:05 31826 8192000000 10176831264 10620674048 1137355408 12.191 99 48 93 1 1241739968 95126464 9258096
09:51:42 64706 8192000000 10066847568 10530615296 1128364320 12.106 99 17 30 1 1176929776 46289840 2275616
09:53:48 18641 8192000000 10106794336 10536550400 1136239728 12.180 100 47 92 1 1193295248 47722288 9333232
09:55:09 12734 8192000000 10127396368 10561331200 1121368688 12.040 99 42 84 1 1204953056 75195056 8389312
09:56:23 59908 8192000000 10079593648 10526502912 1124858880 12.073 99 33 66 0 1172112080 39979664 7273536
09:58:44 57166 8192000000 10095944704 10558906368 1142194432 12.236 100 17 32 0 1192115216 46669296 3251488
10:00:42 110688 8192000000 10136479440 10632323072 1124543696 12.070 98 12 24 0 1214534560 87208992 2781872
10:03:33 92125 8192000000 10125951760 10623287296 1131116608 12.132 100 14 28 0 1208735152 74322448 3296096
10:06:07 62332 8192000000 10102921632 10570739712 1137129584 12.189 100 11 22 0 1216766192 77105680 2530928
10:08:14 19352 8192000000 10128658624 10566033408 1116295168 11.992 97 37 72 0 1197740240 73736368 7708704
10:09:34 9096 8192000000 10155930480 10584829952 1124737216 12.072 99 40 75 0 1244563312 111294064 8532032
10:10:44 39487 8192000000 10188396528 10664669184 1134500496 12.164 99 30 58 0 1290530304 149185536 6844272
10:12:29 80390 8192000000 10118317792 10571014144 1124944272 12.074 99 20 38 1 1227529184 98595888 3989024
10:14:51 10873 8192000000 10096073760 10522107904 1117723808 12.005 98 59 119 0 1171752048 42171232 11857008
10:16:09 75039 8192000000 10063561168 10502672384 1067272544 11.526 94 34 66 0 1119256000 44777552 7205904
10:18:24 19837 8192000000 10117071232 10543759360 1132112848 12.141 100 51 98 0 1195336576 53692464 9531264
10:19:51 113690 8192000000 10055778816 10501853184 1089893888 11.742 96 25 49 0 1127772400 32969568 4908944
10:22:46 39625 8192000000 10076886224 10541944832 1122496544 12.051 99 22 39 0 1174875040 48076944 4301552
10:24:32 62315 8192000000 10086660640 10572013568 1113093536 11.962 98 8 16 0 1169765728 54915264 1756928
10:26:40 100184 8192000000 10106329200 10568908800 1102805136 11.864 97 15 29 0 1151285776 45456432 3024208
10:29:36 97829 8192000000 10083348048 10584403968 1131178704 12.132 99 17 34 0 1206054976 70964400 3911872
10:32:17 38943 8192000000 10104125456 10563706880 1106043200 11.895 96 26 52 0 1167659280 56165440 5450640
10:33:57 64467 8192000000 10097460096 10620858368 1118593824 12.014 98 23 44 0 1212001120 88482960 4924336
10:36:02 16311 8192000000 10096488608 10530103296 1139236464 12.208 100 43 73 0 1178217136 31285120 7695552
10:37:19 19200 8192000000 9283521600 9613197312 392988896 4.577 35 7 14 0 458425952 64133616 1303440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 08-10-2022 10:38:42 to 08-10-2022 10:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

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

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          211970
MON$OLDEST_ACTIVE               213308
MON$OLDEST_SNAPSHOT             213308
MON$NEXT_TRANSACTION            213308
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                       2077728
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2856 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2856 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2856 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2856 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Sat Oct  8 10:37:48 2022
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Sat Oct  8 10:37:56 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

08.10.22 10:39:03. 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-10-08 10:39:06 No severe PSQL-related problems occured
08.10.22 10:39:07. End of report.