Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2021 07:51
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15980200 kB
MemAvailable 30949492 kB
Buffers 0 kB
Cached 15674332 kB
SwapCached 2604 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.1
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 2021-08-20 07:50:35
DTS_END                         2021-08-20 07:50:35.1010
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/53652
TRN_ID                          238357
ATT_ID                          682
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: 9113.69 21516 1093643
customer order: creation 172.94 384 20753
customer order: refuse 14.57 288 1748
order to supplier: creation 46.96 1368 5635
order to supplier: removal 3.56 112324 427
invoice (draft): creation 47.37 3803 5684
invoice (draft): removal 53.47 1103 6416
invoice accept: apply 41.89 16759 5027
invoice accept: cancel 47.44 834 5693
customer reserve: creation 3800.28 167 456033
customer reserve: removal 4588.57 66 550628
realization accept: apply 84.23 159 10107
realization accept: cancel 85.16 116 10219
payment from customer: creation 59.49 105 7139
payment from customer: removal 5.30 101 636
payment to supplier: creation 48.86 123 5863
payment to supplier: removal 3.38 69 405
service: total inventory turnovers 3.60 3711 432
service: total monetary turnovers 5.66 639 679
service: aggregate perf. data 0.95 16477 114
service: refresh index statistics 0.04 271729 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-08-2021 07:51:35 to 20-08-2021 07:51:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3549
TEST_TIME 2 3693
TEST_TIME 3 5016
TEST_TIME 4 4751
TEST_TIME 5 4480
TEST_TIME 6 4421
TEST_TIME 7 4684
TEST_TIME 8 4780
TEST_TIME 9 5072
TEST_TIME 10 5872
TEST_TIME 11 6226
TEST_TIME 12 6265
TEST_TIME 13 6511
TEST_TIME 14 6446
TEST_TIME 15 6350
TEST_TIME 16 6923
TEST_TIME 17 7087
TEST_TIME 18 7442
TEST_TIME 19 7679
TEST_TIME 20 7663
TEST_TIME 21 7981
TEST_TIME 22 8064
TEST_TIME 23 8183
TEST_TIME 24 8666
TEST_TIME 25 8872
TEST_TIME 26 9587
TEST_TIME 27 10592
TEST_TIME 28 10836
TEST_TIME 29 10669
TEST_TIME 30 10741
TEST_TIME 31 10547
TEST_TIME 32 10623
TEST_TIME 33 10683
TEST_TIME 34 10579
TEST_TIME 35 10499
TEST_TIME 36 10677
TEST_TIME 37 10678
TEST_TIME 38 10698
TEST_TIME 39 10760
TEST_TIME 40 10504
TEST_TIME 41 10259
TEST_TIME 42 10173
TEST_TIME 43 10084
TEST_TIME 44 10048
TEST_TIME 45 9953
TEST_TIME 46 9903
TEST_TIME 47 9874
TEST_TIME 48 9766
TEST_TIME 49 9692
TEST_TIME 50 9636
TEST_TIME 51 9500
TEST_TIME 52 9533
TEST_TIME 53 9479
TEST_TIME 54 9346
TEST_TIME 55 9249
TEST_TIME 56 9194
TEST_TIME 57 9278
TEST_TIME 58 9350
TEST_TIME 59 9289
TEST_TIME 60 9325
TEST_TIME 61 9361
TEST_TIME 62 9415
TEST_TIME 63 9386
TEST_TIME 64 9465
TEST_TIME 65 9438
TEST_TIME 66 9506
TEST_TIME 67 9569
TEST_TIME 68 9510
TEST_TIME 69 9622
TEST_TIME 70 9608
TEST_TIME 71 9595
TEST_TIME 72 9474
TEST_TIME 73 9470
TEST_TIME 74 9411
TEST_TIME 75 9358
TEST_TIME 76 9357
TEST_TIME 77 9299
TEST_TIME 78 9251
TEST_TIME 79 9238
TEST_TIME 80 9190
TEST_TIME 81 9212
TEST_TIME 82 9184
TEST_TIME 83 9184
TEST_TIME 84 9242
TEST_TIME 85 9262
TEST_TIME 86 9265
TEST_TIME 87 9250
TEST_TIME 88 9308
TEST_TIME 89 9411
TEST_TIME 90 9502
TEST_TIME 91 9461
TEST_TIME 92 9373
TEST_TIME 93 9338
TEST_TIME 94 9333
TEST_TIME 95 9279
TEST_TIME 96 9241
TEST_TIME 97 9211
TEST_TIME 98 9185
TEST_TIME 99 9200
TEST_TIME 100 9192
TEST_TIME 101 9199
TEST_TIME 102 9282
TEST_TIME 103 9251
TEST_TIME 104 9241
TEST_TIME 105 9260
TEST_TIME 106 9241
TEST_TIME 107 9319
TEST_TIME 108 9338
TEST_TIME 109 9336
TEST_TIME 110 9278
TEST_TIME 111 9206
TEST_TIME 112 9208
TEST_TIME 113 9209
TEST_TIME 114 9188
TEST_TIME 115 9150
TEST_TIME 116 9162
TEST_TIME 117 9165
TEST_TIME 118 9176
TEST_TIME 119 9170
TEST_TIME 120 9120

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-08-2021 07:51:39 to 20-08-2021 07:51:42.

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 1517699 1517699 0 0.00 0 19728 6 0 0
doc_list_biud 1517683 1517683 0 0.00 0 35304 31 0 0
sp_add_invoice_to_stock 5134 5027 107 2.08 39 190866 16760 0 107
sp_cancel_adding_invoice 5693 5693 0 0.00 4 54341 834 0 0
sp_cancel_client_order 1748 1748 0 0.00 4 7345 289 0 0
sp_cancel_customer_reserve 550628 550628 0 0.00 1 30075 67 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 3 8084 102 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 3809 69 0 0
sp_cancel_supplier_invoice 6419 6416 3 0.04 2 20664 1103 0 3
sp_cancel_supplier_order 427 427 0 0.00 6 1180970 112324 0 0
sp_cancel_write_off 10219 10219 0 0.00 1 8030 117 0 0
sp_client_order 20753 20753 0 0.00 4 35832 384 0 0
sp_customer_reserve 456033 456033 0 0.00 0 58110 167 0 0
sp_fill_shopping_cart 32060 32060 0 0.00 1 18396 45 0 0
sp_fill_shopping_cart_clo_res 456023 456023 0 0.00 0 16118 17 0 0
sp_get_clo_for_invoice 5016 5016 0 0.00 0 20450 359 0 0
sp_kill_cost_storno 16953 16953 0 0.00 0 7652 55 0 0
sp_kill_qstorno_ret_qs2qd 1118318 1118318 0 0.00 0 30052 37 0 0
sp_kill_qty_storno 590243 590243 0 0.00 0 35302 80 0 0
sp_lock_selected_doc 20850 20850 0 0.00 0 7453 5 0 0
sp_make_cost_storno 28125 28125 0 0.00 0 16839 58 0 0
sp_multiply_rows_for_pdistr 28110 28110 0 0.00 0 8568 29 0 0
sp_multiply_rows_for_qdistr 20753 20753 0 0.00 0 16877 213 0 0
sp_pay_from_customer 7139 7139 0 0.00 4 13981 106 0 0
sp_pay_to_supplier 5863 5863 0 0.00 4 12421 123 0 0
sp_payment_common 12998 12998 0 0.00 1 10627 21 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 7338 267 0 0
sp_qd_handle_on_invoice_upd_sts 10701 10701 0 0.00 0 35302 377 0 0
sp_qd_handle_on_reserve_upd_sts 10104 10104 0 0.00 0 8797 46 0 0
sp_reserve_write_off 10107 10107 0 0.00 7 15775 160 0 0
sp_supplier_invoice 5684 5684 0 0.00 6 121144 3803 0 0
sp_supplier_order 5635 5635 0 0.00 6 37147 1368 0 0
srv_aggregate_perf_data 114 114 0 0.00 2263 137607 16477 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 893 180880 48639 0 0
srv_find_qd_qs_mism 1046952 1046952 0 0.00 0 19728 5 0 0
srv_make_invnt_saldo 432 432 0 0.00 53 114881 3712 0 0
srv_make_money_saldo 679 679 0 0.00 1 17356 640 0 0
srv_recalc_idx_stat 5 5 0 0.00 142726 431021 271730 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5027 5027 0 0.00 0 16365 166 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 3105 82 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 6430 13 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 5326 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 873 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 542 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 543 10 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 16817 1418 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 3723 15 0 0
t$perf-norm:sp_client_order 20753 20753 0 0.00 0 14762 19 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 3712 16 0 0
t$perf-norm:sp_pay_from_customer 7139 7139 0 0.00 0 6417 10 0 0
t$perf-norm:sp_pay_to_supplier 5863 5863 0 0.00 0 3450 8 0 0
t$perf-norm:sp_reserve_write_off 10107 10107 0 0.00 0 18297 15 0 0
t$perf-norm:sp_supplier_invoice 5684 5684 0 0.00 0 6184 16 0 0
t$perf-norm:sp_supplier_order 5635 5635 0 0.00 0 11103 17 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 1581 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 356 8 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 4480 32 0 0
t$perf-norm:srv_make_money_saldo 679 679 0 0.00 0 1212 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 32 8 0 0
v_add_invoice_to_stock 10228 10014 214 2.09 0 5314 16 0 214
v_all_customers 38056 38056 0 0.00 0 23 1 0 0
v_all_suppliers 31008 31008 0 0.00 0 19 1 0 0
v_all_wares 41506 41506 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1045 8 0 0
v_cancel_client_order 3496 3496 0 0.00 0 1383 4 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 3599 8 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 3787 7 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 310 3 0 0
v_cancel_supplier_invoice 834 828 6 0.71 0 957 14 0 6
v_cancel_supplier_order 854 854 0 0.00 0 1434 5 0 0
v_cancel_write_off 1980 1980 0 0.00 0 1020 12 0 0
v_max_id_clo_ord 5629 5629 0 0.00 0 1366 2 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 2805 19 0 0
v_max_id_ord_sup 5675 5675 0 0.00 0 6175 6 0 0
v_max_non_paid_invoice 1663 1663 0 0.00 0 806 3 0 0
v_max_non_paid_realizn 3724 3724 0 0.00 0 6292 6 0 0
v_min_id_clo_ord 5629 5629 0 0.00 0 3503 7 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 2933 9 0 0
v_min_id_ord_sup 5675 5675 0 0.00 0 7115 11 0 0
v_min_non_paid_invoice 5863 5863 0 0.00 0 5007 14 0 0
v_min_non_paid_realizn 7135 7135 0 0.00 0 5708 14 0 0
v_our_firm 10272 10272 0 0.00 0 17 1 0 0
v_reserve_write_off 20206 20206 0 0.00 0 5323 5 0 0
x_lock_depdocs_on_canc_invoice 11695 11695 0 0.00 0 18382 104 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 4566 286 0 0
x_make_qstorno_1000_1200 5635 5635 0 0.00 2 36960 1292 0 0
x_make_qstorno_1200_2000 5684 5684 0 0.00 3 115410 3630 0 0
x_make_qstorno_2100_3300 456033 456033 0 0.00 0 55717 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-08-2021 07:51:43 to 20-08-2021 07:51:47.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:20:38 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:21:39 18164 8192000000 9709066240 10003378176 787742624 8.772 100 87 174 0 816408912 7691984 20974304
05:23:02 20421 8192000000 9745498544 10048716800 820121728 9.100 100 86 172 0 852515696 12025104 20368864
05:24:37 16395 8192000000 9794931216 10113363968 854901904 9.449 100 77 154 1 896060656 23312752 17846000
05:25:59 8864 8192000000 9858210464 10189340672 910369536 10.001 100 78 156 3 973124832 46060784 16694512
05:27:10 13675 8192000000 9909725264 10252361728 946993536 10.362 100 89 178 0 1026639760 61499456 18146768
05:28:26 5187 8192000000 9949135824 10302267392 983814784 10.721 100 75 150 0 1066282256 67799136 14668336
05:29:35 2823 8192000000 9973060480 10331394048 1014198416 11.016 100 90 177 1 1083582624 51337008 18047200
05:30:40 4574 8192000000 10003780752 10372730880 1037990640 11.245 100 76 151 1 1117737424 64616208 15130576
05:31:47 1870 8192000000 10026222016 10400538624 1056407744 11.422 100 79 157 0 1134396320 61922080 16066496
05:32:49 3863 8192000000 10018679408 10406608896 1064381120 11.498 100 62 123 0 1134615600 57099024 13135456
05:33:55 8179 8192000000 9980829536 10374959104 1070389248 11.556 100 42 83 1 1098691120 19319088 8982784
05:35:05 7381 8192000000 9981686816 10377211904 1076732400 11.616 100 21 42 1 1109171632 27767632 4671600
05:36:13 56778 8192000000 9990607696 10394214400 1084691952 11.692 100 40 80 1 1107344528 13494464 9158112
05:38:10 8714 8192000000 10013296976 10424102912 1086399504 11.708 100 38 74 2 1120317936 25562704 8355728
05:39:23 10811 8192000000 9997410272 10407235584 1082430016 11.671 99 29 58 1 1118051152 29539008 6082128
05:40:35 23474 8192000000 9995269664 10386747392 1055936944 11.418 97 22 44 0 1088870144 28570320 4362880
05:42:00 7081 8192000000 9974430576 10383462400 1036185328 11.228 97 21 42 0 1057802080 17097520 4519232
05:43:07 5132 8192000000 9950098976 10330046464 1029100048 11.160 99 51 103 2 1064346144 23196944 12049152
05:44:16 5097 8192000000 9932973248 10316361728 1000984656 10.888 100 58 116 2 1036952848 23194304 12773888
05:45:24 11072 8192000000 9937983968 10316402688 1017137168 11.044 99 45 91 1 1049420384 21779728 10503488
05:46:38 29072 8192000000 9928822416 10323054592 991752096 10.798 96 9 18 0 1015451120 22208336 1490688
05:48:12 7333 8192000000 9972507232 10361114624 1047279168 11.335 100 52 101 1 1075390176 16673200 11437808
05:49:20 4354 8192000000 9973909264 10365861888 1058144352 11.439 100 46 93 1 1088531632 20826464 9560816
05:50:27 45730 8192000000 9994379904 10395078656 1078711232 11.635 100 37 72 1 1110269008 24324336 7233440
05:52:42 12785 8192000000 10057997904 10455482368 1104617344 11.881 100 51 102 1 1152586960 37709056 10260560
05:53:58 29575 8192000000 10087056992 10490896384 1116360832 11.993 100 43 84 3 1199600528 74653904 8585792
05:55:39 18228 8192000000 10138652368 10543861760 1138402112 12.200 100 52 104 2 1242485968 92660656 11423200
05:57:00 8579 8192000000 10193059456 10603454464 1150011248 12.310 100 52 104 1 1300600928 138821824 11767856
05:58:15 22061 8192000000 10231143344 10651209728 1156864016 12.374 100 43 84 3 1334013200 166863568 10285616
05:59:54 2977 8192000000 10261730848 10673807360 1165462352 12.454 100 68 135 0 1367361264 186761808 15137104
06:01:03 4596 8192000000 10271241184 10685050880 1173348752 12.528 100 77 151 2 1374412864 184140976 16923136
06:02:13 7206 8192000000 10274254896 10695151616 1178425360 12.576 100 57 112 1 1376936448 185215360 13295728
06:03:27 1913 8192000000 10304248048 10726010880 1179719744 12.588 100 49 98 0 1409071040 216852352 12498944
06:04:34 4352 8192000000 10311268352 10725511168 1164425600 12.445 99 74 146 2 1403842320 222911584 16505136
06:05:44 7679 8192000000 10363537184 10776809472 1153894112 12.346 98 65 128 1 1449281488 280354496 15032880
06:06:58 3649 8192000000 10353123648 10757824512 1157179984 12.377 100 92 180 0 1453809024 276159776 20469264
06:08:08 2132 8192000000 10414229152 10813177856 1170187536 12.499 100 91 182 2 1512876832 321898768 20790528
06:09:16 3935 8192000000 10375152320 10801848320 1147409488 12.285 100 61 120 0 1475309216 312796288 15103440
06:10:23 892 8192000000 10419903296 10816090112 1141926512 12.234 100 86 172 0 1510864080 348753184 20184384
06:11:27 10582 8192000000 10483846512 10907590656 1156488544 12.370 100 48 96 0 1581597312 412401472 12707296
06:12:47 9484 8192000000 10472698176 10896044032 1139517184 12.211 100 51 99 3 1566524272 414255152 12751936
06:14:04 17371 8192000000 10456526592 10885726208 1124949808 12.074 99 33 66 0 1539519760 405411392 9158560
06:15:35 28356 8192000000 10423362768 10877988864 1112435680 11.955 100 27 54 2 1525025360 404458656 8131024
06:17:49 35377 8192000000 10072261904 10527318016 1108473440 11.918 99 18 36 1 1223489456 111342176 3673840
06:19:30 16097 8192000000 10083385472 10511106048 1107242256 11.906 100 39 78 0 1151853664 36769808 7841600
06:20:55 67924 8192000000 10095931248 10574446592 1106836816 11.902 99 30 60 0 1172167584 58949136 6381632
06:23:05 128652 8192000000 10075320976 10498744320 1059135104 11.448 94 12 24 2 1116432560 55124160 2173296
06:26:19 102598 8192000000 10052989120 10518863872 1112015904 11.951 99 5 10 0 1173563872 60793440 754528
06:29:02 180880 8192000000 10057005104 10492989440 1109233296 11.925 98 16 32 0 1150334544 38151808 2949440
06:33:04 36359 8192000000 10090156976 10543013888 1135044304 12.169 100 46 90 0 1199490928 54870352 9576272
06:34:50 70528 8192000000 10088994208 10558791680 1139314848 12.209 100 4 8 0 1193772560 53530208 927504
06:37:05 32615 8192000000 10074864656 10536603648 1130667360 12.128 99 14 28 1 1175491184 41560160 3263664
06:38:39 64283 8192000000 10111172320 10554699776 1103260128 11.869 96 19 39 0 1158347616 50781568 4305920
06:40:47 91438 8192000000 10081268576 10562990080 1141100416 12.226 100 20 40 1 1193241184 48055856 4084912
06:43:26 33158 8192000000 10066417296 10511589376 1139131616 12.207 100 20 40 1 1164596480 21513744 3951120
06:45:40 69733 8192000000 10130766576 10607747072 1142235216 12.237 99 12 24 0 1220328784 75332896 2760672
06:47:50 72102 8192000000 10074659664 10556444672 1139771904 12.213 99 5 10 0 1188389696 47610112 1007680
06:50:04 48770 8192000000 10135354656 10623291392 1148577344 12.296 100 37 74 0 1230968400 74807792 7583264
06:52:20 72980 8192000000 10090609152 10542104576 1087495808 11.719 95 22 44 0 1138770016 46599696 4674512
06:54:34 55878 8192000000 10077242784 10537623552 1101287392 11.850 98 14 28 1 1169926128 65767264 2871472
06:56:30 61101 8192000000 10061278624 10527453184 1092345584 11.765 97 13 26 1 1149706016 54471360 2889072
06:58:42 60521 8192000000 10129995232 10576261120 1069232016 11.545 96 29 58 0 1141978368 66775488 5970864
07:00:53 146860 8192000000 10069320928 10511273984 1103049744 11.867 99 28 57 1 1159247200 49622160 6575296
07:05:40 54143 8192000000 10046736304 10506330112 1088246128 11.726 97 5 10 1 1130031872 40842240 943504
07:07:36 71039 8192000000 10062005008 10527232000 1122153728 12.047 100 17 34 0 1165348512 38950048 4244736
07:10:03 58214 8192000000 10078816688 10527461376 1093637344 11.777 97 15 30 0 1148349664 51060512 3651808
07:12:06 26935 8192000000 10059586096 10502275072 1090534144 11.748 97 39 78 0 1154632848 55762128 8336576
07:13:34 39895 8192000000 10116431072 10556100608 1109217472 11.925 99 27 54 0 1176545200 61771392 5556336
07:15:17 49712 8192000000 10101316096 10543296512 1112928496 11.960 99 37 74 3 1207485936 86472288 8085152
07:17:07 47119 8192000000 10165690624 10606641152 1133700624 12.156 100 25 50 0 1238821040 99590880 5529536
07:18:54 53819 8192000000 10114353488 10625523712 1126968144 12.093 99 10 20 0 1210771216 81563424 2239648
07:20:51 55323 8192000000 10070794928 10515128320 1100949264 11.847 97 28 56 0 1127564528 20018464 6596800
07:22:51 86156 8192000000 10078841952 10529501184 1141375808 12.228 100 31 61 1 1176900496 29269392 6255296
07:25:27 73015 8192000000 10085266496 10552885248 1152210480 12.330 100 12 24 0 1193826912 39312160 2304272
07:27:40 68999 8192000000 10116274096 10576809984 1134123888 12.160 98 10 20 1 1179086576 42813232 2149456
07:29:50 68063 8192000000 10124222608 10602803200 1148600496 12.296 99 22 44 0 1206920864 53217264 5103104
07:32:22 58059 8192000000 10079847136 10522046464 1127373696 12.097 98 26 52 2 1184055552 50377824 6304032
07:34:24 41478 8192000000 10128930848 10565529600 1122013488 12.046 98 48 95 0 1190288528 58464880 9810160
07:36:06 47012 8192000000 10132085840 10567081984 1097686992 11.816 95 22 44 1 1179183696 77040528 4456176
07:38:16 87055 8192000000 10055172256 10513473536 1062384544 11.479 93 8 16 0 1098138512 33887392 1866576
07:40:53 105278 8192000000 10083375776 10560782336 1098284784 11.821 97 25 50 0 1156295392 52830912 5179696
07:44:58 43337 8192000000 10064921616 10548277248 1111864928 11.950 99 8 16 1 1155167104 41777136 1525040
07:46:57 64133 8192000000 10054954384 10516729856 1079677856 11.644 96 17 34 2 1140120416 56822928 3619632
07:49:02 78201 8192000000 10066368624 10506280960 1095532864 11.795 98 25 50 1 1141935632 40909376 5493392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-08-2021 07:51:49 to 20-08-2021 07:52:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-08-2021 07:52:05 to 20-08-2021 07:52:07.

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          238160
MON$OLDEST_ACTIVE               239563
MON$OLDEST_SNAPSHOT             239563
MON$NEXT_TRANSACTION            239563
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                       2063264
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             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Aug 20 07:50:35 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Aug 20 07:50:40 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Aug 20 07:50:40 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Aug 20 07:50:45 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

20.08.21 07:52:08. 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
2021-08-20 07:52:10 No severe PSQL-related problems occured
20.08.21 07:52:12. End of report.