Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.2022 13: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 14009104 kB
MemAvailable 30041644 kB
Buffers 0 kB
Cached 17647156 kB
SwapCached 3232 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-12-16 13:37:22
DTS_END                         2022-12-16 13:37:22.2360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38274
TRN_ID                          223913
ATT_ID                          611
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: 8802.56 30660 1056307
customer order: creation 175.08 477 21010
customer order: refuse 15.24 528 1829
order to supplier: creation 47.38 1417 5686
order to supplier: removal 3.29 195393 395
invoice (draft): creation 47.05 3347 5646
invoice (draft): removal 48.36 1176 5803
invoice accept: apply 41.72 27941 5006
invoice accept: cancel 42.99 1563 5159
customer reserve: creation 3844.99 263 461399
customer reserve: removal 4240.09 118 508811
realization accept: apply 85.63 320 10276
realization accept: cancel 78.06 185 9367
payment from customer: creation 60.00 198 7200
payment from customer: removal 6.03 160 723
payment to supplier: creation 50.44 217 6053
payment to supplier: removal 3.18 161 382
service: total inventory turnovers 3.81 4976 457
service: total monetary turnovers 8.15 472 978
service: aggregate perf. data 1.03 15629 123
service: refresh index statistics 0.03 358649 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2022 13:38:30 to 16-12-2022 13:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6117
TEST_TIME 2 6255
TEST_TIME 3 6582
TEST_TIME 4 6323
TEST_TIME 5 6145
TEST_TIME 6 6200
TEST_TIME 7 6281
TEST_TIME 8 6175
TEST_TIME 9 6224
TEST_TIME 10 6162
TEST_TIME 11 6232
TEST_TIME 12 6359
TEST_TIME 13 6500
TEST_TIME 14 6539
TEST_TIME 15 6626
TEST_TIME 16 6554
TEST_TIME 17 6596
TEST_TIME 18 6847
TEST_TIME 19 6922
TEST_TIME 20 7061
TEST_TIME 21 7304
TEST_TIME 22 7455
TEST_TIME 23 7661
TEST_TIME 24 7772
TEST_TIME 25 7916
TEST_TIME 26 7880
TEST_TIME 27 7954
TEST_TIME 28 8037
TEST_TIME 29 8019
TEST_TIME 30 9064
TEST_TIME 31 9046
TEST_TIME 32 9043
TEST_TIME 33 9093
TEST_TIME 34 9437
TEST_TIME 35 9743
TEST_TIME 36 10041
TEST_TIME 37 10227
TEST_TIME 38 10100
TEST_TIME 39 10166
TEST_TIME 40 10252
TEST_TIME 41 10265
TEST_TIME 42 10228
TEST_TIME 43 10402
TEST_TIME 44 10408
TEST_TIME 45 10335
TEST_TIME 46 10184
TEST_TIME 47 10142
TEST_TIME 48 10391
TEST_TIME 49 10536
TEST_TIME 50 10356
TEST_TIME 51 10207
TEST_TIME 52 10167
TEST_TIME 53 10069
TEST_TIME 54 10104
TEST_TIME 55 10120
TEST_TIME 56 10005
TEST_TIME 57 9947
TEST_TIME 58 9897
TEST_TIME 59 9799
TEST_TIME 60 9746
TEST_TIME 61 9804
TEST_TIME 62 9708
TEST_TIME 63 9564
TEST_TIME 64 9526
TEST_TIME 65 9550
TEST_TIME 66 9477
TEST_TIME 67 9416
TEST_TIME 68 9375
TEST_TIME 69 9332
TEST_TIME 70 9288
TEST_TIME 71 9270
TEST_TIME 72 9245
TEST_TIME 73 9253
TEST_TIME 74 9296
TEST_TIME 75 9284
TEST_TIME 76 9279
TEST_TIME 77 9334
TEST_TIME 78 9399
TEST_TIME 79 9348
TEST_TIME 80 9427
TEST_TIME 81 9396
TEST_TIME 82 9362
TEST_TIME 83 9342
TEST_TIME 84 9254
TEST_TIME 85 9203
TEST_TIME 86 9186
TEST_TIME 87 9145
TEST_TIME 88 9144
TEST_TIME 89 9107
TEST_TIME 90 9044
TEST_TIME 91 9004
TEST_TIME 92 8958
TEST_TIME 93 8933
TEST_TIME 94 8923
TEST_TIME 95 8962
TEST_TIME 96 8981
TEST_TIME 97 8971
TEST_TIME 98 8946
TEST_TIME 99 8944
TEST_TIME 100 8944
TEST_TIME 101 9020
TEST_TIME 102 9014
TEST_TIME 103 9013
TEST_TIME 104 9044
TEST_TIME 105 9057
TEST_TIME 106 9057
TEST_TIME 107 9001
TEST_TIME 108 8990
TEST_TIME 109 8929
TEST_TIME 110 8934
TEST_TIME 111 8901
TEST_TIME 112 8867
TEST_TIME 113 8863
TEST_TIME 114 8806
TEST_TIME 115 8850
TEST_TIME 116 8834
TEST_TIME 117 8817
TEST_TIME 118 8829
TEST_TIME 119 8862
TEST_TIME 120 8827

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-12-2022 13:38:33 to 16-12-2022 13:38:37.

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 1482506 1482506 0 0.00 0 25356 15 0 0
doc_list_biud 1482494 1482494 0 0.00 0 25563 48 0 0
sp_add_invoice_to_stock 5121 5006 115 2.24 79 189263 27942 0 115
sp_cancel_adding_invoice 5159 5159 0 0.00 5 138010 1564 0 0
sp_cancel_client_order 1829 1829 0 0.00 6 11864 529 0 0
sp_cancel_customer_reserve 508811 508811 0 0.00 1 24975 119 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 3 5624 161 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 4 7610 162 0 0
sp_cancel_supplier_invoice 5815 5803 12 0.20 3 14604 1176 0 12
sp_cancel_supplier_order 395 395 0 0.00 30 1655409 195393 0 0
sp_cancel_write_off 9367 9367 0 0.00 2 16463 186 0 0
sp_client_order 21010 21010 0 0.00 5 30632 478 0 0
sp_customer_reserve 461399 461399 0 0.00 0 61944 264 0 0
sp_fill_shopping_cart 32336 32336 0 0.00 1 16967 93 0 0
sp_fill_shopping_cart_clo_res 461364 461364 0 0.00 0 17451 20 0 0
sp_get_clo_for_invoice 4966 4966 0 0.00 0 20641 278 0 0
sp_kill_cost_storno 15631 15631 0 0.00 0 8876 84 0 0
sp_kill_qstorno_ret_qs2qd 1033187 1033187 0 0.00 0 24960 51 0 0
sp_kill_qty_storno 546604 546604 0 0.00 0 25559 119 0 0
sp_lock_selected_doc 21166 21166 0 0.00 0 9718 9 0 0
sp_make_cost_storno 28495 28495 0 0.00 0 25352 90 0 0
sp_multiply_rows_for_pdistr 28493 28493 0 0.00 0 9782 41 0 0
sp_multiply_rows_for_qdistr 21010 21010 0 0.00 0 14342 169 0 0
sp_pay_from_customer 7200 7200 0 0.00 5 10269 198 0 0
sp_pay_to_supplier 6053 6053 0 0.00 7 11376 217 0 0
sp_payment_common 13252 13252 0 0.00 2 11362 53 0 0
sp_qd_handle_on_cancel_clo 1829 1829 0 0.00 0 11820 440 0 0
sp_qd_handle_on_invoice_upd_sts 10125 10125 0 0.00 0 25556 667 0 0
sp_qd_handle_on_reserve_upd_sts 10274 10274 0 0.00 0 18695 69 0 0
sp_reserve_write_off 10276 10276 0 0.00 9 25372 320 0 0
sp_supplier_invoice 5646 5646 0 0.00 8 76482 3348 0 0
sp_supplier_order 5686 5686 0 0.00 7 31375 1417 0 0
srv_aggregate_perf_data 124 123 1 0.80 2411 156638 15629 0 1
srv_fill_mon_cache_memory 75 75 0 0.00 720 100489 29967 0 0
srv_find_qd_qs_mism 1007385 1007385 0 0.00 0 22903 11 0 0
srv_make_invnt_saldo 457 457 0 0.00 42 76430 4976 0 0
srv_make_money_saldo 978 978 0 0.00 1 35026 472 0 0
srv_recalc_idx_stat 4 4 0 0.00 103014 472049 358650 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5009 5009 0 0.00 1 13862 1145 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 2 5989 587 0 0
t$perf-norm:sp_cancel_client_order 1829 1829 0 0.00 0 3009 35 0 0
t$perf-norm:sp_cancel_customer_reserve 733 733 0 0.00 0 1806 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 3963 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 2319 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 1665 47 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 128175 9521 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 856 22 0 0
t$perf-norm:sp_client_order 21010 21010 0 0.00 0 7712 44 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 831 16 0 0
t$perf-norm:sp_pay_from_customer 7201 7201 0 0.00 0 7999 23 0 0
t$perf-norm:sp_pay_to_supplier 6053 6053 0 0.00 0 11683 26 0 0
t$perf-norm:sp_reserve_write_off 10276 10276 0 0.00 0 8022 26 0 0
t$perf-norm:sp_supplier_invoice 5646 5646 0 0.00 0 6397 36 0 0
t$perf-norm:sp_supplier_order 5686 5686 0 0.00 0 5335 40 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 1470 39 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 226 7 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 4382 34 0 0
t$perf-norm:srv_make_money_saldo 978 978 0 0.00 0 515 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 9 184 91 0 0
v_add_invoice_to_stock 10156 9926 230 2.26 0 7465 30 0 230
v_all_customers 38676 38676 0 0.00 0 95 5 0 0
v_all_suppliers 31978 31978 0 0.00 0 92 5 0 0
v_all_wares 42020 42020 0 0.00 0 97 5 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 662 17 0 0
v_cancel_client_order 3658 3658 0 0.00 0 3526 13 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 3070 17 0 0
v_cancel_customer_reserve 1466 1466 0 0.00 1 4733 24 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 7601 23 0 0
v_cancel_supplier_invoice 914 890 24 2.62 0 1169 27 0 24
v_cancel_supplier_order 790 790 0 0.00 0 1738 18 0 0
v_cancel_write_off 1978 1978 0 0.00 0 4223 29 0 0
v_max_id_clo_ord 5680 5680 0 0.00 0 753 6 0 0
v_max_id_clo_res 990 990 0 0.00 0 12449 29 0 0
v_max_id_ord_sup 5642 5642 0 0.00 0 4748 9 0 0
v_max_non_paid_invoice 1386 1386 0 0.00 0 654 10 0 0
v_max_non_paid_realizn 3757 3757 0 0.00 0 4273 10 0 0
v_min_id_clo_ord 5680 5680 0 0.00 0 4612 9 0 0
v_min_id_clo_res 990 990 0 0.00 0 2489 12 0 0
v_min_id_ord_sup 5642 5642 0 0.00 0 3134 9 0 0
v_min_non_paid_invoice 6053 6053 0 0.00 0 11353 20 0 0
v_min_non_paid_realizn 7199 7199 0 0.00 0 10050 18 0 0
v_our_firm 10226 10226 0 0.00 0 64 6 0 0
v_reserve_write_off 20548 20548 0 0.00 0 8727 14 0 0
x_lock_depdocs_on_canc_invoice 10517 10517 0 0.00 0 13194 149 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 8081 423 0 0
x_make_qstorno_1000_1200 5686 5686 0 0.00 2 31357 1225 0 0
x_make_qstorno_1200_2000 5646 5646 0 0.00 4 76397 3110 0 0
x_make_qstorno_2100_3300 461397 461397 0 0.00 0 61942 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2022 13:38:38 to 16-12-2022 13:38:42.

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
11:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:26 22153 8192000000 9733719952 10033082368 811648816 9.014 100 85 170 0 839973888 7638080 20686992
11:09:53 21209 8192000000 9769787184 10079793152 846604912 9.366 100 90 180 0 879894400 12030384 21259104
11:11:27 10499 8192000000 9796291248 10113843200 865663600 9.557 100 89 178 0 907221728 21196688 20361440
11:12:44 8433 8192000000 9850759472 10181193728 902466960 9.923 100 86 169 0 963103312 42483216 18153136
11:13:55 5403 8192000000 9916275024 10257903616 944715104 10.339 100 93 178 0 1032644480 69586224 18343152
11:15:05 2677 8192000000 9964690000 10315575296 988670016 10.769 100 89 175 1 1083795120 76584320 18540784
11:16:09 2271 8192000000 9983245840 10346700800 1009573824 10.971 100 87 165 1 1106079312 79904160 16601328
11:17:13 2483 8192000000 10020980496 10385649664 1034864160 11.215 100 89 174 0 1138486064 86463248 17158656
11:18:17 1066 8192000000 10022235888 10401107968 1050792352 11.368 100 96 181 0 1146607392 76555920 19259120
11:19:21 821 8192000000 10045275552 10422042624 1061225248 11.468 100 97 187 2 1167673184 87988336 18459600
11:20:24 1224 8192000000 10048456480 10433179648 1064096576 11.496 100 92 171 3 1158695552 77577792 17021184
11:21:26 1481 8192000000 10024086688 10410545152 1068719424 11.540 100 89 163 2 1154836864 69815696 16301744
11:22:29 1110 8192000000 10029410544 10412617728 1077175504 11.621 100 92 178 3 1147844112 52298480 18370128
11:23:31 1449 8192000000 10076716064 10464587776 1080160512 11.649 100 98 191 2 1197840368 98583168 19096688
11:24:34 1293 8192000000 10040299280 10427355136 1085969168 11.704 100 96 186 0 1164691872 60462448 18260256
11:25:36 1432 8192000000 10091487440 10478104576 1094004416 11.781 100 94 184 1 1205785760 93155696 18625648
11:26:39 1193 8192000000 10084370768 10474967040 1096130128 11.801 100 96 183 1 1206862064 92863664 17868272
11:27:41 923 8192000000 10055910064 10451210240 1097834816 11.817 100 88 169 1 1179035072 62437488 18762768
11:28:43 904 8192000000 10058650208 10445197312 1091333072 11.755 100 96 187 0 1179541760 69618016 18590672
11:29:45 1145 8192000000 10035729696 10423959552 1092363536 11.765 100 86 162 3 1160808224 51151456 17293232
11:30:47 1183 8192000000 10045667504 10435502080 1083972608 11.685 100 85 163 2 1167155296 65914432 17268256
11:31:49 1018 8192000000 10019459520 10395414528 1060417264 11.460 100 93 175 2 1141223360 63035088 17771008
11:32:51 1188 8192000000 9961977920 10333126656 1024226000 11.113 99 95 184 2 1086802336 45018256 17558080
11:33:53 828 8192000000 10008948720 10387136512 1043924544 11.302 100 89 170 2 1132855488 71195872 17735072
11:34:55 1255 8192000000 10016780224 10394632192 1033328288 11.200 100 95 180 1 1136595232 83904032 19362912
11:35:57 1024 8192000000 9983586576 10359083008 1035408576 11.221 100 95 186 3 1107432320 52583840 19439904
11:36:59 1623 8192000000 9957658992 10331058176 1024179056 11.112 100 76 140 1 1083023200 46281360 12562784
11:38:09 10352 8192000000 10013950704 10393300992 1048944480 11.351 100 70 137 1 1135679264 72952032 13782752
11:39:24 2397 8192000000 10016615504 10395332608 1062973728 11.485 100 83 155 1 1138576560 60159296 15443536
11:40:32 14823 8192000000 10030034512 10427379712 1079875200 11.646 100 53 104 0 1167408112 77333440 10199472
11:41:55 7858 8192000000 10083842752 10479439872 1100399024 11.841 100 66 127 2 1198318704 83656416 14263264
11:43:06 968 8192000000 10127603248 10513383424 1113271536 11.963 100 92 174 3 1245520480 113663216 18585728
11:44:17 1778 8192000000 10145347456 10546683904 1122963440 12.055 100 87 156 1 1264065632 124021408 17080784
11:45:24 7046 8192000000 10189214160 10584776704 1133572528 12.155 100 81 159 2 1306634880 156095600 16966752
11:46:41 1285 8192000000 10199607536 10598305792 1148242256 12.293 100 87 166 2 1315161056 146812512 20106288
11:47:47 1405 8192000000 10233648256 10629169152 1147152112 12.283 100 94 180 1 1349700400 181291584 21256704
11:48:55 13298 8192000000 10259611328 10671648768 1156316384 12.369 100 67 134 1 1384234000 212608832 15308784
11:50:11 3628 8192000000 10275113824 10690916352 1162731664 12.429 100 71 137 0 1386408880 207973296 15703920
11:51:19 3677 8192000000 10291449904 10697453568 1164037616 12.441 99 83 163 1 1400513280 217054000 19421664
11:52:32 932 8192000000 10354973232 10762514432 1181331024 12.603 100 96 187 1 1463426640 260751648 21343968
11:53:38 1334 8192000000 10398389296 10796859392 1175811664 12.551 100 95 187 1 1497486272 299042448 22632160
11:54:43 2415 8192000000 10394537936 10814865408 1173006800 12.525 100 91 181 0 1506118608 312231808 20880000
11:55:50 1524 8192000000 10431605392 10862436352 1185634240 12.643 100 88 173 0 1543975520 337952816 20388464
11:56:54 1928 8192000000 10431333120 10868355072 1184751920 12.634 100 89 168 2 1541356192 336539120 20065152
11:57:59 2818 8192000000 10431626912 10845237248 1177908768 12.571 100 86 169 0 1539945200 342242992 19793440
11:59:04 1000 8192000000 10478685648 10894946304 1188214832 12.667 100 98 190 0 1577764496 366914576 22635088
12:00:11 1682 8192000000 10477163392 10912321536 1177096032 12.563 100 94 184 0 1576295168 377333920 21865216
12:01:17 881 8192000000 10452643168 10883051520 1169015504 12.488 99 93 182 1 1556754240 364888784 22849952
12:02:21 1164 8192000000 10496140464 10921181184 1185124480 12.638 100 96 184 1 1600663424 394152224 21386720
12:03:26 716 8192000000 10485496816 10893430784 1159338736 12.397 100 97 182 1 1590495456 408742576 22414144
12:04:32 731 8192000000 10535369424 10963001344 1174116672 12.535 100 95 184 0 1640712048 443773872 22821504
12:05:35 796 8192000000 10491316096 10965315584 1165762608 12.457 100 71 137 0 1594275200 411464224 17048368
12:06:39 2228 8192000000 10479991104 11019923456 1166989248 12.469 100 78 150 0 1584865568 398688208 19188112
12:07:43 14895 8192000000 10398851952 10834432000 1156712288 12.372 99 42 84 1 1508100080 340821360 10566432
12:09:49 14893 8192000000 10428969904 10868367360 1152083760 12.329 100 31 62 0 1528123824 367731200 8308864
12:11:05 18945 8192000000 10381889408 10888437760 1157297728 12.378 100 36 71 0 1490946240 325135968 8512544
12:12:26 16208 8192000000 10276306544 10775728128 1153840784 12.346 100 28 54 1 1445367856 284661392 6865680
12:14:02 7922 8192000000 10225499904 10671607808 1147240512 12.284 99 51 95 0 1326288176 168370560 10677104
12:15:14 5854 8192000000 10222305568 10651156480 1151997872 12.328 99 49 93 1 1315985712 152300720 11687120
12:16:24 1182 8192000000 10278521056 10714562560 1167738752 12.476 100 78 150 0 1373891024 189173216 16979056
12:17:33 11531 8192000000 10287016560 10736287744 1170587904 12.502 100 52 102 1 1375481088 192242352 12650832
12:18:46 29504 8192000000 10333598528 10798469120 1173554528 12.530 100 35 70 0 1424920080 242500864 8864688
12:20:28 30538 8192000000 10286980672 10791104512 1160249920 12.406 100 30 60 0 1379875504 212583328 7042256
12:21:59 100489 8192000000 10265133744 10706227200 1156143808 12.367 100 40 79 0 1345950336 180404416 9402112
12:24:40 30946 8192000000 10240211856 10721153024 1131285264 12.133 99 31 59 0 1327403824 188996880 7121680
12:26:14 76940 8192000000 10087357520 10556125184 1133968768 12.159 99 16 32 0 1176295088 38790432 3535888
12:28:33 11221 8192000000 10121362816 10550509568 1140103264 12.217 100 60 113 0 1208668032 55539824 13024944
12:29:46 23824 8192000000 10134757632 10580209664 1147972688 12.290 100 42 81 2 1218174000 61215568 8985744
12:31:11 36251 8192000000 10155799072 10606501888 1140486720 12.220 100 40 72 0 1250983504 102838864 7657920
12:32:52 91747 8192000000 10079369216 10565935104 1110939392 11.941 97 14 28 0 1182539312 68992448 2607472
12:35:47 26340 8192000000 10081525936 10560634880 1137700384 12.194 100 16 32 0 1181618880 40325104 3593392
12:37:14 76929 8192000000 10089912112 10546085888 1113902032 11.969 97 31 63 0 1187180144 66177120 7100992
12:39:31 82032 8192000000 10058556416 10511409152 1127543472 12.098 99 16 30 0 1154865776 24440848 2881456
12:41:54 97786 8192000000 10062757616 10570010624 1123247376 12.058 98 22 44 0 1179974048 52415808 4310864
12:44:39 27607 8192000000 10103791904 10568171520 1149224560 12.302 100 36 71 1 1202906800 46094096 7588144
12:46:09 22287 8192000000 10134640336 10585268224 1148724144 12.298 100 47 93 0 1218894384 59482448 10687792
12:48:21 44879 8192000000 10154915280 10670460928 1147342288 12.285 100 11 22 0 1259263520 109540624 2380608
12:50:07 47965 8192000000 10132178432 10616938496 1099663920 11.834 96 18 36 0 1176854080 73064256 4125904
12:51:56 38871 8192000000 10122860256 10571440128 1130312208 12.124 100 25 48 1 1200938128 65748480 4877440
12:53:40 60040 8192000000 10129527744 10622656512 1102320144 11.860 97 15 30 0 1213945280 108371376 3253760
12:55:42 51996 8192000000 10084446800 10520899584 1064589536 11.500 95 35 64 1 1132014784 59462864 7962384
12:57:50 50736 8192000000 10076786992 10536718336 1118645728 12.014 100 35 66 1 1158826416 33052400 7128288
12:59:41 4267 8192000000 10057917600 10479820800 1094518128 11.786 99 49 96 2 1139899632 35869520 9511984
13:00:47 15810 8192000000 10080969088 10496462848 1117747824 12.006 100 49 98 0 1178304928 51037824 9519280
13:02:07 97716 8192000000 10045045952 10520825856 1120650240 12.033 100 15 30 0 1177855328 53919744 3285344
13:04:55 48767 8192000000 10048973600 10507399168 1080403056 11.651 96 38 76 0 1140953072 53611136 6938880
13:06:47 83422 8192000000 10048318352 10501439488 1091163104 11.754 97 17 34 0 1138158432 43509616 3485712
13:09:11 95925 8192000000 10084456800 10574626816 1123403456 12.059 99 11 22 0 1214811312 89133712 2274144
13:11:48 40424 8192000000 10063669648 10512601088 1130581360 12.127 99 18 36 1 1176455168 42362128 3511680
13:13:35 29980 8192000000 10049373408 10485559296 1111381680 11.945 98 31 62 1 1143035168 24633664 7019824
13:15:06 40491 8192000000 10117744896 10557952000 1138342048 12.200 100 36 72 0 1212840736 67004064 7494624
13:16:53 37463 8192000000 10156971776 10595348480 1128462192 12.107 99 32 59 0 1237218640 102566464 6189984
13:18:33 72354 8192000000 10082807456 10541940736 1095683520 11.797 96 25 50 0 1133967536 33403120 4880896
13:20:47 46393 8192000000 10113633424 10564984832 1139163552 12.208 100 29 58 1 1228384592 83288608 5932432
13:22:38 8394 8192000000 10113540464 10554896384 1134909088 12.168 99 52 104 0 1212009888 66842096 10258704
13:23:48 13196 8192000000 10104708880 10542264320 1134214880 12.161 100 56 111 1 1203368096 57788752 11364464
13:25:05 80002 8192000000 10043508032 10509000704 1101514736 11.852 97 11 20 0 1137504288 33947376 2042176
13:27:34 43641 8192000000 10059090480 10523496448 1122567600 12.051 99 27 53 1 1165901264 38116176 5217488
13:29:18 65439 8192000000 10117941120 10586775552 1146036832 12.272 100 13 26 0 1194646064 45609344 2999888
13:31:23 76449 8192000000 10067516672 10591809536 1106179488 11.896 97 21 38 0 1166097808 55700640 4217680
13:33:40 30357 8192000000 10096186640 10531569664 1109845888 11.931 97 35 70 0 1158573072 41280608 7446576
13:35:14 77756 8192000000 10139592864 10595532800 1124342352 12.068 99 15 30 0 1222744240 95038144 3363744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-12-2022 13:38:44 to 16-12-2022 13:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 115 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2022 13:39:01 to 16-12-2022 13:39:03.

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          220526
MON$OLDEST_ACTIVE               224030
MON$OLDEST_SNAPSHOT             224030
MON$NEXT_TRANSACTION            224030
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                       2149312
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:116: 
:117: mscs-test-161.budzdorov.ru    Fri Dec 16 13:37:24 2022
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Fri Dec 16 13:37:25 2022
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:123: 
:124: 
:125: mscs-test-161.budzdorov.ru    Fri Dec 16 13:37:27 2022
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
--- end of diff output ---

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

16.12.22 13:39:05. 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-12-16 13:39:07 No severe PSQL-related problems occured
16.12.22 13:39:08. End of report.