Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.06.2022 13: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 16421140 kB
MemAvailable 31364248 kB
Buffers 0 kB
Cached 15331664 kB
SwapCached 2448 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-06-05 13:37:16
DTS_END                         2022-06-05 13:37:16.3220
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/39456
TRN_ID                          211587
ATT_ID                          639
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: 8475.43 30087 1017052
customer order: creation 158.57 445 19028
customer order: refuse 13.33 438 1599
order to supplier: creation 42.61 1405 5113
order to supplier: removal 3.18 178313 382
invoice (draft): creation 42.23 3590 5068
invoice (draft): removal 46.21 1166 5545
invoice accept: apply 39.38 28496 4725
invoice accept: cancel 42.28 1309 5074
customer reserve: creation 3687.65 270 442518
customer reserve: removal 4127.58 108 495309
realization accept: apply 77.52 268 9302
realization accept: cancel 74.89 181 8987
payment from customer: creation 55.30 197 6636
payment from customer: removal 4.94 125 593
payment to supplier: creation 44.60 200 5352
payment to supplier: removal 3.19 113 383
service: total inventory turnovers 3.71 4341 445
service: total monetary turnovers 7.33 401 880
service: aggregate perf. data 0.91 16537 109
service: refresh index statistics 0.03 363830 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-06-2022 13:39:56 to 05-06-2022 13:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6515
TEST_TIME 2 5877
TEST_TIME 3 5203
TEST_TIME 4 4870
TEST_TIME 5 4702
TEST_TIME 6 4725
TEST_TIME 7 4840
TEST_TIME 8 4973
TEST_TIME 9 5059
TEST_TIME 10 5306
TEST_TIME 11 5546
TEST_TIME 12 5707
TEST_TIME 13 5809
TEST_TIME 14 5839
TEST_TIME 15 6081
TEST_TIME 16 6206
TEST_TIME 17 6272
TEST_TIME 18 6383
TEST_TIME 19 6521
TEST_TIME 20 6671
TEST_TIME 21 6838
TEST_TIME 22 7228
TEST_TIME 23 7184
TEST_TIME 24 7382
TEST_TIME 25 7455
TEST_TIME 26 7806
TEST_TIME 27 8398
TEST_TIME 28 8873
TEST_TIME 29 9066
TEST_TIME 30 9038
TEST_TIME 31 9414
TEST_TIME 32 9621
TEST_TIME 33 9885
TEST_TIME 34 10111
TEST_TIME 35 10008
TEST_TIME 36 9828
TEST_TIME 37 9777
TEST_TIME 38 9715
TEST_TIME 39 9655
TEST_TIME 40 9501
TEST_TIME 41 9388
TEST_TIME 42 9289
TEST_TIME 43 9433
TEST_TIME 44 9288
TEST_TIME 45 9224
TEST_TIME 46 9369
TEST_TIME 47 9193
TEST_TIME 48 9181
TEST_TIME 49 9059
TEST_TIME 50 9005
TEST_TIME 51 9015
TEST_TIME 52 9009
TEST_TIME 53 8922
TEST_TIME 54 8888
TEST_TIME 55 8850
TEST_TIME 56 8812
TEST_TIME 57 8724
TEST_TIME 58 8695
TEST_TIME 59 8585
TEST_TIME 60 8721
TEST_TIME 61 8657
TEST_TIME 62 8598
TEST_TIME 63 8586
TEST_TIME 64 8588
TEST_TIME 65 8558
TEST_TIME 66 8536
TEST_TIME 67 8623
TEST_TIME 68 8649
TEST_TIME 69 8612
TEST_TIME 70 8650
TEST_TIME 71 8662
TEST_TIME 72 8675
TEST_TIME 73 8792
TEST_TIME 74 8836
TEST_TIME 75 8837
TEST_TIME 76 8779
TEST_TIME 77 8684
TEST_TIME 78 8659
TEST_TIME 79 8589
TEST_TIME 80 8585
TEST_TIME 81 8558
TEST_TIME 82 8518
TEST_TIME 83 8478
TEST_TIME 84 8434
TEST_TIME 85 8410
TEST_TIME 86 8361
TEST_TIME 87 8386
TEST_TIME 88 8380
TEST_TIME 89 8369
TEST_TIME 90 8372
TEST_TIME 91 8380
TEST_TIME 92 8374
TEST_TIME 93 8403
TEST_TIME 94 8436
TEST_TIME 95 8407
TEST_TIME 96 8456
TEST_TIME 97 8528
TEST_TIME 98 8575
TEST_TIME 99 8671
TEST_TIME 100 8635
TEST_TIME 101 8628
TEST_TIME 102 8624
TEST_TIME 103 8627
TEST_TIME 104 8597
TEST_TIME 105 8625
TEST_TIME 106 8605
TEST_TIME 107 8655
TEST_TIME 108 8672
TEST_TIME 109 8678
TEST_TIME 110 8670
TEST_TIME 111 8689
TEST_TIME 112 8669
TEST_TIME 113 8633
TEST_TIME 114 8599
TEST_TIME 115 8583
TEST_TIME 116 8577
TEST_TIME 117 8530
TEST_TIME 118 8549
TEST_TIME 119 8547
TEST_TIME 120 8502

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-06-2022 13:40:00 to 05-06-2022 13:40:03.

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 1422774 1422774 0 0.00 0 13894 14 0 0
doc_list_biud 1422769 1422769 0 0.00 0 64108 44 0 0
sp_add_invoice_to_stock 4826 4725 101 2.09 20 192709 28496 0 101
sp_cancel_adding_invoice 5074 5074 0 0.00 7 118925 1309 0 0
sp_cancel_client_order 1599 1599 0 0.00 6 6904 438 0 0
sp_cancel_customer_reserve 495309 495309 0 0.00 1 64114 108 0 0
sp_cancel_pay_from_customer 593 593 0 0.00 4 3786 125 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 4 1835 114 0 0
sp_cancel_supplier_invoice 5554 5545 9 0.16 3 28912 1167 0 9
sp_cancel_supplier_order 382 382 0 0.00 17 1312233 178314 0 0
sp_cancel_write_off 8987 8987 0 0.00 2 20275 182 0 0
sp_client_order 19028 19028 0 0.00 6 35867 445 0 0
sp_customer_reserve 442518 442518 0 0.00 1 38365 271 0 0
sp_fill_shopping_cart 29203 29203 0 0.00 1 47779 86 0 0
sp_fill_shopping_cart_clo_res 442487 442487 0 0.00 0 26280 20 0 0
sp_get_clo_for_invoice 4689 4689 0 0.00 0 30505 327 0 0
sp_kill_cost_storno 15037 15037 0 0.00 0 13885 85 0 0
sp_kill_qstorno_ret_qs2qd 1005532 1005532 0 0.00 0 64102 49 0 0
sp_kill_qty_storno 530887 530887 0 0.00 0 64107 110 0 0
sp_lock_selected_doc 19253 19253 0 0.00 0 8396 6 0 0
sp_make_cost_storno 25979 25979 0 0.00 0 12401 84 0 0
sp_multiply_rows_for_pdistr 25979 25979 0 0.00 0 12041 43 0 0
sp_multiply_rows_for_qdistr 19028 19028 0 0.00 0 32500 167 0 0
sp_pay_from_customer 6636 6636 0 0.00 5 12050 198 0 0
sp_pay_to_supplier 5352 5352 0 0.00 7 14258 200 0 0
sp_payment_common 11988 11988 0 0.00 2 11390 43 0 0
sp_qd_handle_on_cancel_clo 1599 1599 0 0.00 1 6897 363 0 0
sp_qd_handle_on_invoice_upd_sts 9763 9763 0 0.00 0 17036 524 0 0
sp_qd_handle_on_reserve_upd_sts 9301 9301 0 0.00 0 15378 64 0 0
sp_reserve_write_off 9302 9302 0 0.00 8 15696 269 0 0
sp_supplier_invoice 5068 5068 0 0.00 9 111850 3591 0 0
sp_supplier_order 5113 5113 0 0.00 7 58611 1406 0 0
srv_aggregate_perf_data 109 109 0 0.00 2294 186446 16537 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 925 158262 35196 0 0
srv_find_qd_qs_mism 970358 970358 0 0.00 0 9013 11 0 0
srv_make_invnt_saldo 445 445 0 0.00 50 119729 4341 0 0
srv_make_money_saldo 880 880 0 0.00 1 11183 401 0 0
srv_recalc_idx_stat 4 4 0 0.00 123057 627191 363830 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4729 4729 0 0.00 1 28322 907 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 1 8447 490 0 0
t$perf-norm:sp_cancel_client_order 1599 1599 0 0.00 0 2723 28 0 0
t$perf-norm:sp_cancel_customer_reserve 618 618 0 0.00 0 3647 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 593 593 0 0.00 0 2622 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 2466 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 3018 44 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 103469 7391 0 0
t$perf-norm:sp_cancel_write_off 929 929 0 0.00 0 3099 22 0 0
t$perf-norm:sp_client_order 19028 19028 0 0.00 0 8655 36 0 0
t$perf-norm:sp_customer_reserve 922 922 0 0.00 0 3564 27 0 0
t$perf-norm:sp_pay_from_customer 6636 6636 0 0.00 0 8701 20 0 0
t$perf-norm:sp_pay_to_supplier 5353 5353 0 0.00 0 7716 20 0 0
t$perf-norm:sp_reserve_write_off 9302 9302 0 0.00 0 9458 23 0 0
t$perf-norm:sp_supplier_invoice 5068 5068 0 0.00 0 7412 33 0 0
t$perf-norm:sp_supplier_order 5113 5113 0 0.00 0 18491 38 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 479 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 9262 135 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 1165 21 0 0
t$perf-norm:srv_make_money_saldo 880 880 0 0.00 0 6377 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 584 205 0 0
v_add_invoice_to_stock 9576 9374 202 2.10 0 5211 24 0 202
v_all_customers 35438 35438 0 0.00 0 189 3 0 0
v_all_suppliers 28166 28166 0 0.00 0 62 4 0 0
v_all_wares 38056 38056 0 0.00 0 55 3 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 4964 20 0 0
v_cancel_client_order 3198 3198 0 0.00 0 5176 14 0 0
v_cancel_customer_prepayment 1186 1186 0 0.00 0 3714 14 0 0
v_cancel_customer_reserve 1236 1236 0 0.00 0 3834 17 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 1815 13 0 0
v_cancel_supplier_invoice 768 750 18 2.34 0 4083 30 0 18
v_cancel_supplier_order 764 764 0 0.00 0 3036 16 0 0
v_cancel_write_off 1858 1858 0 0.00 0 11703 31 0 0
v_max_id_clo_ord 5109 5109 0 0.00 0 2119 5 0 0
v_max_id_clo_res 922 922 0 0.00 0 18377 35 0 0
v_max_id_ord_sup 5064 5064 0 0.00 0 2924 6 0 0
v_max_non_paid_invoice 1442 1442 0 0.00 0 7726 13 0 0
v_max_non_paid_realizn 3293 3293 0 0.00 0 9412 10 0 0
v_min_id_clo_ord 5109 5109 0 0.00 0 6250 17 0 0
v_min_id_clo_res 922 922 0 0.00 0 8882 28 0 0
v_min_id_ord_sup 5064 5064 0 0.00 0 7199 13 0 0
v_min_non_paid_invoice 5351 5351 0 0.00 0 9281 14 0 0
v_min_non_paid_realizn 6636 6636 0 0.00 0 10896 17 0 0
v_our_firm 9300 9300 0 0.00 0 52 4 0 0
v_reserve_write_off 18598 18598 0 0.00 0 5619 12 0 0
x_lock_depdocs_on_canc_invoice 10244 10244 0 0.00 0 9922 135 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 8762 421 0 0
x_make_qstorno_1000_1200 5113 5113 0 0.00 2 58593 1230 0 0
x_make_qstorno_1200_2000 5068 5068 0 0.00 4 103143 3337 0 0
x_make_qstorno_2100_3300 442518 442518 0 0.00 0 34264 239 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-06-2022 13:40:04 to 05-06-2022 13:40:08.

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:19 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:20 32414 8192000000 9712174896 10009059328 791839936 8.814 100 86 172 2 820340336 7197504 21302896
11:09:56 26857 8192000000 9766286288 10072977408 836234624 9.262 100 87 173 1 869022464 11983920 20803920
11:11:29 20770 8192000000 9804534576 10123370496 868394368 9.584 100 82 164 0 912351984 25322544 18635072
11:12:54 8329 8192000000 9861517072 10194743296 922896128 10.125 100 80 160 1 975341728 34930608 17514992
11:14:09 4639 8192000000 9933582960 10277883904 970837296 10.595 100 86 169 4 1043214144 54227456 18149392
11:15:19 1692 8192000000 9974527456 10333339648 1003184720 10.909 100 87 164 2 1098205168 78876512 16143936
11:16:24 1227 8192000000 10020080832 10383208448 1034685664 11.214 100 95 189 0 1134181104 80040128 19455312
11:17:28 992 8192000000 10021367920 10391085056 1052968592 11.389 100 95 187 1 1140067616 68553408 18545616
11:18:30 1070 8192000000 10027341344 10407690240 1062463632 11.480 100 98 186 1 1147629008 67239840 17925536
11:19:32 984 8192000000 10053926496 10431320064 1071826480 11.570 100 95 187 1 1173938432 83440640 18671312
11:20:35 1200 8192000000 10037262976 10416599040 1076739888 11.616 100 90 173 1 1162207408 67543360 17924160
11:21:37 2147 8192000000 10037208832 10421604352 1079899600 11.647 100 81 148 5 1157889216 62575856 15413760
11:22:40 7655 8192000000 10035492544 10431881216 1083847600 11.684 100 55 105 2 1148543264 54077616 10618048
11:23:48 1008 8192000000 10057678496 10442403840 1083923200 11.685 100 93 178 0 1175239712 74701040 16615472
11:24:50 1329 8192000000 10061557888 10450464768 1087605168 11.720 100 94 181 1 1184383296 77738976 19039152
11:25:52 1332 8192000000 10074735200 10461966336 1095089312 11.791 100 90 178 0 1196657232 83314752 18253168
11:26:57 1274 8192000000 10090689936 10484035584 1098169536 11.820 100 100 193 0 1214442064 96652144 19620384
11:28:00 1723 8192000000 10057822880 10449453056 1086670064 11.711 99 83 157 1 1178546912 76415312 15461536
11:29:04 952 8192000000 10026461600 10410872832 1081243792 11.659 100 89 170 0 1147563008 47389984 18929232
11:30:05 2040 8192000000 10020476512 10413420544 1065545120 11.510 99 74 147 1 1139299344 58903872 14850352
11:31:13 1091 8192000000 10002577152 10378559488 1050806448 11.368 99 93 177 4 1120915024 52459520 17649056
11:32:16 1554 8192000000 10010807520 10388004864 1050857488 11.369 100 81 157 0 1131107504 64364064 15885952
11:33:19 971 8192000000 9996380080 10373304320 1039030720 11.255 100 89 172 2 1117678448 60452832 18194896
11:34:21 1574 8192000000 9991554560 10376093696 1045518000 11.318 100 74 142 0 1116901968 56451136 14932832
11:35:24 1236 8192000000 10014350128 10391289856 1037855568 11.244 100 95 178 1 1140963872 83721856 19386448
11:36:27 2955 8192000000 9971002656 10351734784 1051622928 11.376 100 63 115 2 1098938528 34013008 13302592
11:37:34 6016 8192000000 9982134592 10367369216 1052751376 11.387 100 65 107 1 1104161568 40704224 10705968
11:38:49 5789 8192000000 10027216752 10409500672 1063028224 11.485 100 69 131 1 1150279680 75252992 11998464
11:40:04 7353 8192000000 10017012016 10406592512 1077709472 11.626 100 66 121 3 1138740640 48999600 12031568
11:41:29 3226 8192000000 10078289392 10466410496 1085907072 11.704 100 62 119 1 1193111248 95139152 12065024
11:42:37 11535 8192000000 10078995776 10463608832 1089277440 11.736 100 75 139 1 1197083776 93968608 13837728
11:43:53 1455 8192000000 10128642784 10516148224 1113968432 11.970 100 87 161 1 1247064080 115287488 17808160
11:44:58 1117 8192000000 10169108144 10557198336 1120981856 12.036 100 89 170 1 1282878464 142148000 19748608
11:46:02 2846 8192000000 10184808400 10584608768 1129239440 12.114 100 77 146 0 1302056400 156345936 16471024
11:47:09 2093 8192000000 10211788976 10612707328 1145650944 12.269 100 92 175 0 1322243568 158513712 18078912
11:48:13 1055 8192000000 10280820576 10680705024 1169423696 12.491 100 94 182 2 1392438912 202889936 20125280
11:49:19 1510 8192000000 10323794256 10728218624 1182188128 12.611 100 86 169 0 1435630608 234656016 18786464
11:50:25 1233 8192000000 10344585744 10757185536 1190169040 12.685 100 80 155 0 1455248896 247646928 17432928
11:51:31 1029 8192000000 10395653072 10808221696 1197239872 12.751 100 93 179 1 1504088272 284352288 22496112
11:52:35 920 8192000000 10416848816 10835558400 1199300144 12.770 100 94 186 0 1524613744 303388032 21925568
11:53:40 2530 8192000000 10399556768 10828865536 1198314352 12.761 100 88 168 0 1512455888 293938480 20203056
11:54:48 1690 8192000000 10461492464 10881245184 1204231648 12.816 100 87 173 1 1567381792 342558288 20591856
11:55:55 958 8192000000 10479468592 10893496320 1200314416 12.779 100 97 187 1 1584248656 362620384 21313856
11:56:58 1083 8192000000 10525141632 10952814592 1213166192 12.898 100 95 183 2 1635026832 399550528 22310112
11:58:03 1023 8192000000 10524578880 10962731008 1192105792 12.703 100 94 187 1 1629338704 414945376 22287536
11:59:08 1259 8192000000 10545314640 11000008704 1201826480 12.793 100 97 195 2 1655803584 430743664 23233440
12:00:13 4884 8192000000 10580841632 11018170368 1200057984 12.777 100 55 110 1 1691632752 477586528 13988240
12:01:20 2563 8192000000 10565765328 11012968448 1194932400 12.729 100 58 115 1 1671315040 462389488 13993152
12:02:30 5199 8192000000 10528728880 10978091008 1176661008 12.559 100 50 99 1 1637436992 447845376 12930608
12:03:47 5788 8192000000 10487079696 10947235840 1169431360 12.492 100 55 102 1 1613135552 430762304 12941888
12:05:08 26879 8192000000 10348044688 10857742336 1172932064 12.524 100 42 79 2 1476042704 293106400 10004240
12:06:38 17658 8192000000 10299470304 10785865728 1154798688 12.355 100 21 42 1 1418735056 258643792 5292576
12:07:58 15709 8192000000 10307804272 10766131200 1159838064 12.402 100 42 83 1 1413963360 244249984 9875312
12:09:16 33918 8192000000 10220573712 10720821248 1159871072 12.402 100 28 56 1 1359901568 193211200 6819296
12:10:54 15523 8192000000 10142809648 10608091136 1121957472 12.045 98 34 67 2 1244588976 115064448 7567056
12:12:11 63442 8192000000 10131827856 10564063232 1137650896 12.193 100 49 96 2 1230445200 81895904 10898400
12:14:49 31860 8192000000 10114713968 10572820480 1142082160 12.235 100 26 49 1 1226927456 79687136 5158160
12:16:37 29483 8192000000 10124115520 10570608640 1144848784 12.261 100 27 55 0 1218911184 68006704 6055696
12:18:17 56075 8192000000 10166683200 10628239360 1115268064 11.982 97 31 59 3 1246447264 124665872 6513328
12:20:28 85838 8192000000 10145920944 10601082880 1095268816 11.793 96 20 35 1 1200263248 100996432 3998000
12:22:54 158262 8192000000 10078330720 10544414720 1082541920 11.672 95 25 49 3 1134176960 45704224 5930816
12:26:33 41882 8192000000 10090173696 10556882944 1115067936 11.980 99 27 54 0 1210993184 90546432 5378816
12:28:18 87366 8192000000 10079831632 10556645376 1104896912 11.884 98 16 32 1 1153744352 45560480 3286960
12:31:14 48822 8192000000 10105350880 10570268672 1118507984 12.013 98 14 25 1 1200040656 78334672 3198000
12:33:03 53150 8192000000 10094194064 10538782720 1131216848 12.133 99 21 42 1 1200162512 64204976 4740688
12:34:58 83513 8192000000 10140889184 10608050176 1150595056 12.315 100 16 32 0 1215641200 61648768 3397376
12:37:41 85847 8192000000 10069197040 10528165888 1150660896 12.316 100 13 27 2 1198635984 45498032 2477056
12:40:10 13943 8192000000 10073944912 10512957440 1117131168 12.000 97 49 88 0 1155854096 29648848 9074080
12:41:44 20021 8192000000 10058116768 10502361088 1114388528 11.974 98 27 54 0 1140915472 20855632 5671312
12:43:06 17184 8192000000 10104675504 10534780928 1121531232 12.041 98 51 96 2 1193220704 62027136 9662336
12:44:24 66685 8192000000 10110611280 10566291456 1101664384 11.853 97 21 42 0 1208542512 102147136 4730992
12:46:31 80020 8192000000 10108947248 10542510080 1087291760 11.717 95 27 53 0 1142568960 50407264 4869936
12:48:54 24498 8192000000 10124521056 10561908736 1118099264 12.009 99 44 87 2 1195082128 68063776 8919088
12:50:27 102997 8192000000 10086089632 10553548800 1097296464 11.812 98 10 19 1 1150827456 51257376 2273616
12:54:13 126903 8192000000 10041844880 10495143936 1090527792 11.748 97 36 71 1 1148189264 50386592 7274880
12:57:21 38230 8192000000 10046556976 10490249216 1114773888 11.978 99 30 49 5 1152197584 33130384 4293312
12:59:01 41490 8192000000 10052807648 10513760256 1133401152 12.153 100 10 20 1 1163263136 27709440 2152544
13:00:43 63544 8192000000 10068898032 10540232704 1133419024 12.154 100 8 16 0 1164940832 29793120 1728688
13:02:47 42332 8192000000 10092425072 10549317632 1133476288 12.154 99 20 33 2 1200844352 63971264 3396800
13:04:45 39351 8192000000 10124434656 10558423040 1126793600 12.091 98 21 42 0 1205527040 74037936 4695504
13:06:30 47285 8192000000 10165653584 10604924928 1141789872 12.232 99 33 66 1 1248554416 99165344 7599200
13:08:18 19455 8192000000 10174213696 10630422528 1151456016 12.323 100 45 89 2 1271156160 110351904 9348240
13:09:42 19885 8192000000 10219243376 10660204544 1147261008 12.284 99 49 98 0 1319024016 160935632 10827376
13:11:02 31634 8192000000 10167163472 10626703360 1111612144 11.948 97 45 87 1 1265733088 144345504 9775440
13:12:41 16363 8192000000 10221646768 10646458368 1136361824 12.181 100 54 104 1 1325099312 177453520 11283968
13:14:07 70585 8192000000 10160896304 10628984832 1117741376 12.006 98 16 32 1 1275254624 153575744 3937504
13:16:22 100724 8192000000 10074848768 10544553984 1108546800 11.919 98 22 44 1 1212686192 99758224 4381168
13:19:12 59880 8192000000 10083574992 10511863808 1107993008 11.913 98 22 44 2 1151198768 38778448 4427312
13:21:15 36119 8192000000 10083030272 10509131776 1097002368 11.809 98 29 58 1 1154980816 52224384 5754064
13:22:57 8210 8192000000 10062862848 10485616640 1114291472 11.973 100 53 104 2 1148266272 23355872 10618928
13:24:10 90537 8192000000 10055002976 10539425792 1108521952 11.918 98 29 59 0 1170287424 55919824 5845648
13:26:43 12473 8192000000 10073350080 10489262080 1119364816 12.021 99 42 83 1 1152816512 26057824 7393872
13:28:07 99477 8192000000 10098836288 10605170688 1128695920 12.109 100 20 40 0 1242289536 109480176 4113440
13:31:16 52306 8192000000 10104527536 10542886912 1107214256 11.906 98 36 72 2 1185175120 69475120 8485744
13:33:10 69829 8192000000 10084974512 10543722496 1086989120 11.714 97 10 20 0 1171142176 82019184 2133872
13:35:21 42134 8192000000 10061386496 10499559424 1088285792 11.726 98 23 46 0 1150704704 57162960 5255952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-06-2022 13:40:10 to 05-06-2022 13:40:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-06-2022 13:40:27 to 05-06-2022 13:40:29.

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          210338
MON$OLDEST_ACTIVE               211764
MON$OLDEST_SNAPSHOT             211764
MON$NEXT_TRANSACTION            211764
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                       2035552
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:168: 
:169: mscs-test-161.budzdorov.ru    Sun Jun  5 13:37:32 2022
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:171: 
:172: 
:173: mscs-test-161.budzdorov.ru    Sun Jun  5 13:37:35 2022
:174:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:175: 
:176: 
:177: mscs-test-161.budzdorov.ru    Sun Jun  5 13:37:47 2022
:178:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:179: 
--- end of diff output ---

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

05.06.22 13:40:30. 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-06-05 13:40:32 No severe PSQL-related problems occured
05.06.22 13:40:33. End of report.