Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.08.2022 07:47
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 15537092 kB
MemAvailable 31011668 kB
Buffers 0 kB
Cached 16189728 kB
SwapCached 2512 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-08-03 07:46:47
DTS_END                         2022-08-03 07:46:47.0900
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/34418
TRN_ID                          221848
ATT_ID                          630
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: 9054.36 23676 1086523
customer order: creation 175.41 458 21049
customer order: refuse 14.23 396 1707
order to supplier: creation 46.68 1347 5602
order to supplier: removal 3.25 170654 390
invoice (draft): creation 47.02 3111 5642
invoice (draft): removal 50.89 994 6107
invoice accept: apply 42.25 24249 5070
invoice accept: cancel 45.34 1159 5441
customer reserve: creation 3906.03 228 468724
customer reserve: removal 4431.67 98 531800
realization accept: apply 84.43 298 10131
realization accept: cancel 76.08 157 9130
payment from customer: creation 59.73 182 7168
payment from customer: removal 6.00 136 720
payment to supplier: creation 48.72 207 5846
payment to supplier: removal 3.81 140 457
service: total inventory turnovers 4.08 3935 490
service: total monetary turnovers 7.79 340 935
service: aggregate perf. data 0.92 15751 110
service: refresh index statistics 0.03 249684 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-08-2022 07:48:17 to 03-08-2022 07:48:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6237
TEST_TIME 2 6894
TEST_TIME 3 6654
TEST_TIME 4 6103
TEST_TIME 5 6016
TEST_TIME 6 6441
TEST_TIME 7 6285
TEST_TIME 8 6192
TEST_TIME 9 6146
TEST_TIME 10 6117
TEST_TIME 11 6542
TEST_TIME 12 6918
TEST_TIME 13 7005
TEST_TIME 14 7263
TEST_TIME 15 7436
TEST_TIME 16 7600
TEST_TIME 17 7875
TEST_TIME 18 7860
TEST_TIME 19 7749
TEST_TIME 20 7965
TEST_TIME 21 8078
TEST_TIME 22 8363
TEST_TIME 23 8402
TEST_TIME 24 8530
TEST_TIME 25 8734
TEST_TIME 26 8626
TEST_TIME 27 8787
TEST_TIME 28 8967
TEST_TIME 29 9353
TEST_TIME 30 9712
TEST_TIME 31 9796
TEST_TIME 32 10082
TEST_TIME 33 10384
TEST_TIME 34 10785
TEST_TIME 35 10886
TEST_TIME 36 10641
TEST_TIME 37 10810
TEST_TIME 38 10939
TEST_TIME 39 10828
TEST_TIME 40 10706
TEST_TIME 41 10595
TEST_TIME 42 10417
TEST_TIME 43 10361
TEST_TIME 44 10428
TEST_TIME 45 10382
TEST_TIME 46 10301
TEST_TIME 47 10364
TEST_TIME 48 10300
TEST_TIME 49 10343
TEST_TIME 50 10319
TEST_TIME 51 10285
TEST_TIME 52 10124
TEST_TIME 53 10021
TEST_TIME 54 9950
TEST_TIME 55 9815
TEST_TIME 56 9820
TEST_TIME 57 9788
TEST_TIME 58 9717
TEST_TIME 59 9753
TEST_TIME 60 9730
TEST_TIME 61 9626
TEST_TIME 62 9564
TEST_TIME 63 9653
TEST_TIME 64 9552
TEST_TIME 65 9498
TEST_TIME 66 9478
TEST_TIME 67 9511
TEST_TIME 68 9512
TEST_TIME 69 9528
TEST_TIME 70 9570
TEST_TIME 71 9588
TEST_TIME 72 9537
TEST_TIME 73 9607
TEST_TIME 74 9588
TEST_TIME 75 9648
TEST_TIME 76 9601
TEST_TIME 77 9649
TEST_TIME 78 9638
TEST_TIME 79 9675
TEST_TIME 80 9589
TEST_TIME 81 9522
TEST_TIME 82 9458
TEST_TIME 83 9428
TEST_TIME 84 9382
TEST_TIME 85 9324
TEST_TIME 86 9257
TEST_TIME 87 9224
TEST_TIME 88 9210
TEST_TIME 89 9175
TEST_TIME 90 9107
TEST_TIME 91 9109
TEST_TIME 92 9075
TEST_TIME 93 9077
TEST_TIME 94 9097
TEST_TIME 95 9147
TEST_TIME 96 9230
TEST_TIME 97 9223
TEST_TIME 98 9324
TEST_TIME 99 9334
TEST_TIME 100 9419
TEST_TIME 101 9428
TEST_TIME 102 9419
TEST_TIME 103 9389
TEST_TIME 104 9319
TEST_TIME 105 9262
TEST_TIME 106 9299
TEST_TIME 107 9254
TEST_TIME 108 9310
TEST_TIME 109 9267
TEST_TIME 110 9225
TEST_TIME 111 9187
TEST_TIME 112 9149
TEST_TIME 113 9094
TEST_TIME 114 9093
TEST_TIME 115 9113
TEST_TIME 116 9094
TEST_TIME 117 9103
TEST_TIME 118 9078
TEST_TIME 119 9051
TEST_TIME 120 9070

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-08-2022 07:48:20 to 03-08-2022 07:48:24.

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 1518261 1518261 0 0.00 0 19131 13 0 0
doc_list_biud 1518261 1518261 0 0.00 0 95877 40 0 0
sp_add_invoice_to_stock 5175 5070 105 2.02 41 231992 24249 0 105
sp_cancel_adding_invoice 5441 5441 0 0.00 8 59401 1160 0 0
sp_cancel_client_order 1707 1707 0 0.00 6 13612 396 0 0
sp_cancel_customer_reserve 531800 531800 0 0.00 1 46245 98 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 4 10234 137 0 0
sp_cancel_pay_to_supplier 457 457 0 0.00 4 2074 141 0 0
sp_cancel_supplier_invoice 6114 6107 7 0.11 3 95886 995 0 7
sp_cancel_supplier_order 390 390 0 0.00 14 1563266 170654 0 0
sp_cancel_write_off 9130 9130 0 0.00 2 10084 158 0 0
sp_client_order 21049 21049 0 0.00 6 13541 458 0 0
sp_customer_reserve 468724 468724 0 0.00 1 132812 228 0 0
sp_fill_shopping_cart 32288 32288 0 0.00 1 21930 87 0 0
sp_fill_shopping_cart_clo_res 468717 468717 0 0.00 0 13977 18 0 0
sp_get_clo_for_invoice 5037 5037 0 0.00 0 41988 281 0 0
sp_kill_cost_storno 15748 15748 0 0.00 0 10071 70 0 0
sp_kill_qstorno_ret_qs2qd 1079227 1079227 0 0.00 0 95875 43 0 0
sp_kill_qty_storno 569742 569742 0 0.00 0 95876 99 0 0
sp_lock_selected_doc 20898 20898 0 0.00 0 7155 7 0 0
sp_make_cost_storno 28181 28181 0 0.00 0 19122 86 0 0
sp_multiply_rows_for_pdistr 28181 28181 0 0.00 0 7519 41 0 0
sp_multiply_rows_for_qdistr 21049 21049 0 0.00 0 12512 166 0 0
sp_pay_from_customer 7168 7168 0 0.00 5 14081 182 0 0
sp_pay_to_supplier 5846 5846 0 0.00 6 11293 208 0 0
sp_payment_common 13014 13014 0 0.00 2 11220 50 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 1 13584 316 0 0
sp_qd_handle_on_invoice_upd_sts 10477 10477 0 0.00 0 34100 521 0 0
sp_qd_handle_on_reserve_upd_sts 10131 10131 0 0.00 0 14115 71 0 0
sp_reserve_write_off 10131 10131 0 0.00 9 14291 299 0 0
sp_supplier_invoice 5642 5642 0 0.00 8 97900 3112 0 0
sp_supplier_order 5602 5602 0 0.00 7 35473 1347 0 0
srv_aggregate_perf_data 110 110 0 0.00 2265 212757 15751 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 921 215864 38841 0 0
srv_find_qd_qs_mism 1036910 1036910 0 0.00 0 10760 9 0 0
srv_make_invnt_saldo 490 490 0 0.00 73 156198 3936 0 0
srv_make_money_saldo 935 935 0 0.00 1 15408 341 0 0
srv_recalc_idx_stat 4 4 0 0.00 68876 474489 249684 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 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 5070 5070 0 0.00 0 27404 997 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 1 10446 485 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 2231 32 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 1192 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 3070 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 457 457 0 0.00 0 1160 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1757 45 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 102433 7513 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 1570 22 0 0
t$perf-norm:sp_client_order 21049 21049 0 0.00 0 14830 40 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 603 13 0 0
t$perf-norm:sp_pay_from_customer 7168 7168 0 0.00 0 5693 22 0 0
t$perf-norm:sp_pay_to_supplier 5846 5846 0 0.00 0 4518 20 0 0
t$perf-norm:sp_reserve_write_off 10131 10131 0 0.00 0 21311 26 0 0
t$perf-norm:sp_supplier_invoice 5642 5642 0 0.00 0 29586 35 0 0
t$perf-norm:sp_supplier_order 5602 5602 0 0.00 0 4968 33 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 532 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 12308 184 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 779 13 0 0
t$perf-norm:srv_make_money_saldo 935 935 0 0.00 0 4524 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 169 76 0 0
v_add_invoice_to_stock 10282 10072 210 2.04 0 9234 29 0 210
v_all_customers 38296 38296 0 0.00 0 192 4 0 0
v_all_suppliers 30708 30708 0 0.00 0 88 5 0 0
v_all_wares 42098 42098 0 0.00 0 76 4 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 6329 26 0 0
v_cancel_client_order 3414 3414 0 0.00 0 5780 12 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 2295 12 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 2390 17 0 0
v_cancel_payment_to_supplier 914 914 0 0.00 0 1883 16 0 0
v_cancel_supplier_invoice 830 816 14 1.68 0 8603 40 0 14
v_cancel_supplier_order 780 780 0 0.00 0 2925 17 0 0
v_cancel_write_off 2022 2022 0 0.00 0 2610 26 0 0
v_max_id_clo_ord 5600 5600 0 0.00 0 4380 6 0 0
v_max_id_clo_res 974 974 0 0.00 0 902 14 0 0
v_max_id_ord_sup 5638 5638 0 0.00 0 2573 6 0 0
v_max_non_paid_invoice 1730 1730 0 0.00 0 1085 9 0 0
v_max_non_paid_realizn 3814 3814 0 0.00 0 4734 10 0 0
v_min_id_clo_ord 5600 5600 0 0.00 0 1496 8 0 0
v_min_id_clo_res 974 974 0 0.00 0 1423 10 0 0
v_min_id_ord_sup 5638 5638 0 0.00 0 8255 10 0 0
v_min_non_paid_invoice 5846 5846 0 0.00 0 8318 18 0 0
v_min_non_paid_realizn 7168 7168 0 0.00 0 7710 20 0 0
v_our_firm 10510 10510 0 0.00 0 64 5 0 0
v_reserve_write_off 20262 20262 0 0.00 0 6686 13 0 0
x_lock_depdocs_on_canc_invoice 11140 11140 0 0.00 0 23290 112 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 14016 351 0 0
x_make_qstorno_1000_1200 5602 5602 0 0.00 2 35450 1175 0 0
x_make_qstorno_1200_2000 5642 5642 0 0.00 4 94028 2874 0 0
x_make_qstorno_2100_3300 468724 468724 0 0.00 0 132800 198 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-08-2022 07:48:25 to 03-08-2022 07:48:29.

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:16:50 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:51 4241 8192000000 9709359136 10004193280 794261264 8.838 100 90 178 0 823504960 7384992 21858704
05:19:02 31221 8192000000 9758254880 10065092608 827410176 9.173 100 87 174 0 860192720 11688624 21093920
05:20:42 15603 8192000000 9802470192 10119827456 870960032 9.610 100 87 174 0 910276656 19209824 20106800
05:22:01 9112 8192000000 9864485808 10195505152 912799584 10.025 100 78 156 0 977066768 46800208 17466976
05:23:14 5677 8192000000 9919833248 10262220800 956801344 10.458 100 83 160 1 1028370304 54457328 17111632
05:24:23 1900 8192000000 9955120960 10307059712 994627952 10.826 100 87 167 0 1077882080 66188000 17066128
05:25:29 2329 8192000000 9993113200 10355044352 1022847696 11.099 100 94 177 3 1107789888 68154480 16787712
05:26:34 1355 8192000000 10025053776 10397495296 1039698320 11.262 100 94 182 2 1147873360 90159872 18015168
05:27:37 1132 8192000000 10032225568 10407157760 1058471616 11.442 100 93 170 2 1150955520 75239408 17244496
05:28:39 1106 8192000000 10067868640 10447052800 1074642848 11.596 100 98 192 0 1193364448 99092192 19629408
05:29:41 1525 8192000000 10032648608 10423234560 1072995216 11.581 100 75 144 1 1162245728 73954752 15295760
05:30:43 8552 8192000000 10023397408 10421669888 1082007360 11.667 100 70 133 1 1164603456 68570560 14025536
05:31:53 1257 8192000000 10030949984 10425016320 1086718896 11.711 100 77 139 6 1150361744 50178912 13463936
05:32:55 930 8192000000 10043064272 10436575232 1094486272 11.785 100 88 167 2 1170067200 57416752 18164176
05:33:57 1039 8192000000 10052746480 10443038720 1098887536 11.827 100 94 174 3 1176291152 58536080 18867536
05:34:58 1385 8192000000 10074323744 10473287680 1103572240 11.872 100 82 162 2 1193790032 74098336 16119456
05:36:04 1620 8192000000 10072322800 10471723008 1105647104 11.891 100 79 152 1 1199750368 79308416 14794848
05:37:06 1008 8192000000 10069058208 10458578944 1104913168 11.884 100 93 179 1 1190926368 68077440 17935760
05:38:08 1315 8192000000 10050019728 10447036416 1092478144 11.766 100 80 157 0 1172299808 63396000 16425664
05:39:10 1437 8192000000 10043829824 10439696384 1090485216 11.747 100 75 147 0 1167809232 62764768 14559248
05:40:13 1351 8192000000 10019349040 10400985088 1063285584 11.488 100 81 157 1 1141501744 62068784 16147376
05:41:15 878 8192000000 10017843056 10401435648 1066666304 11.520 100 81 154 1 1135279008 52555904 16056800
05:42:17 1409 8192000000 9987223168 10364260352 1035559936 11.222 100 83 159 0 1109259008 57707024 15992048
05:43:19 1882 8192000000 9987113456 10360041472 1032638128 11.194 98 90 172 2 1110536992 59280976 18617888
05:44:22 1605 8192000000 9991538688 10373058560 1037875968 11.244 100 79 154 1 1108390016 54851280 15662768
05:45:24 1914 8192000000 9974354368 10360410112 1046899920 11.331 100 63 127 1 1103086720 43064656 13122144
05:46:26 5734 8192000000 9957981760 10328420352 1044245920 11.305 100 84 150 4 1078048560 18634800 15167840
05:47:35 26137 8192000000 10001549472 10384351232 1065450016 11.509 100 71 131 6 1118178496 39785216 12943264
05:49:07 1411 8192000000 10044419776 10424160256 1083918928 11.685 99 93 171 1 1164780752 62255008 18606816
05:50:13 10954 8192000000 10060266592 10448547840 1098224656 11.821 100 68 121 0 1174580800 64322352 12033792
05:51:43 2463 8192000000 10098861248 10497155072 1122061600 12.046 100 85 163 2 1217561040 78262528 17236912
05:52:48 4522 8192000000 10105165168 10502107136 1128023936 12.103 100 66 128 1 1237173152 95984032 13165184
05:53:57 9193 8192000000 10169726992 10572398592 1136002480 12.178 100 69 120 1 1280052688 130817008 13233200
05:55:14 5755 8192000000 10215929376 10614099968 1150879936 12.318 100 70 140 0 1327952048 161821584 15250528
05:56:27 2501 8192000000 10244634032 10643177472 1162231984 12.424 100 83 156 2 1358460096 179211488 17016624
05:57:33 944 8192000000 10306032768 10708115456 1178440176 12.576 100 96 181 0 1416088560 215422832 22225552
05:58:37 2422 8192000000 10317505632 10732838912 1176009856 12.553 100 95 190 1 1427089232 229883872 21195504
05:59:41 942 8192000000 10340040544 10752983040 1185635888 12.643 100 95 183 1 1452923824 246111840 21176096
06:00:44 977 8192000000 10335500480 10747772928 1175555632 12.549 100 92 172 2 1441819520 245470208 20793680
06:01:49 917 8192000000 10353249104 10767314944 1179239280 12.583 100 95 183 1 1461622656 261961376 20422000
06:02:54 1386 8192000000 10383210176 10811351040 1181894864 12.608 100 95 187 1 1488813488 284185072 22733552
06:03:58 960 8192000000 10385041952 10790768640 1184981264 12.637 100 95 183 0 1492094608 284493920 22619424
06:05:03 1442 8192000000 10424562368 10845028352 1185517488 12.642 100 95 189 0 1524353264 317084400 21751376
06:06:08 1444 8192000000 10449154064 10893697024 1186930016 12.655 100 92 180 2 1555221152 346647888 21643248
06:07:13 936 8192000000 10451494880 10887778304 1189311360 12.677 100 96 185 1 1555238320 344458752 21468208
06:08:19 1020 8192000000 10449450080 10912358400 1183313744 12.621 100 99 200 1 1556268032 349222032 23732256
06:09:23 3386 8192000000 10478861184 10915160064 1186900160 12.655 100 67 134 0 1593008400 389844832 16263408
06:10:35 6407 8192000000 10480584304 10914897920 1185168352 12.638 99 60 118 0 1585611536 385352176 15091008
06:11:48 8394 8192000000 10475209584 10909478912 1181482032 12.604 99 38 74 0 1573259344 381640928 10136384
06:13:01 4126 8192000000 10492942800 10951385088 1183917136 12.627 100 63 119 1 1594518320 396058272 14542912
06:14:10 4850 8192000000 10481017072 10922127360 1150216720 12.312 100 68 132 1 1583447440 416480624 16750096
06:15:17 13905 8192000000 10432287232 10921738240 1153278816 12.340 100 45 84 0 1569802144 404160560 12362768
06:16:32 20750 8192000000 10395804912 10841239552 1140725728 12.222 98 43 84 0 1469684656 318670288 10288640
06:17:54 14458 8192000000 10383450816 10848489472 1155662304 12.363 100 29 58 0 1498147200 334735792 7749104
06:19:09 35096 8192000000 10283295072 10752094208 1155678608 12.363 100 22 42 0 1423110976 262075984 5356384
06:20:49 10292 8192000000 10176740624 10625871872 1150834112 12.317 100 51 100 1 1269117728 107088304 11195312
06:22:07 46577 8192000000 10181748352 10627280896 1149016272 12.300 99 33 66 1 1277550256 121271840 7262144
06:24:05 130774 8192000000 10085691744 10534821888 1104693648 11.882 96 14 28 0 1132366816 24538944 3134224
06:27:28 60293 8192000000 10100866768 10544791552 1117019840 11.999 98 23 46 0 1180721568 59364400 4337328
06:29:31 67043 8192000000 10083054176 10585333760 1130908112 12.130 99 24 46 0 1224804192 89473248 4422832
06:31:38 22763 8192000000 10064242800 10509295616 1126183696 12.085 99 19 37 1 1145445680 15759936 3502048
06:33:05 158340 8192000000 10104371168 10608553984 1095948368 11.799 96 10 20 0 1167609360 69588864 2072128
06:36:45 215862 8192000000 10116270512 10587766784 1118204304 12.010 97 22 44 1 1213515536 90787920 4523312
06:41:42 75030 8192000000 10092246368 10534219776 1120366752 12.030 98 55 108 1 1191990448 60492400 11131296
06:43:59 35334 8192000000 10125198384 10560237568 1132024128 12.140 99 37 72 0 1193651392 53505248 8122016
06:45:42 55220 8192000000 10103341552 10568929280 1114843648 11.978 98 14 28 0 1185400880 67727360 2829872
06:47:38 155511 8192000000 10063862608 10539954176 1100777728 11.845 96 15 28 0 1176903696 73246912 2879056
06:51:14 59037 8192000000 10091851616 10564120576 1128876480 12.111 99 24 46 1 1190039904 56412464 4750960
06:53:34 29967 8192000000 10087328208 10530189312 1119893680 12.026 98 25 50 0 1160710080 35747888 5068512
06:55:23 89193 8192000000 10100424848 10531405824 1083399136 11.680 94 24 47 0 1160553424 71596912 5557376
06:57:58 38200 8192000000 10130955936 10554867712 1122045984 12.046 98 39 77 0 1203403184 72563776 8793424
06:59:39 29718 8192000000 10110235200 10559889408 1094309072 11.784 96 31 60 0 1148568560 47638736 6620752
07:01:09 63237 8192000000 10089159280 10553159680 1120419968 12.031 98 17 34 0 1193764416 69191776 4152672
07:03:30 38136 8192000000 10126675536 10597937152 1121379584 12.040 99 32 65 0 1225750368 97557104 6813680
07:05:12 97027 8192000000 10079506384 10529497088 1095136496 11.791 97 34 66 1 1146488784 45081200 6271088
07:08:30 52188 8192000000 10075812976 10516586496 1124751888 12.072 100 26 50 0 1177637904 48033600 4852416
07:10:25 48134 8192000000 10072633456 10547691520 1131788768 12.138 100 9 17 0 1180338880 46618976 1931136
07:13:12 66137 8192000000 10106599744 10593681408 1133027024 12.150 100 21 42 0 1208688080 70721584 4939472
07:15:19 70811 8192000000 10113468384 10584604672 1089786464 11.741 96 9 18 1 1157018048 65304784 1926800
07:17:30 48979 8192000000 10151612992 10592964608 1054996432 11.409 93 26 53 0 1175690912 114752256 5942224
07:19:20 13421 8192000000 10208433920 10638401536 1127292992 12.096 99 42 84 1 1307958576 171246736 9418848
07:20:34 30150 8192000000 10254613792 10732785664 1146822672 12.280 100 41 81 1 1384917760 228714256 9380832
07:22:15 51361 8192000000 10215403248 10671022080 1150849584 12.317 100 30 61 0 1347815584 189604752 7361248
07:24:09 73962 8192000000 10181480848 10620530688 1137850800 12.195 99 16 30 0 1287249888 146035136 3363952
07:26:26 36962 8192000000 10121844160 10558115840 1093921136 11.780 96 39 76 0 1144353280 42670512 7761632
07:28:08 9034 8192000000 10088237680 10528911360 1125575840 12.080 100 62 120 1 1171173712 33872368 11725504
07:29:19 86256 8192000000 10144269952 10587389952 1110323936 11.935 98 56 112 0 1219668000 97377632 11966432
07:31:45 28562 8192000000 10146960416 10604326912 1130451008 12.126 100 38 74 1 1251770928 112384928 8934992
07:33:14 62615 8192000000 10116643568 10576297984 1122738000 12.053 99 14 28 0 1207187264 81561808 2887456
07:35:17 68799 8192000000 10073626576 10577887232 1093515920 11.776 96 12 24 0 1139827952 44018672 2293360
07:37:29 50102 8192000000 10053925152 10518761472 1117800800 12.006 99 18 36 0 1145271040 23271712 4198528
07:39:54 103002 8192000000 10096504512 10592419840 1140806640 12.223 100 16 32 0 1209066672 64724624 3535408
07:42:44 26503 8192000000 10116822576 10566225920 1132281536 12.143 98 25 50 0 1197544720 59920048 5343136
07:44:22 30564 8192000000 10142484752 10584535040 1128815136 12.110 98 35 70 0 1206143568 69763696 7564736
07:46:01 18034 8192000000 10200027200 10634600448 1132483216 12.145 99 50 95 2 1277665424 134969888 10212320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-08-2022 07:48:31 to 03-08-2022 07:48:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 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:01, from 03-08-2022 07:48:48 to 03-08-2022 07:48:49.

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          217217
MON$OLDEST_ACTIVE               223632
MON$OLDEST_SNAPSHOT             223632
MON$NEXT_TRANSACTION            223632
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                       2089696
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2813 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2813 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2813 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2813 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Aug  3 07:46:52 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Aug  3 07:46:58 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Aug  3 07:46:58 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

03.08.22 07:48:51. 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-08-03 07:48:53 No severe PSQL-related problems occured
03.08.22 07:48:54. End of report.