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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15401528 kB
MemAvailable 30466440 kB
Buffers 0 kB
Cached 16342232 kB
SwapCached 1960 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-27 07:37:22
DTS_END                         2022-10-27 07:37:22.3250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49456
TRN_ID                          224728
ATT_ID                          597
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: 8786.25 27131 1054350
customer order: creation 170.35 456 20442
customer order: refuse 14.40 339 1728
order to supplier: creation 46.42 1340 5570
order to supplier: removal 3.30 126468 396
invoice (draft): creation 45.90 3094 5508
invoice (draft): removal 47.93 863 5752
invoice accept: apply 41.73 23612 5008
invoice accept: cancel 43.63 1028 5235
customer reserve: creation 3850.81 229 462097
customer reserve: removal 4223.67 78 506840
realization accept: apply 83.15 234 9978
realization accept: cancel 83.13 137 9975
payment from customer: creation 60.28 175 7234
payment from customer: removal 5.47 98 656
payment to supplier: creation 50.24 174 6029
payment to supplier: removal 3.29 132 395
service: total inventory turnovers 4.10 3106 492
service: total monetary turnovers 7.41 303 889
service: aggregate perf. data 1.02 12475 122
service: refresh index statistics 0.03 368270 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-10-2022 07:39:23 to 27-10-2022 07:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5886
TEST_TIME 2 6460
TEST_TIME 3 6539
TEST_TIME 4 6071
TEST_TIME 5 6152
TEST_TIME 6 6035
TEST_TIME 7 6247
TEST_TIME 8 6155
TEST_TIME 9 6203
TEST_TIME 10 6136
TEST_TIME 11 6221
TEST_TIME 12 6239
TEST_TIME 13 6408
TEST_TIME 14 6427
TEST_TIME 15 6743
TEST_TIME 16 6774
TEST_TIME 17 7087
TEST_TIME 18 7430
TEST_TIME 19 7820
TEST_TIME 20 7780
TEST_TIME 21 8287
TEST_TIME 22 8818
TEST_TIME 23 9093
TEST_TIME 24 9609
TEST_TIME 25 9998
TEST_TIME 26 10455
TEST_TIME 27 10160
TEST_TIME 28 10035
TEST_TIME 29 10271
TEST_TIME 30 10313
TEST_TIME 31 10105
TEST_TIME 32 10079
TEST_TIME 33 10116
TEST_TIME 34 10006
TEST_TIME 35 9847
TEST_TIME 36 9736
TEST_TIME 37 9742
TEST_TIME 38 9940
TEST_TIME 39 9796
TEST_TIME 40 9951
TEST_TIME 41 10049
TEST_TIME 42 10044
TEST_TIME 43 10126
TEST_TIME 44 10104
TEST_TIME 45 9978
TEST_TIME 46 9835
TEST_TIME 47 9673
TEST_TIME 48 9600
TEST_TIME 49 9544
TEST_TIME 50 9586
TEST_TIME 51 9556
TEST_TIME 52 9504
TEST_TIME 53 9404
TEST_TIME 54 9309
TEST_TIME 55 9276
TEST_TIME 56 9280
TEST_TIME 57 9194
TEST_TIME 58 9219
TEST_TIME 59 9350
TEST_TIME 60 9361
TEST_TIME 61 9356
TEST_TIME 62 9381
TEST_TIME 63 9406
TEST_TIME 64 9491
TEST_TIME 65 9426
TEST_TIME 66 9382
TEST_TIME 67 9457
TEST_TIME 68 9575
TEST_TIME 69 9517
TEST_TIME 70 9478
TEST_TIME 71 9439
TEST_TIME 72 9387
TEST_TIME 73 9384
TEST_TIME 74 9304
TEST_TIME 75 9263
TEST_TIME 76 9255
TEST_TIME 77 9217
TEST_TIME 78 9190
TEST_TIME 79 9171
TEST_TIME 80 9207
TEST_TIME 81 9194
TEST_TIME 82 9198
TEST_TIME 83 9215
TEST_TIME 84 9270
TEST_TIME 85 9252
TEST_TIME 86 9292
TEST_TIME 87 9294
TEST_TIME 88 9339
TEST_TIME 89 9253
TEST_TIME 90 9178
TEST_TIME 91 9165
TEST_TIME 92 9098
TEST_TIME 93 9075
TEST_TIME 94 9003
TEST_TIME 95 9006
TEST_TIME 96 8974
TEST_TIME 97 8940
TEST_TIME 98 8952
TEST_TIME 99 8934
TEST_TIME 100 8956
TEST_TIME 101 8982
TEST_TIME 102 8970
TEST_TIME 103 9023
TEST_TIME 104 8994
TEST_TIME 105 9031
TEST_TIME 106 9086
TEST_TIME 107 9098
TEST_TIME 108 9141
TEST_TIME 109 9155
TEST_TIME 110 9118
TEST_TIME 111 9064
TEST_TIME 112 9015
TEST_TIME 113 9064
TEST_TIME 114 9017
TEST_TIME 115 8971
TEST_TIME 116 8944
TEST_TIME 117 8911
TEST_TIME 118 8884
TEST_TIME 119 8864
TEST_TIME 120 8810

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-10-2022 07:39:27 to 27-10-2022 07:39:30.

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 1478988 1478988 0 0.00 0 19350 12 0 0
doc_list_biud 1478968 1478968 0 0.00 0 46155 32 0 0
sp_add_invoice_to_stock 5088 5008 80 1.57 29 183948 23612 0 80
sp_cancel_adding_invoice 5235 5235 0 0.00 6 80536 1028 0 0
sp_cancel_client_order 1728 1728 0 0.00 6 7816 340 0 0
sp_cancel_customer_reserve 506840 506840 0 0.00 1 43171 78 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 5049 99 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 7164 132 0 0
sp_cancel_supplier_invoice 5759 5752 7 0.12 3 46466 863 0 7
sp_cancel_supplier_order 396 396 0 0.00 7 1231617 126468 0 0
sp_cancel_write_off 9975 9975 0 0.00 2 10404 137 0 0
sp_client_order 20442 20442 0 0.00 5 23897 456 0 0
sp_customer_reserve 462097 462097 0 0.00 0 155850 229 0 0
sp_fill_shopping_cart 31510 31510 0 0.00 1 12511 75 0 0
sp_fill_shopping_cart_clo_res 462059 462059 0 0.00 0 19027 18 0 0
sp_get_clo_for_invoice 4963 4963 0 0.00 0 25585 301 0 0
sp_kill_cost_storno 16261 16261 0 0.00 0 10386 59 0 0
sp_kill_qstorno_ret_qs2qd 1029803 1029803 0 0.00 0 46138 36 0 0
sp_kill_qty_storno 544863 544863 0 0.00 0 46148 81 0 0
sp_lock_selected_doc 20617 20617 0 0.00 0 8172 6 0 0
sp_make_cost_storno 28204 28204 0 0.00 0 15729 67 0 0
sp_multiply_rows_for_pdistr 28200 28200 0 0.00 0 10480 37 0 0
sp_multiply_rows_for_qdistr 20442 20442 0 0.00 0 18705 176 0 0
sp_pay_from_customer 7234 7234 0 0.00 5 22771 175 0 0
sp_pay_to_supplier 6029 6029 0 0.00 6 13414 174 0 0
sp_payment_common 13262 13262 0 0.00 2 5842 36 0 0
sp_qd_handle_on_cancel_clo 1728 1728 0 0.00 0 7794 276 0 0
sp_qd_handle_on_invoice_upd_sts 10196 10196 0 0.00 0 22759 404 0 0
sp_qd_handle_on_reserve_upd_sts 9976 9976 0 0.00 0 12450 52 0 0
sp_reserve_write_off 9978 9978 0 0.00 8 12557 234 0 0
sp_supplier_invoice 5508 5508 0 0.00 11 94897 3094 0 0
sp_supplier_order 5570 5570 0 0.00 7 110469 1341 0 0
srv_aggregate_perf_data 122 122 0 0.00 2358 133511 12476 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 797 228255 38831 0 0
srv_find_qd_qs_mism 1004781 1004781 0 0.00 0 19348 9 0 0
srv_make_invnt_saldo 492 492 0 0.00 56 49946 3107 0 0
srv_make_money_saldo 889 889 0 0.00 1 11703 304 0 0
srv_recalc_idx_stat 4 4 0 0.00 171492 477688 368270 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 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 5017 5017 0 0.00 0 17285 712 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 7450 347 0 0
t$perf-norm:sp_cancel_client_order 1728 1728 0 0.00 0 10439 31 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 830 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 626 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 6388 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 6457 40 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 74643 4269 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 2553 21 0 0
t$perf-norm:sp_client_order 20442 20442 0 0.00 0 11193 31 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 6007 17 0 0
t$perf-norm:sp_pay_from_customer 7234 7234 0 0.00 0 12902 18 0 0
t$perf-norm:sp_pay_to_supplier 6029 6029 0 0.00 0 6211 18 0 0
t$perf-norm:sp_reserve_write_off 9978 9978 0 0.00 0 10682 22 0 0
t$perf-norm:sp_supplier_invoice 5508 5508 0 0.00 0 7784 28 0 0
t$perf-norm:sp_supplier_order 5570 5570 0 0.00 0 4969 23 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 407 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 52 4 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 6101 31 0 0
t$perf-norm:srv_make_money_saldo 889 889 0 0.00 0 288 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 175 47 0 0
v_add_invoice_to_stock 10078 9918 160 1.58 0 4067 21 0 160
v_all_customers 38124 38124 0 0.00 0 75 3 0 0
v_all_suppliers 30952 30952 0 0.00 0 78 4 0 0
v_all_wares 40874 40874 0 0.00 0 96 3 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 257 12 0 0
v_cancel_client_order 3456 3456 0 0.00 0 6207 12 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 5029 14 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 1 6307 18 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 6219 22 0 0
v_cancel_supplier_invoice 824 810 14 1.69 0 6182 29 0 14
v_cancel_supplier_order 792 792 0 0.00 0 598 12 0 0
v_cancel_write_off 2028 2028 0 0.00 0 4884 25 0 0
v_max_id_clo_ord 5569 5569 0 0.00 0 429 4 0 0
v_max_id_clo_res 991 991 0 0.00 0 907 10 0 0
v_max_id_ord_sup 5503 5503 0 0.00 0 6136 8 0 0
v_max_non_paid_invoice 1623 1623 0 0.00 0 887 7 0 0
v_max_non_paid_realizn 3559 3559 0 0.00 0 701 6 0 0
v_min_id_clo_ord 5569 5569 0 0.00 0 3322 9 0 0
v_min_id_clo_res 991 991 0 0.00 0 2139 8 0 0
v_min_id_ord_sup 5503 5503 0 0.00 0 5165 13 0 0
v_min_non_paid_invoice 6028 6028 0 0.00 0 5836 12 0 0
v_min_non_paid_realizn 7232 7232 0 0.00 0 3939 13 0 0
v_our_firm 10096 10096 0 0.00 0 103 4 0 0
v_reserve_write_off 19950 19950 0 0.00 0 6289 11 0 0
x_lock_depdocs_on_canc_invoice 10582 10582 0 0.00 0 31340 125 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 2586 289 0 0
x_make_qstorno_1000_1200 5570 5570 0 0.00 3 110393 1202 0 0
x_make_qstorno_1200_2000 5508 5508 0 0.00 4 89095 2874 0 0
x_make_qstorno_2100_3300 462096 462096 0 0.00 0 155849 201 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-10-2022 07:39:31 to 27-10-2022 07:39:35.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:26 2 8192000000 8797766192 8808615936 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:27 33592 8192000000 9738095904 10036838400 803511968 8.932 100 83 164 0 830731136 7274176 19944992
05:10:06 16978 8192000000 9764200640 10069377024 832522880 9.225 100 87 174 0 866038656 12099200 21416576
05:11:28 11699 8192000000 9801511760 10118557696 869029600 9.590 100 86 172 0 906621712 17558496 20033616
05:12:43 10975 8192000000 9870055088 10199224320 916680304 10.063 100 86 170 1 974230800 38952528 18597968
05:13:59 2197 8192000000 9911606624 10253189120 952521136 10.416 100 91 171 0 1034109104 62973040 18614928
05:15:04 1495 8192000000 9958332416 10309189632 990464080 10.786 100 93 183 1 1080570896 71053024 19053792
05:16:07 4177 8192000000 9995329664 10357317632 1019213456 11.064 100 84 166 0 1113616400 77527024 16875920
05:17:13 949 8192000000 10025714000 10400210944 1048060544 11.342 100 96 188 1 1144223152 76616208 19546400
05:18:15 760 8192000000 10038229296 10415427584 1069406224 11.546 100 91 174 2 1160244944 72989824 17848896
05:19:18 2142 8192000000 10069236096 10459148288 1076942384 11.618 100 90 172 1 1186343968 92752688 16648896
05:20:21 4026 8192000000 10030840176 10417864704 1078506400 11.633 100 60 102 1 1164998240 75182464 11309376
05:21:26 3513 8192000000 10037794400 10430648320 1081652816 11.663 100 69 138 1 1163456800 68131280 13672704
05:22:31 2610 8192000000 10034329536 10424631296 1089261760 11.736 100 66 125 2 1148037664 45570320 13205584
05:23:34 4069 8192000000 10055400448 10444886016 1093065904 11.772 100 78 153 1 1174679184 65923328 15689952
05:24:39 1046 8192000000 10047075408 10434969600 1099186256 11.830 100 84 152 8 1163292608 49970064 14136288
05:25:41 873 8192000000 10060224256 10447663104 1098869984 11.827 100 92 174 2 1181367648 65699104 16798560
05:26:43 1060 8192000000 10064840016 10457444352 1100366848 11.841 100 92 175 1 1192773360 74608960 17797552
05:27:45 1160 8192000000 10061761808 10446319616 1096993248 11.809 100 93 172 2 1178482304 64450048 17039008
05:28:47 4407 8192000000 10033098208 10430894080 1089488944 11.738 100 68 127 0 1155281328 52564944 13227440
05:29:52 1038 8192000000 10038786208 10428178432 1086997872 11.714 100 81 155 3 1157532736 55289584 15245280
05:30:54 968 8192000000 10024752784 10409312256 1071553600 11.567 100 86 168 1 1145095568 54594336 18947632
05:31:56 1122 8192000000 10013753808 10391617536 1056586256 11.424 100 89 166 1 1133492384 58197536 18708592
05:33:01 1887 8192000000 10026743120 10402979840 1031297040 11.181 100 82 157 1 1152863120 105181168 16384912
05:34:05 1346 8192000000 10006758368 10374361088 1035192464 11.218 100 97 186 1 1121943776 69233280 17518032
05:35:07 1288 8192000000 9969011904 10333048832 1005837744 10.935 99 97 183 1 1093714032 69926912 17949376
05:36:09 4375 8192000000 9993535536 10384842752 1044646576 11.309 100 61 117 0 1123337024 66684736 12005712
05:37:15 1000 8192000000 9997993280 10378903552 1046346592 11.326 100 87 163 0 1123283904 60627600 16309712
05:38:22 18157 8192000000 10024943584 10402217984 1057114080 11.429 100 80 150 1 1144461216 73505344 13841792
05:39:45 3626 8192000000 10040653456 10428891136 1070652432 11.558 100 87 167 2 1167707984 79388928 17666624
05:40:54 9290 8192000000 10036436768 10418315264 1064870864 11.503 99 66 120 2 1153031072 76399504 11760704
05:42:05 2949 8192000000 10075762112 10465157120 1093714240 11.778 100 69 128 3 1192872928 85812384 13346304
05:43:12 4052 8192000000 10128112224 10518781952 1113329056 11.964 100 69 130 0 1237695984 110308784 14058144
05:44:23 8910 8192000000 10161125168 10559578112 1129899056 12.120 100 70 130 3 1275445280 131430176 14116048
05:45:43 2427 8192000000 10202147600 10600685568 1148675168 12.297 100 72 136 0 1318553824 154942688 14935968
05:46:52 15812 8192000000 10268396624 10672467968 1167554832 12.474 100 71 137 0 1373759344 191332736 14871776
05:48:11 789 8192000000 10305880896 10703519744 1176201040 12.555 100 98 185 1 1415699680 219464704 20033936
05:49:14 905 8192000000 10347758192 10746519552 1181631520 12.605 100 94 180 2 1453147264 252191648 19324096
05:50:18 3572 8192000000 10382340048 10797830144 1189489600 12.679 100 85 160 0 1490965664 283391552 18084512
05:51:24 1590 8192000000 10420073280 10837340160 1196591200 12.745 100 91 169 0 1525028384 309740912 18696272
05:52:28 1613 8192000000 10440881712 10853756928 1192884048 12.710 99 93 174 1 1549267056 336019680 20363328
05:53:32 1110 8192000000 10513869488 10920611840 1203662160 12.810 100 97 187 0 1622580048 397032304 21885584
05:54:34 1146 8192000000 10514339712 10954280960 1213070032 12.898 100 95 183 1 1622544336 387752720 21721584
05:55:37 2311 8192000000 10513947840 10954940416 1206739184 12.839 100 68 136 0 1623056784 399989904 16327696
05:56:45 1621 8192000000 10482333088 10958286848 1192858960 12.710 100 68 136 0 1596543936 386074800 17610176
05:57:48 18070 8192000000 10474694192 10924113920 1162619680 12.428 99 40 80 1 1586125600 412580800 10925120
05:59:22 16921 8192000000 10351869008 10834423808 1139860528 12.214 98 31 63 0 1478479248 329691936 8926784
06:00:40 20466 8192000000 10311606208 10793943040 1146982816 12.281 99 31 60 1 1439231936 284183120 8066000
06:02:12 86934 8192000000 10127531120 10584272896 1092438656 11.766 95 26 47 2 1290033168 192991360 4603152
06:04:47 34060 8192000000 10140437232 10602668032 1122947552 12.055 98 41 77 2 1236309488 104912224 8449712
06:06:28 42116 8192000000 10093849376 10552418304 1126082352 12.084 100 14 26 0 1217411408 88570368 2758688
06:08:18 48954 8192000000 10083874976 10518974464 1115751648 11.987 98 30 56 1 1141961856 20356976 5853232
06:10:51 51953 8192000000 10072051104 10521751552 1095403600 11.794 97 20 36 1 1138182688 38990000 3789088
06:12:44 94519 8192000000 10118757040 10555797504 1063598400 11.491 95 18 34 1 1156209456 88205552 4405504
06:15:19 55344 8192000000 10087799040 10585882624 1104408464 11.879 99 9 18 0 1205017344 98088720 2520160
06:17:16 96373 8192000000 10068183184 10549383168 1107722768 11.911 98 12 23 0 1151588896 41825200 2040928
06:19:53 115293 8192000000 10083056144 10579197952 1105903024 11.894 97 7 14 0 1177445776 70235856 1306896
06:22:48 68121 8192000000 10044087472 10474856448 1112030080 11.952 98 37 72 1 1135209824 15356096 7823648
06:25:02 13835 8192000000 10064501840 10496958464 1130834096 12.129 100 48 92 0 1167817616 27558000 9425520
06:26:17 2513 8192000000 10124570832 10541244416 1129605168 12.118 100 85 164 0 1212991536 65312896 18073472
06:27:21 7831 8192000000 10105707088 10537394176 1111421984 11.946 98 56 110 0 1203464096 80847040 11195072
06:28:31 30365 8192000000 10080941536 10547974144 1128204544 12.104 99 21 42 0 1183917856 51164640 4548672
06:30:29 228255 8192000000 10112883600 10562867200 1087844816 11.722 95 23 42 0 1208073072 115740400 4487856
06:36:37 77109 8192000000 10077097120 10510761984 1118222224 12.010 99 18 34 0 1172296256 50284800 3789232
06:38:58 30013 8192000000 10112115424 10558652416 1126497760 12.088 99 43 87 0 1200993296 65378592 9116944
06:40:32 20449 8192000000 10121431984 10557300736 1135295744 12.171 100 56 107 1 1185537488 38695472 11546272
06:41:54 52085 8192000000 10097159232 10522017792 1137663376 12.194 99 34 59 0 1188512928 44704720 6144832
06:43:47 39098 8192000000 10161230608 10607976448 1118034752 12.008 98 42 84 0 1223198128 96302672 8860704
06:45:27 90567 8192000000 10099783120 10544599040 1109878784 11.931 97 30 58 0 1138086960 22656608 5551568
06:48:03 121726 8192000000 10082162592 10547040256 1130054256 12.122 99 26 52 0 1216835616 81074032 5707328
06:51:05 53383 8192000000 10027502608 10469814272 1077270480 11.621 95 11 21 1 1113241936 34025840 1945616
06:54:59 46022 8192000000 10079312960 10516385792 1112611904 11.957 97 22 44 0 1162709712 45563232 4534576
06:56:45 39631 8192000000 10078606960 10540257280 1096107008 11.801 97 26 52 0 1177139904 75420160 5612736
06:58:33 36502 8192000000 10094504416 10519023616 1100411008 11.842 97 37 73 0 1162407840 54532384 7464448
07:00:33 55649 8192000000 10105646928 10575745024 1128546000 12.108 100 21 42 0 1206559104 73809904 4203200
07:02:29 16756 8192000000 10078311200 10497519616 1119441888 12.022 99 39 78 0 1165142944 38041760 7659296
07:03:51 14276 8192000000 10088394976 10515763200 1123615648 12.061 99 43 82 0 1184890224 52858240 8416336
07:05:11 33287 8192000000 10113391504 10588237824 1129779024 12.119 100 47 93 0 1216549456 77651504 9118928
07:06:46 46928 8192000000 10039554352 10458472448 1107799056 11.912 98 50 87 1 1137713200 20372992 9541152
07:08:34 47211 8192000000 10048865040 10500096000 1106466432 11.899 98 11 22 0 1136049936 27312544 2270960
07:10:29 74682 8192000000 10057501792 10523512832 1130163648 12.123 100 11 22 1 1171438688 38533888 2741152
07:12:44 56457 8192000000 10074469376 10515255296 1123229264 12.057 99 18 37 0 1172515120 45205552 4080304
07:14:41 27482 8192000000 10114177312 10552459264 1119268432 12.020 98 22 44 0 1187532240 62849728 5414080
07:16:20 27085 8192000000 10147851168 10590769152 1142281184 12.237 100 35 69 0 1241328048 91462336 7584528
07:17:49 29961 8192000000 10140592016 10592923648 1141937072 12.234 99 42 79 0 1246864336 95963824 8963440
07:19:20 38954 8192000000 10156328576 10599464960 1139887872 12.214 99 20 40 0 1236782304 92421072 4473360
07:21:09 33959 8192000000 10148809472 10584784896 1123873264 12.064 98 37 74 0 1224278016 93330464 7074288
07:22:47 26009 8192000000 10120719184 10593251328 1118518576 12.013 98 37 70 0 1188689008 62269328 7901104
07:24:19 64852 8192000000 10124446816 10631479296 1136981200 12.187 100 36 72 0 1245641616 101355648 7304768
07:26:27 45167 8192000000 10106320016 10550198272 1126297840 12.086 99 25 49 0 1193080496 61747504 5035152
07:28:16 91573 8192000000 10116275856 10557693952 1112661344 11.958 98 31 62 1 1209476224 90441936 6372944
07:30:49 54662 8192000000 10057575200 10536873984 1138140704 12.198 100 3 4 1 1164829280 26355248 333328
07:32:45 53173 8192000000 10097922720 10567446528 1136512608 12.183 100 12 24 0 1185569408 46823712 2233088
07:35:07 28499 8192000000 10164231840 10599768064 1139503888 12.211 98 33 64 2 1239582080 93204704 6873488
07:36:39 16464 8192000000 10203920640 10642337792 1147523952 12.286 99 32 62 0 1290053968 135019712 7510304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 27-10-2022 07:39:37 to 27-10-2022 07:39:54.

Exceptions occured during test was in run

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

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

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          218421
MON$OLDEST_ACTIVE               224883
MON$OLDEST_SNAPSHOT             224883
MON$NEXT_TRANSACTION            224883
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                       2034784
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2863 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2863 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2863 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2863 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 ---
:74: 
:75: mscs-test-161.budzdorov.ru    Thu Oct 27 07:37:26 2022
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Thu Oct 27 07:37:29 2022
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:81: 
:82: 
:83: mscs-test-161.budzdorov.ru    Thu Oct 27 07:37:30 2022
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:85: 
:86: 
:87: mscs-test-161.budzdorov.ru    Thu Oct 27 07:37:31 2022
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:89: 
:90: 
:91: mscs-test-161.budzdorov.ru    Thu Oct 27 07:37:31 2022
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:93: 
:94: 
:95: mscs-test-161.budzdorov.ru    Thu Oct 27 07:37:33 2022
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
--- end of diff output ---

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

27.10.22 07:39:58. 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-27 07:40:01 No severe PSQL-related problems occured
27.10.22 07:40:02. End of report.