Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.04.2022 04: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 16492904 kB
MemAvailable 31531356 kB
Buffers 20 kB
Cached 15188680 kB
SwapCached 2536 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-04-23 04:37:14
DTS_END                         2022-04-23 04:37:14.6540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56208
TRN_ID                          214358
ATT_ID                          615
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: 8578.38 19375 1029406
customer order: creation 168.97 464 20276
customer order: refuse 14.22 423 1706
order to supplier: creation 44.43 1407 5332
order to supplier: removal 3.34 152805 401
invoice (draft): creation 44.21 3236 5305
invoice (draft): removal 46.28 1007 5553
invoice accept: apply 40.93 24375 4912
invoice accept: cancel 41.57 1292 4988
customer reserve: creation 3764.18 232 451701
customer reserve: removal 4125.32 96 495038
realization accept: apply 80.68 294 9681
realization accept: cancel 79.39 162 9527
payment from customer: creation 57.65 183 6918
payment from customer: removal 5.58 109 669
payment to supplier: creation 46.15 211 5538
payment to supplier: removal 3.33 130 399
service: total inventory turnovers 3.74 4323 449
service: total monetary turnovers 7.51 411 901
service: aggregate perf. data 0.90 17149 108
service: refresh index statistics 0.03 179182 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-04-2022 04:38:49 to 23-04-2022 04:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8200
TEST_TIME 2 6225
TEST_TIME 3 5308
TEST_TIME 4 6073
TEST_TIME 5 6195
TEST_TIME 6 6218
TEST_TIME 7 6003
TEST_TIME 8 5759
TEST_TIME 9 6023
TEST_TIME 10 5986
TEST_TIME 11 6296
TEST_TIME 12 6263
TEST_TIME 13 6448
TEST_TIME 14 6507
TEST_TIME 15 6910
TEST_TIME 16 7076
TEST_TIME 17 7113
TEST_TIME 18 7061
TEST_TIME 19 7257
TEST_TIME 20 8063
TEST_TIME 21 7904
TEST_TIME 22 8028
TEST_TIME 23 8271
TEST_TIME 24 8398
TEST_TIME 25 8509
TEST_TIME 26 8906
TEST_TIME 27 9360
TEST_TIME 28 9679
TEST_TIME 29 9876
TEST_TIME 30 10238
TEST_TIME 31 10136
TEST_TIME 32 10933
TEST_TIME 33 11003
TEST_TIME 34 10740
TEST_TIME 35 10717
TEST_TIME 36 10651
TEST_TIME 37 10463
TEST_TIME 38 10324
TEST_TIME 39 10260
TEST_TIME 40 10090
TEST_TIME 41 9984
TEST_TIME 42 9981
TEST_TIME 43 9835
TEST_TIME 44 9729
TEST_TIME 45 9778
TEST_TIME 46 9759
TEST_TIME 47 9672
TEST_TIME 48 9569
TEST_TIME 49 9558
TEST_TIME 50 9441
TEST_TIME 51 9361
TEST_TIME 52 9572
TEST_TIME 53 9491
TEST_TIME 54 9502
TEST_TIME 55 9438
TEST_TIME 56 9384
TEST_TIME 57 9335
TEST_TIME 58 9442
TEST_TIME 59 9474
TEST_TIME 60 9488
TEST_TIME 61 9412
TEST_TIME 62 9424
TEST_TIME 63 9415
TEST_TIME 64 9442
TEST_TIME 65 9298
TEST_TIME 66 9169
TEST_TIME 67 9101
TEST_TIME 68 9112
TEST_TIME 69 9112
TEST_TIME 70 9052
TEST_TIME 71 9001
TEST_TIME 72 8960
TEST_TIME 73 8996
TEST_TIME 74 8955
TEST_TIME 75 8980
TEST_TIME 76 8963
TEST_TIME 77 8954
TEST_TIME 78 8946
TEST_TIME 79 8984
TEST_TIME 80 9044
TEST_TIME 81 9022
TEST_TIME 82 9108
TEST_TIME 83 9083
TEST_TIME 84 9060
TEST_TIME 85 8994
TEST_TIME 86 8993
TEST_TIME 87 8977
TEST_TIME 88 8940
TEST_TIME 89 8877
TEST_TIME 90 8827
TEST_TIME 91 8835
TEST_TIME 92 8845
TEST_TIME 93 8827
TEST_TIME 94 8813
TEST_TIME 95 8817
TEST_TIME 96 8859
TEST_TIME 97 8832
TEST_TIME 98 8825
TEST_TIME 99 8827
TEST_TIME 100 8937
TEST_TIME 101 8922
TEST_TIME 102 8920
TEST_TIME 103 8916
TEST_TIME 104 8943
TEST_TIME 105 8959
TEST_TIME 106 8954
TEST_TIME 107 8895
TEST_TIME 108 8889
TEST_TIME 109 8882
TEST_TIME 110 8833
TEST_TIME 111 8799
TEST_TIME 112 8761
TEST_TIME 113 8719
TEST_TIME 114 8711
TEST_TIME 115 8702
TEST_TIME 116 8715
TEST_TIME 117 8694
TEST_TIME 118 8675
TEST_TIME 119 8656
TEST_TIME 120 8607

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-04-2022 04:38:53 to 23-04-2022 04:38:56.

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 1446154 1446154 0 0.00 0 30685 13 0 0
doc_list_biud 1446139 1446139 0 0.00 0 50600 39 0 0
sp_add_invoice_to_stock 5031 4912 119 2.36 29 190615 24376 0 119
sp_cancel_adding_invoice 4988 4988 0 0.00 7 113255 1293 0 0
sp_cancel_client_order 1706 1706 0 0.00 6 11904 424 0 0
sp_cancel_customer_reserve 495038 495038 0 0.00 1 47601 96 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 4 3041 110 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 4657 130 0 0
sp_cancel_supplier_invoice 5566 5553 13 0.23 3 25582 1007 0 13
sp_cancel_supplier_order 401 401 0 0.00 9 1482267 152805 0 0
sp_cancel_write_off 9527 9527 0 0.00 2 21294 162 0 0
sp_client_order 20276 20276 0 0.00 6 29539 464 0 0
sp_customer_reserve 451701 451701 0 0.00 1 59812 233 0 0
sp_fill_shopping_cart 30900 30900 0 0.00 1 15215 85 0 0
sp_fill_shopping_cart_clo_res 451671 451671 0 0.00 0 12985 20 0 0
sp_get_clo_for_invoice 4874 4874 0 0.00 0 19499 335 0 0
sp_kill_cost_storno 15583 15583 0 0.00 0 17192 73 0 0
sp_kill_qstorno_ret_qs2qd 1005557 1005557 0 0.00 0 47500 42 0 0
sp_kill_qty_storno 531765 531765 0 0.00 0 50598 98 0 0
sp_lock_selected_doc 20167 20167 0 0.00 0 7990 6 0 0
sp_make_cost_storno 27011 27011 0 0.00 0 21947 82 0 0
sp_multiply_rows_for_pdistr 27009 27009 0 0.00 0 20868 41 0 0
sp_multiply_rows_for_qdistr 20276 20276 0 0.00 0 22943 178 0 0
sp_pay_from_customer 6918 6918 0 0.00 5 21389 183 0 0
sp_pay_to_supplier 5538 5538 0 0.00 6 21357 212 0 0
sp_payment_common 12454 12454 0 0.00 2 20714 49 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 0 11898 345 0 0
sp_qd_handle_on_invoice_upd_sts 9860 9860 0 0.00 0 50594 554 0 0
sp_qd_handle_on_reserve_upd_sts 9680 9680 0 0.00 0 21877 77 0 0
sp_reserve_write_off 9681 9681 0 0.00 9 22541 294 0 0
sp_supplier_invoice 5305 5305 0 0.00 9 99966 3237 0 0
sp_supplier_order 5332 5332 0 0.00 8 86690 1408 0 0
srv_aggregate_perf_data 108 108 0 0.00 2161 170385 17150 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 746 158588 41849 0 0
srv_find_qd_qs_mism 982210 982210 0 0.00 0 30682 9 0 0
srv_make_invnt_saldo 449 449 0 0.00 54 73596 4323 0 0
srv_make_money_saldo 901 901 0 0.00 1 19137 411 0 0
srv_recalc_idx_stat 4 4 0 0.00 68040 276379 179183 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4917 4917 0 0.00 1 14787 881 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 2 7973 417 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 2687 28 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 2350 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 5869 32 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 501 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 4228 44 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 106877 6744 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 9396 26 0 0
t$perf-norm:sp_client_order 20276 20276 0 0.00 0 5896 34 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 535 13 0 0
t$perf-norm:sp_pay_from_customer 6918 6918 0 0.00 0 3940 17 0 0
t$perf-norm:sp_pay_to_supplier 5538 5538 0 0.00 0 4983 17 0 0
t$perf-norm:sp_reserve_write_off 9681 9681 0 0.00 0 4616 21 0 0
t$perf-norm:sp_supplier_invoice 5306 5306 0 0.00 0 13096 31 0 0
t$perf-norm:sp_supplier_order 5332 5332 0 0.00 0 11943 32 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 210 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 150 7 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 807 10 0 0
t$perf-norm:srv_make_money_saldo 901 901 0 0.00 0 267 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 185 71 0 0
v_add_invoice_to_stock 9968 9730 238 2.38 0 8397 25 0 238
v_all_customers 37118 37118 0 0.00 0 106 3 0 0
v_all_suppliers 29348 29348 0 0.00 0 218 4 0 0
v_all_wares 40550 40550 0 0.00 0 96 4 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 1899 18 0 0
v_cancel_client_order 3412 3412 0 0.00 0 8410 14 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 1769 12 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 1 4997 14 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 4544 19 0 0
v_cancel_supplier_invoice 856 830 26 3.03 0 745 21 0 26
v_cancel_supplier_order 802 802 0 0.00 0 1046 11 0 0
v_cancel_write_off 1982 1982 0 0.00 0 7656 27 0 0
v_max_id_clo_ord 5330 5330 0 0.00 0 158 4 0 0
v_max_id_clo_res 960 960 0 0.00 0 3053 12 0 0
v_max_id_ord_sup 5294 5294 0 0.00 0 6616 8 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 230 7 0 0
v_max_non_paid_realizn 3573 3573 0 0.00 0 3982 9 0 0
v_min_id_clo_ord 5330 5330 0 0.00 0 1708 7 0 0
v_min_id_clo_res 960 960 0 0.00 0 1260 10 0 0
v_min_id_ord_sup 5294 5294 0 0.00 0 5597 11 0 0
v_min_non_paid_invoice 5537 5537 0 0.00 0 18271 23 0 0
v_min_non_paid_realizn 6917 6917 0 0.00 0 19998 21 0 0
v_our_firm 10118 10118 0 0.00 0 76 4 0 0
v_reserve_write_off 19357 19357 0 0.00 0 8292 12 0 0
x_lock_depdocs_on_canc_invoice 10126 10126 0 0.00 0 32521 142 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 25572 471 0 0
x_make_qstorno_1000_1200 5332 5332 0 0.00 2 86598 1246 0 0
x_make_qstorno_1200_2000 5305 5305 0 0.00 4 85539 2988 0 0
x_make_qstorno_2100_3300 451700 451700 0 0.00 0 59811 202 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-04-2022 04:38:57 to 23-04-2022 04:39:01.

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
02:07:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:18 33728 8192000000 9728647984 10030026752 802752752 8.924 100 91 181 0 833306848 7698256 22855840
02:10:02 16836 8192000000 9760678752 10068344832 833299328 9.232 100 92 184 0 868968192 12927248 22741616
02:11:24 10384 8192000000 9813292176 10132705280 876838576 9.668 100 87 174 1 921529296 23993856 20696864
02:12:47 8165 8192000000 9863216560 10201522176 926339600 10.159 100 79 158 0 980402080 36890352 17172128
02:14:03 6124 8192000000 9931126752 10275020800 972514096 10.611 100 90 175 1 1040954800 49946016 18494688
02:15:12 3481 8192000000 9986786704 10342895616 1001646896 10.894 100 88 176 0 1103752000 84463552 17641552
02:16:20 1042 8192000000 10013520208 10374991872 1022730368 11.098 100 98 193 1 1128575408 87045360 18799680
02:17:22 1314 8192000000 10018080960 10387714048 1043066384 11.294 100 91 180 1 1136989632 76156480 17766768
02:18:25 1135 8192000000 10032576416 10406055936 1053934720 11.398 100 96 193 0 1149579504 76799328 18845456
02:19:29 1174 8192000000 10046182912 10424025088 1062393680 11.479 100 94 186 2 1161169440 80460128 18315632
02:20:31 1120 8192000000 10041831728 10424143872 1070526928 11.557 100 93 182 1 1164701616 75309632 18865056
02:21:33 1417 8192000000 10038380576 10426597376 1073677808 11.587 100 84 160 2 1157200720 67171168 16351744
02:22:36 1049 8192000000 10037801808 10425659392 1081458688 11.661 100 93 175 2 1164988720 65380800 18149232
02:23:41 987 8192000000 10061197552 10444500992 1087393680 11.718 100 98 190 1 1182461392 76499152 18568560
02:24:43 1222 8192000000 10059124800 10446802944 1088525808 11.729 100 98 182 1 1179441984 72761376 18154800
02:25:45 1140 8192000000 10070219136 10457661440 1094521872 11.786 100 92 180 2 1189396192 75514464 19359856
02:26:48 1277 8192000000 10061989120 10452979712 1089661152 11.739 100 76 144 0 1185701808 81621104 14419552
02:27:50 4136 8192000000 10030351088 10429415424 1089078224 11.734 100 73 144 1 1149524896 45471152 14975520
02:28:59 1054 8192000000 10059524288 10447142912 1099267840 11.831 100 90 169 0 1181730496 63255648 19207008
02:30:01 1206 8192000000 10043799200 10433069056 1083555120 11.681 100 96 177 0 1168255408 66529936 18170352
02:31:03 1080 8192000000 10012560000 10397085696 1063674752 11.492 100 93 174 1 1137327088 56161152 17491184
02:32:05 1061 8192000000 10011574544 10390720512 1065181472 11.506 100 93 184 3 1134523824 50964672 18377680
02:33:07 3682 8192000000 10005442752 10389618688 1058347408 11.441 100 75 144 0 1126784368 53510880 14926080
02:34:12 4413 8192000000 9986855200 10361491456 1029042880 11.159 99 68 129 4 1101175856 59180816 12952160
02:35:18 1212 8192000000 9977299456 10355802112 1041587952 11.280 100 82 159 1 1103925360 45595968 16741440
02:36:20 873 8192000000 10005838416 10381475840 1042232912 11.286 100 90 172 1 1129324112 67816400 19274800
02:37:23 919 8192000000 9993060512 10366951424 1056637104 11.424 100 93 171 1 1117014128 43737328 16639696
02:38:33 5430 8192000000 10038779072 10420432896 1076410432 11.613 100 73 140 1 1160465184 68390480 15664272
02:39:43 19289 8192000000 10018747984 10406330368 1080392816 11.651 100 57 113 0 1142878992 50939136 11547040
02:41:09 783 8192000000 10074120656 10454298624 1084757296 11.693 100 93 169 0 1192911392 91330864 16823232
02:42:13 2362 8192000000 10118577216 10508611584 1108107888 11.915 100 84 165 0 1247265872 121421152 17736832
02:43:20 3877 8192000000 10145291008 10538549248 1125916576 12.083 100 90 166 3 1263143872 119503872 17723424
02:44:32 4747 8192000000 10186823552 10588348416 1131901184 12.139 100 68 133 0 1299655488 153183296 14571008
02:45:44 1373 8192000000 10216520976 10608271360 1144912128 12.262 100 85 160 0 1329001040 166536848 17552064
02:46:52 12159 8192000000 10251728544 10656481280 1144784992 12.261 100 68 136 1 1354080032 194105440 15189600
02:48:11 2004 8192000000 10333463488 10738200576 1169389728 12.491 100 91 175 1 1442970704 254684080 18896896
02:49:16 2230 8192000000 10333094736 10735591424 1170021696 12.497 100 86 166 1 1444015136 254669792 19323648
02:50:21 1069 8192000000 10373365104 10773475328 1184410768 12.631 100 93 184 0 1484673168 279320608 20941792
02:51:25 1593 8192000000 10424944400 10827558912 1188278480 12.667 100 97 194 0 1528589024 318636832 21673712
02:52:28 931 8192000000 10435153552 10850324480 1202282160 12.798 100 97 187 4 1551777024 326047872 23446992
02:53:31 1048 8192000000 10444039872 10848317440 1185289008 12.639 100 95 187 0 1555130768 346325072 23516688
02:54:37 1300 8192000000 10474764272 10882060288 1186928400 12.655 100 97 189 3 1580453104 370474288 23050416
02:55:41 733 8192000000 10479673776 10918899712 1196637920 12.745 100 98 191 1 1586103888 365960848 23505120
02:56:44 1680 8192000000 10507464304 10980012032 1190913728 12.692 100 99 193 0 1610941376 396276928 23750720
02:57:47 4416 8192000000 10502440624 10957246464 1198026592 12.758 100 64 127 0 1610212352 396479776 15705984
02:59:05 1049 8192000000 10511523760 10950725632 1198412352 12.762 100 94 186 0 1613340720 392997760 21930608
03:00:11 1389 8192000000 10514764912 10963562496 1197051392 12.749 100 70 128 2 1621258464 408762400 15444672
03:01:14 6299 8192000000 10500357136 10946584576 1183651664 12.624 100 55 104 0 1611830272 413286960 14891648
03:02:24 878 8192000000 10481771616 10920620032 1165289040 12.453 100 94 178 1 1582273728 396066992 20917696
03:03:27 15102 8192000000 10424044144 10909478912 1140028960 12.216 98 44 87 1 1504462480 352897152 11536368
03:04:46 20271 8192000000 10358351840 10824114176 1143985376 12.253 100 36 72 0 1500931040 347644368 9301296
03:06:26 20094 8192000000 10343061536 10821722112 1154166208 12.349 100 26 52 0 1433700352 273011824 6522320
03:07:48 33856 8192000000 10307574400 10785738752 1156351424 12.369 100 16 32 2 1407432400 247155648 3925328
03:09:29 66396 8192000000 10126926864 10593849344 1115141776 11.981 96 14 26 0 1182934736 65085296 2707664
03:11:38 18859 8192000000 10124615936 10559975424 1152952480 12.337 100 49 91 1 1213872544 49989232 10930832
03:12:58 72529 8192000000 10110955264 10583724032 1144881040 12.261 99 25 49 1 1237561280 87513424 5166816
03:15:22 52082 8192000000 10162104864 10640232448 1143587120 12.249 99 8 16 0 1257206000 112212896 1405984
03:17:29 158588 8192000000 10128605968 10574213120 1125071392 12.075 97 15 28 2 1217666032 89774944 2819696
03:21:14 151141 8192000000 10101444368 10644320256 1099753632 11.835 96 24 46 0 1199083920 93746608 5583680
03:24:49 21425 8192000000 10092451760 10526699520 1135891696 12.177 100 65 118 1 1186179312 38027632 12259984
03:26:11 74871 8192000000 10124393168 10601529344 1126979968 12.093 98 21 42 0 1226679008 94902800 4796240
03:28:33 44581 8192000000 10135185216 10625593344 1125829808 12.082 98 14 28 0 1227138384 97919856 3388720
03:30:20 54439 8192000000 10091132144 10530877440 1135105984 12.169 99 38 75 1 1171740576 28395840 8238752
03:32:22 65848 8192000000 10079593968 10519277568 1118488592 12.013 98 42 85 1 1162203168 34160992 9553584
03:34:30 63600 8192000000 10139094544 10623721472 1131320768 12.134 99 18 36 0 1217975264 82368832 4285664
03:36:41 154267 8192000000 10097892256 10555400192 1089544560 11.738 95 14 28 0 1155110416 63033040 2532816
03:40:15 95214 8192000000 10107866880 10628186112 1130426160 12.125 99 13 26 1 1175506112 42563008 2516944
03:42:51 118186 8192000000 10108065760 10574843904 1134201968 12.161 99 28 54 1 1213425568 73030128 6193472
03:46:04 90793 8192000000 10071022768 10552446976 1127766832 12.100 98 14 26 1 1152822272 21679856 3375584
03:48:56 50309 8192000000 10090712032 10557562880 1124760752 12.072 99 17 34 0 1193791568 64621392 4409424
03:50:47 49229 8192000000 10116088352 10556387328 1132593024 12.146 99 28 55 1 1195876672 57219200 6064448
03:52:50 43378 8192000000 10100293968 10566348800 1134803664 12.167 99 23 46 1 1181149728 41392272 4953792
03:54:34 41157 8192000000 10114513952 10567442432 1119788608 12.025 98 20 40 1 1205494672 81153696 4552368
03:56:23 48159 8192000000 10117406320 10564890624 1109196144 11.925 97 32 65 1 1221610080 105368624 7045312
03:58:16 50873 8192000000 10122046688 10558341120 1102594368 11.862 98 36 72 0 1180030880 70318496 7118016
04:00:11 26824 8192000000 10082435616 10503200768 1085473168 11.700 96 49 95 0 1147882992 52095472 10314352
04:01:38 105358 8192000000 10076871024 10531856384 1098844496 11.827 98 42 84 0 1174968448 67874992 8248960
04:04:32 55180 8192000000 10027816864 10485686272 1095389920 11.794 98 22 40 3 1145196848 45732224 4074704
04:06:33 63996 8192000000 10062247616 10550923264 1122666272 12.052 100 11 22 0 1171686544 46611776 2408496
04:08:38 87795 8192000000 10057864176 10482741248 1109584048 11.928 98 21 42 1 1157157872 43049344 4524480
04:11:06 50014 8192000000 10081431680 10523504640 1126617328 12.089 99 13 26 2 1159409056 30232576 2559152
04:12:57 49240 8192000000 10086912720 10534776832 1108088304 11.914 97 26 50 2 1182671920 69539024 5044592
04:14:47 27788 8192000000 10096727056 10527977472 1108449488 11.918 97 41 79 1 1172591248 56001232 8140528
04:16:18 55025 8192000000 10105560656 10544771072 1108275904 11.916 98 28 56 3 1206599904 92605152 5718848
04:18:22 18031 8192000000 10088979936 10516340736 1133846352 12.158 100 45 86 1 1190295072 47579792 8868928
04:19:42 15170 8192000000 10064858240 10484678656 1088071792 11.724 97 43 86 0 1137919504 41504544 8343168
04:20:59 89008 8192000000 10079665760 10529792000 1096068160 11.800 98 26 52 0 1185061168 83876704 5116304
04:23:35 126078 8192000000 10065724176 10551844864 1113791024 11.968 99 30 60 1 1177261456 56789664 6680768
04:26:51 57762 8192000000 10050115200 10528362496 1105172128 11.887 98 7 14 0 1142309184 35990720 1146336
04:28:49 37989 8192000000 10054081248 10492649472 1094088496 11.782 97 20 40 1 1115557952 16860816 4608640
04:31:04 97163 8192000000 10079233904 10576416768 1130885904 12.130 100 14 28 1 1198604640 64390160 3328576
04:33:51 35037 8192000000 10087327968 10536054784 1131434880 12.135 99 23 46 1 1190828128 54162288 5230960
04:35:27 53415 8192000000 10133544848 10579369984 1125885216 12.083 98 23 46 0 1203712800 73013584 4814000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-04-2022 04:39:03 to 23-04-2022 04:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-04-2022 04:39:19 to 23-04-2022 04:39:21.

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          208172
MON$OLDEST_ACTIVE               216248
MON$OLDEST_SNAPSHOT             216248
MON$NEXT_TRANSACTION            216248
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                       2046880
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:525: 
:526: mscs-test-161.budzdorov.ru    Sat Apr 23 04:37:26 2022
:527:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:528: 
--- end of diff output ---

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

23.04.22 04:39:22. 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-04-23 04:39:25 No severe PSQL-related problems occured
23.04.22 04:39:26. End of report.