Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.02.2023 10: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 15075044 kB
MemAvailable 30363172 kB
Buffers 0 kB
Cached 16591176 kB
SwapCached 3572 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 2023-02-05 10:37:25
DTS_END                         2023-02-05 10:37:25.1520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52996
TRN_ID                          210055
ATT_ID                          582
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: 8521.27 23691 1022552
customer order: creation 168.09 503 20171
customer order: refuse 14.58 413 1749
order to supplier: creation 44.15 1371 5298
order to supplier: removal 3.24 167076 389
invoice (draft): creation 44.78 3288 5373
invoice (draft): removal 46.99 1017 5639
invoice accept: apply 41.19 26289 4943
invoice accept: cancel 42.00 1262 5040
customer reserve: creation 3710.97 257 445316
customer reserve: removal 4120.51 102 494461
realization accept: apply 80.92 272 9710
realization accept: cancel 76.64 171 9197
payment from customer: creation 57.92 186 6950
payment from customer: removal 5.32 146 638
payment to supplier: creation 48.93 230 5871
payment to supplier: removal 3.13 119 375
service: total inventory turnovers 3.85 3786 462
service: total monetary turnovers 7.14 374 857
service: aggregate perf. data 0.90 17034 108
service: refresh index statistics 0.04 249931 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-02-2023 10:38:40 to 05-02-2023 10:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4795
TEST_TIME 2 4859
TEST_TIME 3 4689
TEST_TIME 4 5425
TEST_TIME 5 5318
TEST_TIME 6 5614
TEST_TIME 7 5844
TEST_TIME 8 6334
TEST_TIME 9 6337
TEST_TIME 10 6557
TEST_TIME 11 7111
TEST_TIME 12 7101
TEST_TIME 13 7208
TEST_TIME 14 7356
TEST_TIME 15 7416
TEST_TIME 16 7435
TEST_TIME 17 7620
TEST_TIME 18 8034
TEST_TIME 19 8216
TEST_TIME 20 8582
TEST_TIME 21 8477
TEST_TIME 22 8486
TEST_TIME 23 8590
TEST_TIME 24 8726
TEST_TIME 25 8709
TEST_TIME 26 8962
TEST_TIME 27 9088
TEST_TIME 28 9223
TEST_TIME 29 9421
TEST_TIME 30 9363
TEST_TIME 31 9819
TEST_TIME 32 10311
TEST_TIME 33 11022
TEST_TIME 34 11096
TEST_TIME 35 11076
TEST_TIME 36 10799
TEST_TIME 37 10614
TEST_TIME 38 10524
TEST_TIME 39 10432
TEST_TIME 40 10324
TEST_TIME 41 10183
TEST_TIME 42 10099
TEST_TIME 43 9937
TEST_TIME 44 9866
TEST_TIME 45 9810
TEST_TIME 46 9752
TEST_TIME 47 9615
TEST_TIME 48 9510
TEST_TIME 49 9516
TEST_TIME 50 9378
TEST_TIME 51 9273
TEST_TIME 52 9181
TEST_TIME 53 9179
TEST_TIME 54 9138
TEST_TIME 55 9145
TEST_TIME 56 9037
TEST_TIME 57 9016
TEST_TIME 58 9037
TEST_TIME 59 8953
TEST_TIME 60 8947
TEST_TIME 61 8950
TEST_TIME 62 8899
TEST_TIME 63 8842
TEST_TIME 64 8965
TEST_TIME 65 8918
TEST_TIME 66 8952
TEST_TIME 67 8993
TEST_TIME 68 9055
TEST_TIME 69 8986
TEST_TIME 70 9002
TEST_TIME 71 9021
TEST_TIME 72 9072
TEST_TIME 73 9020
TEST_TIME 74 9018
TEST_TIME 75 9077
TEST_TIME 76 9087
TEST_TIME 77 9118
TEST_TIME 78 9081
TEST_TIME 79 9003
TEST_TIME 80 8946
TEST_TIME 81 8961
TEST_TIME 82 8897
TEST_TIME 83 8886
TEST_TIME 84 8840
TEST_TIME 85 8810
TEST_TIME 86 8768
TEST_TIME 87 8694
TEST_TIME 88 8739
TEST_TIME 89 8710
TEST_TIME 90 8734
TEST_TIME 91 8817
TEST_TIME 92 8777
TEST_TIME 93 8775
TEST_TIME 94 8853
TEST_TIME 95 8860
TEST_TIME 96 8852
TEST_TIME 97 8908
TEST_TIME 98 8883
TEST_TIME 99 8881
TEST_TIME 100 8859
TEST_TIME 101 8898
TEST_TIME 102 8897
TEST_TIME 103 8899
TEST_TIME 104 8831
TEST_TIME 105 8814
TEST_TIME 106 8798
TEST_TIME 107 8756
TEST_TIME 108 8709
TEST_TIME 109 8686
TEST_TIME 110 8670
TEST_TIME 111 8633
TEST_TIME 112 8595
TEST_TIME 113 8593
TEST_TIME 114 8584
TEST_TIME 115 8593
TEST_TIME 116 8579
TEST_TIME 117 8581
TEST_TIME 118 8579
TEST_TIME 119 8612
TEST_TIME 120 8551

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-02-2023 10:38:44 to 05-02-2023 10:38:47.

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 1433880 1433880 0 0.00 0 20861 14 0 0
doc_list_biud 1433864 1433864 0 0.00 0 26345 41 0 0
sp_add_invoice_to_stock 5058 4943 115 2.27 56 238228 26289 0 115
sp_cancel_adding_invoice 5040 5040 0 0.00 5 105950 1263 0 0
sp_cancel_client_order 1749 1749 0 0.00 6 7896 413 0 0
sp_cancel_customer_reserve 494461 494461 0 0.00 1 21173 103 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 3 5285 146 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 4 2938 120 0 0
sp_cancel_supplier_invoice 5650 5639 11 0.19 2 24916 1018 0 11
sp_cancel_supplier_order 389 389 0 0.00 5 1423129 167076 0 0
sp_cancel_write_off 9197 9197 0 0.00 2 10736 171 0 0
sp_client_order 20171 20171 0 0.00 5 25763 503 0 0
sp_customer_reserve 445316 445316 0 0.00 1 47417 258 0 0
sp_fill_shopping_cart 30833 30833 0 0.00 1 22718 92 0 0
sp_fill_shopping_cart_clo_res 445281 445281 0 0.00 0 12596 19 0 0
sp_get_clo_for_invoice 4900 4900 0 0.00 0 40442 318 0 0
sp_kill_cost_storno 15250 15250 0 0.00 0 10678 77 0 0
sp_kill_qstorno_ret_qs2qd 1004147 1004147 0 0.00 0 24859 44 0 0
sp_kill_qty_storno 531085 531085 0 0.00 0 26345 102 0 0
sp_lock_selected_doc 20124 20124 0 0.00 0 20746 9 0 0
sp_make_cost_storno 27431 27431 0 0.00 0 13514 78 0 0
sp_multiply_rows_for_pdistr 27431 27431 0 0.00 0 11980 37 0 0
sp_multiply_rows_for_qdistr 20170 20170 0 0.00 0 25642 189 0 0
sp_pay_from_customer 6950 6950 0 0.00 5 21536 186 0 0
sp_pay_to_supplier 5871 5871 0 0.00 6 21731 231 0 0
sp_payment_common 12821 12821 0 0.00 2 15666 52 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 1 7888 330 0 0
sp_qd_handle_on_invoice_upd_sts 9940 9940 0 0.00 0 26345 546 0 0
sp_qd_handle_on_reserve_upd_sts 9710 9710 0 0.00 0 14422 60 0 0
sp_reserve_write_off 9710 9710 0 0.00 7 14479 272 0 0
sp_supplier_invoice 5373 5373 0 0.00 9 85272 3288 0 0
sp_supplier_order 5298 5298 0 0.00 8 49404 1372 0 0
srv_aggregate_perf_data 108 108 0 0.00 2540 385258 17035 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 901 176638 39146 0 0
srv_find_qd_qs_mism 975574 975574 0 0.00 0 20826 11 0 0
srv_make_invnt_saldo 462 462 0 0.00 85 67628 3786 0 0
srv_make_money_saldo 857 857 0 0.00 1 15467 375 0 0
srv_recalc_idx_stat 5 5 0 0.00 80970 412753 249931 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4954 4954 0 0.00 1 10997 940 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 2 5916 392 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 3439 30 0 0
t$perf-norm:sp_cancel_customer_reserve 599 599 0 0.00 0 864 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 6356 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 13509 53 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 3810 42 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 96129 7371 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 1489 17 0 0
t$perf-norm:sp_client_order 20171 20171 0 0.00 0 9351 38 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 13219 31 0 0
t$perf-norm:sp_pay_from_customer 6950 6950 0 0.00 0 3683 18 0 0
t$perf-norm:sp_pay_to_supplier 5871 5871 0 0.00 0 5159 18 0 0
t$perf-norm:sp_reserve_write_off 9710 9710 0 0.00 0 4607 23 0 0
t$perf-norm:sp_supplier_invoice 5373 5373 0 0.00 0 3149 31 0 0
t$perf-norm:sp_supplier_order 5298 5298 0 0.00 0 3572 33 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 255 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 123 5 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 1013 16 0 0
t$perf-norm:srv_make_money_saldo 857 857 0 0.00 0 343 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 197 58 0 0
v_add_invoice_to_stock 10028 9798 230 2.29 0 7716 26 0 230
v_all_customers 36752 36752 0 0.00 0 114 4 0 0
v_all_suppliers 29620 29620 0 0.00 0 96 4 0 0
v_all_wares 40338 40338 0 0.00 0 176 4 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 958 16 0 0
v_cancel_client_order 3498 3498 0 0.00 0 2644 11 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 795 12 0 0
v_cancel_customer_reserve 1198 1198 0 0.00 0 1541 14 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 353 10 0 0
v_cancel_supplier_invoice 786 764 22 2.79 0 356 24 0 22
v_cancel_supplier_order 778 778 0 0.00 0 3673 16 0 0
v_cancel_write_off 1982 1982 0 0.00 0 6343 31 0 0
v_max_id_clo_ord 5296 5296 0 0.00 0 1506 6 0 0
v_max_id_clo_res 943 943 0 0.00 0 1884 19 0 0
v_max_id_ord_sup 5367 5367 0 0.00 0 7167 8 0 0
v_max_non_paid_invoice 1724 1724 0 0.00 0 641 8 0 0
v_max_non_paid_realizn 3691 3691 0 0.00 0 7216 10 0 0
v_min_id_clo_ord 5296 5296 0 0.00 0 16188 16 0 0
v_min_id_clo_res 943 943 0 0.00 0 3316 15 0 0
v_min_id_ord_sup 5367 5367 0 0.00 0 6290 9 0 0
v_min_non_paid_invoice 5871 5871 0 0.00 0 9038 21 0 0
v_min_non_paid_realizn 6950 6950 0 0.00 0 8108 18 0 0
v_our_firm 10104 10104 0 0.00 0 63 5 0 0
v_reserve_write_off 19420 19420 0 0.00 0 3283 11 0 0
x_lock_depdocs_on_canc_invoice 10297 10297 0 0.00 0 13645 140 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 2842 335 0 0
x_make_qstorno_1000_1200 5298 5298 0 0.00 2 49391 1193 0 0
x_make_qstorno_1200_2000 5373 5373 0 0.00 4 83844 3032 0 0
x_make_qstorno_2100_3300 445313 445313 0 0.00 0 47397 226 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 05-02-2023 10:38:48 to 05-02-2023 10:38:53.

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
08:07:29 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:30 23159 8192000000 9717664032 10016448512 795356416 8.849 100 85 168 0 823449152 7353104 20739632
08:09:58 21603 8192000000 9761256816 10066264064 831756576 9.217 100 92 184 0 866404816 11984000 22664240
08:11:23 16223 8192000000 9798000272 10111377408 857000192 9.470 100 87 174 0 896236928 19235472 20001264
08:12:54 7659 8192000000 9873500640 10208055296 918497152 10.081 100 83 165 0 988213136 51600528 18115456
08:14:06 5949 8192000000 9912960656 10258448384 960892032 10.498 100 85 168 0 1035097440 56100304 18105104
08:15:15 2799 8192000000 9983974784 10335068160 1003318880 10.911 100 94 186 1 1098462496 76647856 18495760
08:16:20 1576 8192000000 9997991040 10360643584 1022139504 11.093 100 90 175 1 1114336688 74801168 17396016
08:17:24 1159 8192000000 10022234048 10390487040 1043926848 11.302 100 95 187 1 1143449888 80353280 19169760
08:18:26 1162 8192000000 10030290192 10401136640 1060640464 11.463 100 96 186 0 1149399088 70103184 18655440
08:19:28 1088 8192000000 10050016304 10425896960 1064086992 11.496 100 95 189 1 1165843728 81946128 19810608
08:20:31 1108 8192000000 10041709712 10422157312 1066832624 11.522 100 88 168 0 1160681600 77288976 16560000
08:21:33 3691 8192000000 10040485056 10431823872 1073625024 11.587 100 79 147 4 1163420400 73681536 16113840
08:22:38 1795 8192000000 10021806448 10411565056 1078536864 11.634 100 83 146 5 1139169536 46205312 14427360
08:23:42 1161 8192000000 10060497376 10449915904 1086452480 11.709 100 94 177 0 1180191280 75184064 18554736
08:24:44 1524 8192000000 10051953776 10437128192 1087839936 11.722 100 89 175 1 1174366496 68120320 18406240
08:25:46 1818 8192000000 10062168176 10453192704 1089516768 11.738 100 81 157 0 1186326096 80165680 16643648
08:26:49 1002 8192000000 10051437232 10441355264 1099712704 11.835 100 88 157 3 1174481856 58486432 16282720
08:27:50 1589 8192000000 10063039008 10457853952 1096070080 11.800 100 78 143 1 1180973984 71730864 13173040
08:28:53 937 8192000000 10054029520 10444845056 1092054400 11.762 100 97 184 5 1174265712 62713792 19497520
08:29:55 1251 8192000000 10068846544 10456592384 1092141808 11.763 100 97 181 0 1188754640 78052064 18560768
08:30:58 1235 8192000000 10050224320 10434248704 1075236352 11.602 98 93 185 1 1172221888 78094192 18891344
08:31:59 979 8192000000 10022017904 10406170624 1060284192 11.459 100 87 163 3 1132573536 55255520 17033824
08:33:01 1440 8192000000 10002215808 10381758464 1056715344 11.425 100 79 150 0 1119873392 47936480 15221568
08:34:04 1625 8192000000 9986814336 10372411392 1056530912 11.423 100 66 125 0 1111922864 42063520 13328432
08:35:08 3481 8192000000 9958719072 10335617024 1020040864 11.072 99 67 111 1 1075955392 43696208 12218320
08:36:12 955 8192000000 9973612192 10346704896 1031328880 11.181 99 93 172 1 1098064448 47031904 19703664
08:37:14 2632 8192000000 9985349568 10355474432 1034623488 11.213 100 89 160 9 1106505472 55214800 16667184
08:38:23 1774 8192000000 9999559840 10373529600 1049092016 11.352 99 85 158 2 1123405904 59578080 14735808
08:39:49 1402 8192000000 10023153168 10402353152 1074350608 11.594 100 77 148 0 1146053984 56197248 15506128
08:40:54 7428 8192000000 10082995504 10463117312 1081282800 11.660 100 83 160 0 1202183616 104860768 16040048
08:42:19 7992 8192000000 10112957424 10498379776 1097278672 11.812 100 81 154 0 1223930144 110052800 16598672
08:43:36 2658 8192000000 10132759776 10529243136 1112604064 11.957 100 72 133 2 1251606240 126042160 12960016
08:44:56 1203 8192000000 10154678832 10545565696 1113771632 11.968 99 97 187 1 1269345728 137093840 18480256
08:46:01 2956 8192000000 10186588480 10587123712 1136533824 12.183 100 75 143 1 1296407088 144286112 15587152
08:47:12 1492 8192000000 10212681120 10616475648 1145233104 12.265 100 93 181 3 1323889008 157891248 20764656
08:48:17 1393 8192000000 10259133344 10676105216 1155842752 12.364 100 98 182 0 1372299888 195669344 20787792
08:49:21 971 8192000000 10276784160 10669981696 1155894656 12.365 100 97 188 0 1386447520 208355984 22196880
08:50:25 1316 8192000000 10303611792 10701942784 1159604592 12.400 100 89 178 0 1417466672 238464384 19397696
08:51:31 934 8192000000 10337721456 10741813248 1171464448 12.511 100 95 187 0 1445719280 253668800 20586032
08:52:35 1040 8192000000 10359207648 10764546048 1170224592 12.499 100 95 184 0 1470077664 279074608 20778464
08:53:40 3944 8192000000 10357783056 10780741632 1166912384 12.468 100 86 166 1 1461883264 275882336 19088544
08:54:54 931 8192000000 10340585392 10779209728 1157318240 12.378 99 94 180 0 1448855888 270355040 21182608
08:55:57 1027 8192000000 10351962976 10801172480 1178010400 12.572 100 96 186 3 1460922000 259746608 23164992
08:57:01 891 8192000000 10395746832 10826706944 1170799616 12.504 99 97 188 0 1500796272 307353040 22643616
08:58:04 1925 8192000000 10385212192 10796150784 1174824832 12.542 100 90 180 1 1486180656 289810080 21545744
08:59:08 968 8192000000 10425685536 10849882112 1182192144 12.611 100 96 187 1 1530249232 325321728 22735360
09:00:11 1677 8192000000 10451397968 10869809152 1173630992 12.531 100 98 192 1 1550141936 353483360 23027584
09:01:15 1808 8192000000 10481182320 10908024832 1179372448 12.584 100 78 153 1 1587733376 389123360 19237568
09:02:21 3383 8192000000 10490852096 10950684672 1178194368 12.573 100 72 143 0 1598390256 401738272 18457616
09:03:27 7699 8192000000 10524954928 10953379840 1188287744 12.667 100 61 120 1 1647024128 442511120 16225264
09:04:38 16914 8192000000 10500546752 10972520448 1175682352 12.550 99 50 101 0 1614494176 425496160 13315664
09:05:59 14700 8192000000 10488831440 10939932672 1162919264 12.431 99 39 75 0 1592094768 418929408 10246096
09:07:23 21215 8192000000 10476357136 10921099264 1145904016 12.271 99 32 65 0 1566052304 411485888 8662400
09:08:46 17881 8192000000 10398012160 10924490752 1150659520 12.316 100 30 60 0 1510989680 352923952 7406208
09:10:04 24536 8192000000 10191222096 10711957504 1132735488 12.147 98 18 36 0 1336345216 199564544 4045184
09:11:29 20246 8192000000 10071500608 10516140032 1121579904 12.042 98 42 77 1 1164566576 35168576 7818096
09:12:54 65488 8192000000 10077625968 10510893056 1088848208 11.732 96 32 60 2 1155196256 59898704 6449344
09:15:12 86444 8192000000 10094813680 10558033920 1117717488 12.005 99 10 20 0 1188757408 69047152 1992768
09:17:38 25183 8192000000 10078205024 10510897152 1129646224 12.118 100 29 51 0 1183240000 47547904 6045872
09:19:04 61435 8192000000 10069021648 10526638080 1119028032 12.018 99 21 42 0 1159979728 36342848 4608848
09:21:08 46795 8192000000 10112560224 10544701440 1122835712 12.054 100 34 66 0 1207605168 76205856 8563600
09:23:09 122778 8192000000 10071199344 10522189824 1098813712 11.826 98 24 47 0 1161993696 58179232 5000752
09:26:21 73995 8192000000 10058871152 10547036160 1122487744 12.050 100 13 26 1 1179174720 53890976 2796000
09:28:36 14761 8192000000 10084396272 10515009536 1124738032 12.072 100 61 115 0 1188996112 52543712 11714368
09:29:53 64026 8192000000 10138173184 10615971840 1129247728 12.114 99 17 34 0 1262217792 129038288 3931776
09:33:32 67170 8192000000 10133488192 10619867136 1122351152 12.049 99 17 34 0 1232021040 105833504 3836384
09:35:42 61155 8192000000 10079428032 10538192896 1110417632 11.936 98 48 88 0 1184152912 64121264 9614016
09:37:45 79329 8192000000 10067816176 10588209152 1115419168 11.984 99 4 8 0 1167260512 51146224 695120
09:40:16 99958 8192000000 10054040256 10580783104 1107804832 11.912 97 20 40 0 1175187936 63195296 4187808
09:42:58 137370 8192000000 10072309824 10545524736 1050439136 11.365 93 15 28 1 1114222160 60940688 2842336
09:46:16 7791 8192000000 10079023168 10496901120 1133626640 12.156 100 76 141 2 1171208656 22997632 14584384
09:47:27 19980 8192000000 10120472160 10547396608 1136349152 12.181 100 51 100 0 1219920704 73925216 9646336
09:48:50 74182 8192000000 10062090944 10518695936 1103800832 11.874 97 37 75 1 1156529984 45658688 7070464
09:51:05 51514 8192000000 10121724128 10596392960 1101423632 11.851 97 45 88 0 1199017456 88697824 8896000
09:53:02 80897 8192000000 10117608496 10586050560 1127716048 12.100 99 14 28 0 1191766816 61418992 2631776
09:55:23 106031 8192000000 10039420368 10486239232 1104546624 11.881 97 38 70 0 1143178592 31007968 7624000
09:58:11 74022 8192000000 10055984976 10510008320 1091038992 11.753 96 28 51 0 1131862640 35744320 5079328
10:00:31 38078 8192000000 10075465968 10528034816 1109981184 11.932 98 18 34 0 1159255952 45952192 3322576
10:02:10 176636 8192000000 10118272432 10581504000 1086971440 11.714 95 31 62 0 1202900432 109012560 6916432
10:06:08 44278 8192000000 10089602752 10538852352 1120239104 12.029 100 16 33 0 1189881312 66196096 3446112
10:07:52 71401 8192000000 10053377216 10524299264 1043497024 11.298 93 18 37 1 1081219440 33711536 4010880
10:10:04 11461 8192000000 10105317664 10541666304 1117676240 12.005 100 33 63 1 1198757200 74536480 6544480
10:11:21 56594 8192000000 10045810208 10527764480 1089468288 11.738 97 14 28 0 1175660704 83109712 3082704
10:13:25 58375 8192000000 10111173840 10558115840 1140696384 12.222 100 28 54 0 1212493280 66274896 5522000
10:15:27 44255 8192000000 10088346640 10525794304 1119494672 12.022 98 24 45 0 1158525104 34473600 4556832
10:17:22 28753 8192000000 10100342784 10537443328 1118239392 12.010 98 32 52 1 1183221680 57060080 7922208
10:18:52 18293 8192000000 10081588976 10506022912 1116236784 11.991 99 34 68 0 1162860544 39574784 7048976
10:20:11 85803 8192000000 10082358960 10517434368 1114284736 11.973 99 36 72 0 1174109680 52619776 7205168
10:22:37 50712 8192000000 10062162368 10531827712 1129382016 12.116 100 20 40 0 1185739728 52324352 4033360
10:24:28 71638 8192000000 10064771824 10519326720 1117358656 12.002 99 14 28 1 1150683728 30101744 3223328
10:26:40 67115 8192000000 10040969184 10535059456 1091924208 11.761 97 7 14 0 1150396832 56671312 1801312
10:28:50 70714 8192000000 10074692336 10524119040 1127982224 12.102 100 13 26 0 1163043968 32371808 2689936
10:31:06 79976 8192000000 10075944800 10510110720 1073559136 11.586 94 27 54 0 1143490736 64094704 5836896
10:34:02 39459 8192000000 10122018336 10563878912 1140441792 12.220 100 34 66 0 1228360960 80655216 7263952
10:35:42 55520 8192000000 10111450336 10562441216 1110834784 11.940 97 31 62 0 1208007568 90421072 6751712

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-02-2023 10:38:55 to 05-02-2023 10:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-02-2023 10:39:12 to 05-02-2023 10:39:14.

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          209364
MON$OLDEST_ACTIVE               215793
MON$OLDEST_SNAPSHOT             215793
MON$NEXT_TRANSACTION            215793
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                       2055200
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2895 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2895 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2895 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 ---
:135: 
:136: mscs-test-161.budzdorov.ru    Sun Feb  5 10:37:36 2023
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:138: 
:139: 
:140: mscs-test-161.budzdorov.ru    Sun Feb  5 10:37:38 2023
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:142: 
--- end of diff output ---

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

05.02.23 10:39:15. 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
2023-02-05 10:39:17 No severe PSQL-related problems occured
05.02.23 10:39:19. End of report.