Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.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 14677516 kB
MemAvailable 30070304 kB
Buffers 0 kB
Cached 17000060 kB
SwapCached 3296 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-11 13:37:21
DTS_END                         2022-12-11 13:37:21.7940
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/46170
TRN_ID                          217049
ATT_ID                          596
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: 9066.90 24351 1088028
customer order: creation 165.63 479 19876
customer order: refuse 14.38 371 1726
order to supplier: creation 44.70 1395 5364
order to supplier: removal 3.44 165061 413
invoice (draft): creation 44.10 3148 5292
invoice (draft): removal 53.23 1021 6388
invoice accept: apply 39.56 25950 4747
invoice accept: cancel 47.68 1101 5722
customer reserve: creation 3748.66 242 449839
customer reserve: removal 4611.88 96 553426
realization accept: apply 81.47 288 9777
realization accept: cancel 86.65 143 10398
payment from customer: creation 57.77 181 6932
payment from customer: removal 5.12 124 614
payment to supplier: creation 47.28 231 5674
payment to supplier: removal 3.17 153 380
service: total inventory turnovers 3.90 3872 468
service: total monetary turnovers 7.32 305 878
service: aggregate perf. data 0.91 14777 109
service: refresh index statistics 0.04 268090 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-12-2022 13:38:31 to 11-12-2022 13:38:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7167
TEST_TIME 2 7535
TEST_TIME 3 7146
TEST_TIME 4 6899
TEST_TIME 5 6477
TEST_TIME 6 6737
TEST_TIME 7 6639
TEST_TIME 8 6615
TEST_TIME 9 6537
TEST_TIME 10 6358
TEST_TIME 11 6271
TEST_TIME 12 6451
TEST_TIME 13 6581
TEST_TIME 14 6646
TEST_TIME 15 6677
TEST_TIME 16 6736
TEST_TIME 17 7025
TEST_TIME 18 7608
TEST_TIME 19 7718
TEST_TIME 20 7938
TEST_TIME 21 8405
TEST_TIME 22 8450
TEST_TIME 23 8567
TEST_TIME 24 8755
TEST_TIME 25 9443
TEST_TIME 26 9581
TEST_TIME 27 9724
TEST_TIME 28 9809
TEST_TIME 29 9719
TEST_TIME 30 10087
TEST_TIME 31 10498
TEST_TIME 32 10383
TEST_TIME 33 10175
TEST_TIME 34 10096
TEST_TIME 35 10041
TEST_TIME 36 10044
TEST_TIME 37 10273
TEST_TIME 38 10936
TEST_TIME 39 10739
TEST_TIME 40 10658
TEST_TIME 41 10629
TEST_TIME 42 10494
TEST_TIME 43 10356
TEST_TIME 44 10333
TEST_TIME 45 10361
TEST_TIME 46 10416
TEST_TIME 47 10342
TEST_TIME 48 10352
TEST_TIME 49 10346
TEST_TIME 50 10203
TEST_TIME 51 10128
TEST_TIME 52 9959
TEST_TIME 53 9978
TEST_TIME 54 9901
TEST_TIME 55 9802
TEST_TIME 56 9747
TEST_TIME 57 9691
TEST_TIME 58 9626
TEST_TIME 59 9663
TEST_TIME 60 9533
TEST_TIME 61 9644
TEST_TIME 62 9557
TEST_TIME 63 9484
TEST_TIME 64 9454
TEST_TIME 65 9530
TEST_TIME 66 9580
TEST_TIME 67 9520
TEST_TIME 68 9585
TEST_TIME 69 9549
TEST_TIME 70 9571
TEST_TIME 71 9641
TEST_TIME 72 9606
TEST_TIME 73 9648
TEST_TIME 74 9612
TEST_TIME 75 9605
TEST_TIME 76 9570
TEST_TIME 77 9602
TEST_TIME 78 9509
TEST_TIME 79 9477
TEST_TIME 80 9393
TEST_TIME 81 9332
TEST_TIME 82 9360
TEST_TIME 83 9315
TEST_TIME 84 9263
TEST_TIME 85 9211
TEST_TIME 86 9155
TEST_TIME 87 9160
TEST_TIME 88 9121
TEST_TIME 89 9103
TEST_TIME 90 9224
TEST_TIME 91 9186
TEST_TIME 92 9236
TEST_TIME 93 9205
TEST_TIME 94 9244
TEST_TIME 95 9207
TEST_TIME 96 9264
TEST_TIME 97 9237
TEST_TIME 98 9337
TEST_TIME 99 9346
TEST_TIME 100 9324
TEST_TIME 101 9271
TEST_TIME 102 9266
TEST_TIME 103 9195
TEST_TIME 104 9176
TEST_TIME 105 9210
TEST_TIME 106 9159
TEST_TIME 107 9134
TEST_TIME 108 9103
TEST_TIME 109 9081
TEST_TIME 110 9163
TEST_TIME 111 9127
TEST_TIME 112 9122
TEST_TIME 113 9087
TEST_TIME 114 9090
TEST_TIME 115 9148
TEST_TIME 116 9160
TEST_TIME 117 9149
TEST_TIME 118 9179
TEST_TIME 119 9160
TEST_TIME 120 9094

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-12-2022 13:38:35 to 11-12-2022 13:38:38.

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 1503056 1503056 0 0.00 0 23173 13 0 0
doc_list_biud 1503052 1503052 0 0.00 0 33332 41 0 0
sp_add_invoice_to_stock 4852 4747 105 2.16 38 224125 25951 0 105
sp_cancel_adding_invoice 5722 5722 0 0.00 6 79392 1101 0 0
sp_cancel_client_order 1726 1726 0 0.00 6 9952 371 0 0
sp_cancel_customer_reserve 553426 553426 0 0.00 1 33348 96 0 0
sp_cancel_pay_from_customer 614 614 0 0.00 4 5539 125 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 4 13458 154 0 0
sp_cancel_supplier_invoice 6395 6388 7 0.10 2 29317 1021 0 7
sp_cancel_supplier_order 413 413 0 0.00 25 1271665 165061 0 0
sp_cancel_write_off 10398 10398 0 0.00 2 10199 144 0 0
sp_client_order 19876 19876 0 0.00 5 21882 479 0 0
sp_customer_reserve 449839 449839 0 0.00 1 125944 243 0 0
sp_fill_shopping_cart 30522 30522 0 0.00 1 18322 78 0 0
sp_fill_shopping_cart_clo_res 449809 449809 0 0.00 0 18839 19 0 0
sp_get_clo_for_invoice 4705 4705 0 0.00 0 47745 294 0 0
sp_kill_cost_storno 17114 17114 0 0.00 0 15942 69 0 0
sp_kill_qstorno_ret_qs2qd 1124051 1124051 0 0.00 0 33327 43 0 0
sp_kill_qty_storno 592553 592553 0 0.00 0 33331 98 0 0
sp_lock_selected_doc 20011 20011 0 0.00 0 10312 9 0 0
sp_make_cost_storno 27086 27086 0 0.00 0 16818 89 0 0
sp_multiply_rows_for_pdistr 27086 27086 0 0.00 0 16274 42 0 0
sp_multiply_rows_for_qdistr 19876 19876 0 0.00 0 21720 190 0 0
sp_pay_from_customer 6932 6932 0 0.00 5 15591 181 0 0
sp_pay_to_supplier 5674 5674 0 0.00 6 19711 232 0 0
sp_payment_common 12606 12606 0 0.00 2 19664 44 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 9941 304 0 0
sp_qd_handle_on_invoice_upd_sts 10425 10425 0 0.00 0 18385 484 0 0
sp_qd_handle_on_reserve_upd_sts 9777 9777 0 0.00 0 17717 65 0 0
sp_reserve_write_off 9777 9777 0 0.00 8 18142 288 0 0
sp_supplier_invoice 5292 5292 0 0.00 10 116864 3148 0 0
sp_supplier_order 5364 5364 0 0.00 8 23263 1396 0 0
srv_aggregate_perf_data 109 109 0 0.00 2505 145407 14777 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 833 176534 38061 0 0
srv_find_qd_qs_mism 1041015 1041015 0 0.00 0 14499 9 0 0
srv_make_invnt_saldo 468 468 0 0.00 78 97190 3873 0 0
srv_make_money_saldo 878 878 0 0.00 1 13287 305 0 0
srv_recalc_idx_stat 5 5 0 0.00 46191 654274 268091 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 4753 4753 0 0.00 1 23162 851 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 1 13475 403 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 1008 25 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 1073 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 614 614 0 0.00 0 1104 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 944 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 826 28 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 88167 6868 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 2616 24 0 0
t$perf-norm:sp_client_order 19876 19876 0 0.00 0 25969 37 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 1114 13 0 0
t$perf-norm:sp_pay_from_customer 6932 6932 0 0.00 0 14264 23 0 0
t$perf-norm:sp_pay_to_supplier 5674 5674 0 0.00 0 4362 16 0 0
t$perf-norm:sp_reserve_write_off 9777 9777 0 0.00 0 8864 25 0 0
t$perf-norm:sp_supplier_invoice 5292 5292 0 0.00 0 5646 27 0 0
t$perf-norm:sp_supplier_order 5364 5364 0 0.00 0 2460 30 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 496 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 708 20 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 1398 19 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 1057 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 193 84 0 0
v_add_invoice_to_stock 9610 9400 210 2.18 0 10487 27 0 210
v_all_customers 36518 36518 0 0.00 0 176 4 0 0
v_all_suppliers 29994 29994 0 0.00 0 332 4 0 0
v_all_wares 39752 39752 0 0.00 0 236 4 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 8082 24 0 0
v_cancel_client_order 3452 3452 0 0.00 0 1407 9 0 0
v_cancel_customer_prepayment 1228 1228 0 0.00 0 5456 19 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 1463 10 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 2263 17 0 0
v_cancel_supplier_invoice 826 812 14 1.69 0 8238 43 0 14
v_cancel_supplier_order 826 826 0 0.00 0 8100 28 0 0
v_cancel_write_off 1936 1936 0 0.00 0 7608 24 0 0
v_max_id_clo_ord 5361 5361 0 0.00 0 4550 6 0 0
v_max_id_clo_res 985 985 0 0.00 0 897 10 0 0
v_max_id_ord_sup 5285 5285 0 0.00 0 6124 10 0 0
v_max_non_paid_invoice 1323 1323 0 0.00 0 153 7 0 0
v_max_non_paid_realizn 3574 3574 0 0.00 0 579 7 0 0
v_min_id_clo_ord 5361 5361 0 0.00 0 492 7 0 0
v_min_id_clo_res 985 985 0 0.00 0 5102 18 0 0
v_min_id_ord_sup 5285 5285 0 0.00 0 5702 9 0 0
v_min_non_paid_invoice 5674 5674 0 0.00 0 19657 21 0 0
v_min_non_paid_realizn 6932 6932 0 0.00 0 6599 17 0 0
v_our_firm 9950 9950 0 0.00 0 231 4 0 0
v_reserve_write_off 19554 19554 0 0.00 0 7990 12 0 0
x_lock_depdocs_on_canc_invoice 11704 11704 0 0.00 0 18586 134 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 11995 339 0 0
x_make_qstorno_1000_1200 5364 5364 0 0.00 2 23221 1239 0 0
x_make_qstorno_1200_2000 5292 5292 0 0.00 4 116692 2934 0 0
x_make_qstorno_2100_3300 449838 449838 0 0.00 0 125941 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-12-2022 13:38:39 to 11-12-2022 13:38:43.

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:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 35869 8192000000 9729820880 10030505984 798313088 8.879 100 83 164 1 825787312 7475808 19998416
11:10:07 23653 8192000000 9768478160 10077343744 840136576 9.301 100 90 179 0 874982608 13516464 21329568
11:11:34 14199 8192000000 9798027792 10120433664 865052288 9.551 100 72 144 0 904863440 23820112 15991040
11:12:53 12573 8192000000 9866920384 10203459584 913802928 10.035 100 70 138 0 977880672 48506288 15571456
11:14:08 1983 8192000000 9925214864 10267799552 964224304 10.530 100 90 178 0 1037110784 53938256 18948224
11:15:12 2467 8192000000 9988473296 10342223872 1001494544 10.893 100 90 173 0 1094918688 76282928 17141216
11:16:16 1328 8192000000 10009519248 10374922240 1022933520 11.100 100 92 177 0 1128994304 88667696 17393088
11:17:19 934 8192000000 10025351040 10402496512 1041609648 11.280 100 96 181 1 1149548016 89787136 18151232
11:18:22 990 8192000000 10034701248 10406772736 1060781040 11.464 100 95 183 0 1153138096 74786832 17570224
11:19:24 1380 8192000000 10027114288 10412605440 1068482384 11.538 100 94 177 2 1155401728 67681280 19238064
11:20:30 1187 8192000000 10041299680 10422190080 1074867568 11.599 100 94 182 0 1161808224 69180720 17759936
11:21:33 1378 8192000000 10024769872 10411417600 1079092672 11.639 100 69 136 1 1160432832 68509248 12830912
11:22:35 1246 8192000000 10056630832 10440335360 1081969392 11.666 100 93 182 2 1175191648 73661520 19560736
11:23:37 1222 8192000000 10075984720 10464329728 1089113104 11.734 100 96 185 0 1200437936 92476160 18848672
11:24:39 1086 8192000000 10051811776 10443530240 1094561584 11.786 100 82 159 3 1175552512 65209024 15781904
11:25:42 1078 8192000000 10075714976 10465923072 1099573968 11.834 100 95 184 1 1190375904 71907264 18894672
11:26:44 1069 8192000000 10049817936 10435633152 1096732032 11.807 100 97 186 5 1171784576 55493680 19558864
11:27:46 1245 8192000000 10087099824 10476613632 1087670992 11.721 99 96 187 1 1208987904 102400080 18916832
11:28:47 1249 8192000000 10064292640 10454712320 1097000448 11.809 100 95 181 2 1181932048 67340368 17591232
11:29:50 1136 8192000000 10055296592 10443710464 1100189152 11.839 100 97 188 1 1179165104 60574976 18400976
11:30:51 1229 8192000000 10047216768 10431930368 1090586704 11.748 100 97 181 0 1170086656 60242336 19257616
11:31:53 1470 8192000000 10037461808 10425110528 1075602208 11.606 100 83 157 2 1165124464 73664624 15857632
11:32:56 1105 8192000000 9990407536 10371928064 1045876832 11.321 99 79 150 1 1118337760 56380528 16080400
11:33:58 1005 8192000000 9997306944 10379587584 1056371280 11.422 100 83 165 2 1119222528 46311616 16539632
11:35:00 1093 8192000000 9993745200 10369740800 1056950384 11.427 100 87 163 2 1115906784 43067664 15888736
11:36:02 1193 8192000000 10037280896 10411728896 1042827760 11.292 100 96 174 0 1157194480 97259344 17107376
11:37:04 2998 8192000000 10037379632 10419634176 1042108912 11.285 99 66 126 2 1147522880 92867792 12546176
11:38:11 10275 8192000000 10001903568 10386108416 1061909248 11.475 100 62 103 3 1121635200 50030608 9695344
11:39:27 1306 8192000000 10031532896 10414374912 1081366416 11.660 100 93 173 2 1152165920 52686800 18112704
11:40:31 2256 8192000000 10068753344 10451439616 1099335760 11.831 100 81 153 0 1189722976 75244272 15142944
11:41:39 3967 8192000000 10099589904 10488467456 1114326896 11.973 100 75 146 0 1216384256 87072336 14985024
11:42:47 19801 8192000000 10139628784 10534813696 1115180336 11.981 100 72 142 1 1253332352 122780400 15371616
11:44:12 2705 8192000000 10160370016 10566881280 1138819600 12.204 100 79 152 1 1285483248 129667264 16996384
11:45:18 1257 8192000000 10224307216 10620604416 1142472592 12.239 100 81 152 1 1333097984 174549152 16076240
11:46:23 1980 8192000000 10237816704 10631950336 1156166384 12.367 100 87 164 0 1349587280 176205456 17215440
11:47:28 6396 8192000000 10274335824 10672820224 1164650064 12.447 100 81 157 2 1383405424 200087552 18667808
11:48:41 1015 8192000000 10340060880 10738757632 1175385424 12.547 100 99 194 1 1452845552 254211040 23249088
11:49:46 1348 8192000000 10362006400 10762412032 1178418848 12.575 100 95 187 1 1476160880 274741216 23000816
11:50:49 1404 8192000000 10421305504 10833915904 1186174592 12.648 100 93 179 1 1528018704 320253584 21590528
11:51:53 1322 8192000000 10457036736 10862743552 1183203888 12.620 100 93 182 1 1569207776 363426704 22577184
11:52:58 1444 8192000000 10475462992 10889801728 1195276816 12.732 100 87 165 2 1580445344 365005680 20162848
11:54:05 817 8192000000 10499268080 10935361536 1210697664 12.876 100 98 193 1 1605076112 370860928 23517520
11:55:07 1668 8192000000 10499480480 10966736896 1206880176 12.840 100 95 185 0 1614182960 383224080 24078704
11:56:12 1062 8192000000 10495748944 10923085824 1211778352 12.886 100 90 178 1 1602754944 369991984 20984608
11:57:19 1501 8192000000 10456563968 10922647552 1194752368 12.728 100 92 176 0 1559795728 343039328 22004032
11:58:23 20115 8192000000 10438403760 10877538304 1203549248 12.809 100 39 77 0 1556352896 343332304 9471344
11:59:45 12820 8192000000 10441356896 10892546048 1176237424 12.555 99 37 72 0 1539918928 353561568 10119936
12:00:59 16548 8192000000 10433674288 10907820032 1187985728 12.665 100 36 72 0 1561068208 363252800 9829680
12:02:19 22255 8192000000 10360160848 10896044032 1168936512 12.487 99 34 68 0 1484692976 306765104 8991360
12:03:43 18088 8192000000 10336690672 10783895552 1165385840 12.454 100 29 58 0 1440563552 267896128 7281584
12:05:06 2233 8192000000 10310718224 10752528384 1166504048 12.464 100 70 131 2 1415931904 235202960 14224896
12:06:13 5148 8192000000 10329726928 10762121216 1162129696 12.423 100 63 123 2 1438525824 260674912 15721216
12:07:20 10268 8192000000 10304402208 10760519680 1153543568 12.343 99 50 98 0 1401941584 236499792 11898224
12:08:31 38361 8192000000 10294178112 10787999744 1157542720 12.380 98 34 67 1 1370872416 205507008 7822688
12:10:16 3766 8192000000 10309898464 10740146176 1160695984 12.410 99 45 87 1 1404269904 233806768 9767152
12:11:27 12563 8192000000 10327834496 10792837120 1168224528 12.480 100 32 64 0 1438150976 261955312 7971136
12:12:52 50318 8192000000 10360578352 10904940544 1139574864 12.212 99 17 34 0 1450644192 306597312 4472016
12:15:27 73352 8192000000 10091846704 10577072128 1122353632 12.049 98 17 34 0 1195914560 70574496 2986432
12:17:40 75466 8192000000 10083260192 10516680704 1132311504 12.143 99 21 42 0 1162842336 26086736 4444096
12:19:58 28553 8192000000 10135048192 10557739008 1147193088 12.283 100 58 109 1 1222836736 63587296 12056352
12:21:35 4039 8192000000 10104529504 10547490816 1140949456 12.224 99 80 150 0 1191460496 33926544 16584496
12:22:45 96250 8192000000 10085697568 10539941888 1107288000 11.907 97 20 40 0 1197971680 85720704 4962976
12:25:21 114126 8192000000 10072051360 10516598784 1082602144 11.672 95 13 25 0 1149052720 63883520 2567056
12:28:28 77533 8192000000 10118891072 10561449984 1140116176 12.217 100 37 73 0 1195713424 47004992 8592256
12:31:07 176534 8192000000 10125494416 10563395584 1102471728 11.861 96 39 76 1 1151740496 41545888 7722880
12:35:08 36553 8192000000 10185173776 10607058944 1155828160 12.364 100 56 104 1 1259927440 92026160 12073120
12:36:50 29995 8192000000 10193674944 10670239744 1161746960 12.420 100 20 40 0 1303204992 136989104 4468928
12:39:07 57435 8192000000 10123910096 10577072128 1135307312 12.171 99 11 22 0 1231576896 94071200 2198384
12:41:05 64137 8192000000 10120857360 10603241472 1109872224 11.931 97 15 30 0 1204176784 91109632 3194928
12:43:11 46991 8192000000 10076663872 10534064128 1152686960 12.335 100 20 40 0 1185926704 29542400 3697344
12:45:00 48042 8192000000 10086607440 10542198784 1107255904 11.906 97 17 34 0 1155091984 44382096 3453984
12:47:01 45595 8192000000 10141611056 10600792064 1127576240 12.099 99 25 50 0 1229055328 96024288 5454800
12:48:47 42906 8192000000 10093216768 10516692992 1126137120 12.085 99 43 84 0 1174546560 39158912 9250528
12:50:31 83407 8192000000 10089377312 10556907520 1123322544 12.058 99 21 42 0 1191812000 64372128 4117328
12:52:58 38490 8192000000 10133946464 10566012928 1133038848 12.150 99 43 86 0 1226897216 84995408 8862960
12:54:36 81403 8192000000 10087974288 10562842624 1104562896 11.881 97 13 27 1 1174890800 67026272 3301632
12:57:24 64047 8192000000 10086758240 10505568256 1125769024 12.081 99 53 103 0 1161090848 24814640 10507184
12:59:30 77353 8192000000 10062778848 10535202816 1121011264 12.037 99 13 26 0 1166207488 42501808 2694416
13:01:47 31615 8192000000 10084968288 10523627520 1110863600 11.941 98 24 47 1 1161036032 45361760 4810672
13:03:32 67759 8192000000 10069958880 10547081216 1084639504 11.692 97 11 22 0 1170927856 83597248 2691104
13:05:42 66890 8192000000 10115849872 10566881280 1088087824 11.724 97 21 40 0 1184956176 92484144 4384208
13:07:57 106526 8192000000 10081486192 10547195904 1093157904 11.773 97 30 60 0 1163043744 63469024 6416816
13:10:43 66001 8192000000 10087748128 10511777792 1083107488 11.677 97 37 74 0 1198677808 108070256 7500064
13:13:19 7126 8192000000 10059287504 10491650048 1104143584 11.877 99 59 112 2 1161637968 46342224 11152160
13:14:28 43517 8192000000 10149321840 10582224896 1106370368 11.898 99 41 82 0 1225461440 111001728 8089344
13:16:14 48129 8192000000 10063091936 10518659072 1122975904 12.055 100 32 63 0 1182505840 53285248 6244688
13:18:03 40541 8192000000 10114896064 10569641984 1126418288 12.088 100 54 105 0 1201360912 63948672 10993952
13:20:11 39986 8192000000 10095139360 10551427072 1120297840 12.030 100 16 32 0 1197208048 73790672 3119536
13:21:58 82587 8192000000 10066412544 10569777152 1114976336 11.980 99 11 22 1 1166673120 49415264 2281520
13:24:21 78189 8192000000 10127220160 10588209152 1132671904 12.147 100 20 40 0 1206395520 68872560 4851056
13:27:25 65528 8192000000 10054560640 10530959360 1098488608 11.823 98 16 32 0 1160231168 58023520 3719040
13:29:31 42157 8192000000 10101152912 10543816704 1132017344 12.140 100 23 46 0 1212995616 75547440 5430832
13:31:14 26962 8192000000 10108827792 10557108224 1099528656 11.833 97 25 50 0 1179009408 74099840 5380912
13:32:41 75188 8192000000 10075325808 10509406208 1120233376 12.029 98 26 51 1 1188465136 62690704 5541056
13:34:58 62917 8192000000 10117457200 10573172736 1125038016 12.075 98 35 70 0 1208514896 76533344 6943536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

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:02, from 11-12-2022 13:39:02 to 11-12-2022 13:39:04.

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          214313
MON$OLDEST_ACTIVE               217162
MON$OLDEST_SNAPSHOT             217162
MON$NEXT_TRANSACTION            217162
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                       2074016
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2884 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2884 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2884 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2884 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 ---
:84: 
:85: mscs-test-161.budzdorov.ru    Sun Dec 11 13:37:35 2022
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Sun Dec 11 13:37:36 2022
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Sun Dec 11 13:37:40 2022
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:95: 
--- end of diff output ---

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

11.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-11 13:39:08 No severe PSQL-related problems occured
11.12.22 13:39:09. End of report.