Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.06.2022 07:50
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 16159308 kB
MemAvailable 31254708 kB
Buffers 0 kB
Cached 15583848 kB
SwapCached 2488 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-24 07:48:46
DTS_END                         2022-06-24 07:48:46.1890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45634
TRN_ID                          213587
ATT_ID                          602
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: 8723.59 30845 1046831
customer order: creation 154.82 474 18578
customer order: refuse 13.26 391 1591
order to supplier: creation 43.21 1420 5185
order to supplier: removal 3.44 178742 413
invoice (draft): creation 43.86 3631 5263
invoice (draft): removal 50.23 1039 6027
invoice accept: apply 39.32 28129 4718
invoice accept: cancel 45.11 1374 5413
customer reserve: creation 3655.23 268 438628
customer reserve: removal 4395.42 111 527450
realization accept: apply 77.71 271 9325
realization accept: cancel 81.89 172 9827
payment from customer: creation 54.98 185 6598
payment from customer: removal 5.46 151 655
payment to supplier: creation 44.63 203 5356
payment to supplier: removal 3.15 100 378
service: total inventory turnovers 3.71 4323 445
service: total monetary turnovers 7.13 338 855
service: aggregate perf. data 1.01 13317 121
service: refresh index statistics 0.04 382267 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-06-2022 07:51:15 to 24-06-2022 07:51:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7619
TEST_TIME 2 7169
TEST_TIME 3 6570
TEST_TIME 4 6000
TEST_TIME 5 6026
TEST_TIME 6 5890
TEST_TIME 7 5811
TEST_TIME 8 5731
TEST_TIME 9 5734
TEST_TIME 10 6006
TEST_TIME 11 6047
TEST_TIME 12 6288
TEST_TIME 13 6492
TEST_TIME 14 6492
TEST_TIME 15 6458
TEST_TIME 16 6438
TEST_TIME 17 6458
TEST_TIME 18 6490
TEST_TIME 19 6526
TEST_TIME 20 6615
TEST_TIME 21 6951
TEST_TIME 22 6914
TEST_TIME 23 6970
TEST_TIME 24 7100
TEST_TIME 25 7303
TEST_TIME 26 8081
TEST_TIME 27 8163
TEST_TIME 28 8419
TEST_TIME 29 8525
TEST_TIME 30 8705
TEST_TIME 31 9112
TEST_TIME 32 9424
TEST_TIME 33 10082
TEST_TIME 34 10313
TEST_TIME 35 10414
TEST_TIME 36 10198
TEST_TIME 37 10061
TEST_TIME 38 10075
TEST_TIME 39 9907
TEST_TIME 40 9758
TEST_TIME 41 9668
TEST_TIME 42 9515
TEST_TIME 43 9656
TEST_TIME 44 9579
TEST_TIME 45 9390
TEST_TIME 46 9421
TEST_TIME 47 9283
TEST_TIME 48 9217
TEST_TIME 49 9146
TEST_TIME 50 9153
TEST_TIME 51 9141
TEST_TIME 52 9090
TEST_TIME 53 8999
TEST_TIME 54 8909
TEST_TIME 55 8891
TEST_TIME 56 9007
TEST_TIME 57 9084
TEST_TIME 58 9058
TEST_TIME 59 8985
TEST_TIME 60 8924
TEST_TIME 61 9061
TEST_TIME 62 9126
TEST_TIME 63 9156
TEST_TIME 64 9220
TEST_TIME 65 9316
TEST_TIME 66 9340
TEST_TIME 67 9231
TEST_TIME 68 9156
TEST_TIME 69 9191
TEST_TIME 70 9202
TEST_TIME 71 9124
TEST_TIME 72 9088
TEST_TIME 73 9030
TEST_TIME 74 8959
TEST_TIME 75 8930
TEST_TIME 76 8854
TEST_TIME 77 8812
TEST_TIME 78 8842
TEST_TIME 79 8781
TEST_TIME 80 8760
TEST_TIME 81 8796
TEST_TIME 82 8771
TEST_TIME 83 8799
TEST_TIME 84 8770
TEST_TIME 85 8783
TEST_TIME 86 8765
TEST_TIME 87 8786
TEST_TIME 88 8745
TEST_TIME 89 8914
TEST_TIME 90 8878
TEST_TIME 91 8862
TEST_TIME 92 8901
TEST_TIME 93 8907
TEST_TIME 94 8883
TEST_TIME 95 8820
TEST_TIME 96 8799
TEST_TIME 97 8779
TEST_TIME 98 8722
TEST_TIME 99 8676
TEST_TIME 100 8634
TEST_TIME 101 8648
TEST_TIME 102 8614
TEST_TIME 103 8610
TEST_TIME 104 8592
TEST_TIME 105 8581
TEST_TIME 106 8564
TEST_TIME 107 8595
TEST_TIME 108 8591
TEST_TIME 109 8585
TEST_TIME 110 8608
TEST_TIME 111 8636
TEST_TIME 112 8633
TEST_TIME 113 8725
TEST_TIME 114 8734
TEST_TIME 115 8890
TEST_TIME 116 8845
TEST_TIME 117 8818
TEST_TIME 118 8770
TEST_TIME 119 8774
TEST_TIME 120 8748

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-06-2022 07:51:19 to 24-06-2022 07:51:22.

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 1449316 1449316 0 0.00 0 24437 14 0 0
doc_list_biud 1449307 1449307 0 0.00 0 26027 47 0 0
sp_add_invoice_to_stock 4818 4718 100 2.07 43 232657 28129 0 100
sp_cancel_adding_invoice 5413 5413 0 0.00 5 88478 1374 0 0
sp_cancel_client_order 1591 1591 0 0.00 5 13698 391 0 0
sp_cancel_customer_reserve 527450 527450 0 0.00 1 34470 112 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 4 10212 152 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 4 1362 101 0 0
sp_cancel_supplier_invoice 6039 6027 12 0.19 2 24858 1039 0 12
sp_cancel_supplier_order 413 413 0 0.00 9 1475842 178742 0 0
sp_cancel_write_off 9827 9827 0 0.00 2 11559 173 0 0
sp_client_order 18578 18578 0 0.00 6 30386 475 0 0
sp_customer_reserve 438628 438628 0 0.00 1 54768 269 0 0
sp_fill_shopping_cart 29008 29008 0 0.00 1 13905 83 0 0
sp_fill_shopping_cart_clo_res 438591 438591 0 0.00 0 17130 20 0 0
sp_get_clo_for_invoice 4670 4670 0 0.00 0 31817 276 0 0
sp_kill_cost_storno 16273 16273 0 0.00 0 22297 76 0 0
sp_kill_qstorno_ret_qs2qd 1071167 1071167 0 0.00 0 26015 50 0 0
sp_kill_qty_storno 564714 564714 0 0.00 0 26025 112 0 0
sp_lock_selected_doc 19396 19396 0 0.00 0 8011 7 0 0
sp_make_cost_storno 25948 25948 0 0.00 0 24429 84 0 0
sp_multiply_rows_for_pdistr 25947 25947 0 0.00 0 9659 41 0 0
sp_multiply_rows_for_qdistr 18577 18577 0 0.00 0 15245 196 0 0
sp_pay_from_customer 6598 6598 0 0.00 5 11636 186 0 0
sp_pay_to_supplier 5356 5356 0 0.00 7 16002 204 0 0
sp_payment_common 11954 11954 0 0.00 2 9135 47 0 0
sp_qd_handle_on_cancel_clo 1591 1591 0 0.00 0 13688 326 0 0
sp_qd_handle_on_invoice_upd_sts 10081 10081 0 0.00 0 16150 549 0 0
sp_qd_handle_on_reserve_upd_sts 9325 9325 0 0.00 0 12162 67 0 0
sp_reserve_write_off 9325 9325 0 0.00 9 24458 272 0 0
sp_supplier_invoice 5263 5263 0 0.00 11 126341 3631 0 0
sp_supplier_order 5185 5185 0 0.00 8 33347 1420 0 0
srv_aggregate_perf_data 121 121 0 0.00 2501 165082 13317 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 739 118598 30236 0 0
srv_find_qd_qs_mism 1000538 1000538 0 0.00 0 23261 11 0 0
srv_make_invnt_saldo 445 445 0 0.00 63 127900 4324 0 0
srv_make_money_saldo 855 855 0 0.00 1 15493 338 0 0
srv_recalc_idx_stat 5 5 0 0.00 182146 506991 382267 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4721 4721 0 0.00 0 12122 956 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 1 6928 450 0 0
t$perf-norm:sp_cancel_client_order 1591 1591 0 0.00 0 3453 27 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 909 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 3190 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 4115 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 745 29 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 107484 6764 0 0
t$perf-norm:sp_cancel_write_off 896 896 0 0.00 0 1205 19 0 0
t$perf-norm:sp_client_order 18579 18579 0 0.00 0 14396 36 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 800 14 0 0
t$perf-norm:sp_pay_from_customer 6599 6599 0 0.00 0 10841 20 0 0
t$perf-norm:sp_pay_to_supplier 5356 5356 0 0.00 0 15130 21 0 0
t$perf-norm:sp_reserve_write_off 9325 9325 0 0.00 0 4684 22 0 0
t$perf-norm:sp_supplier_invoice 5263 5263 0 0.00 0 3213 33 0 0
t$perf-norm:sp_supplier_order 5185 5185 0 0.00 0 17065 38 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 288 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 586 21 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 448 15 0 0
t$perf-norm:srv_make_money_saldo 855 855 0 0.00 0 326 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 308 95 0 0
v_add_invoice_to_stock 9528 9328 200 2.09 0 3297 23 0 200
v_all_customers 34348 34348 0 0.00 0 66 3 0 0
v_all_suppliers 28957 28957 0 0.00 0 101 4 0 0
v_all_wares 37142 37142 0 0.00 0 275 4 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 646 14 0 0
v_cancel_client_order 3182 3182 0 0.00 0 1996 9 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 3371 12 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 2047 14 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 191 9 0 0
v_cancel_supplier_invoice 778 754 24 3.08 0 3110 28 0 24
v_cancel_supplier_order 826 826 0 0.00 0 465 11 0 0
v_cancel_write_off 1792 1792 0 0.00 0 1480 21 0 0
v_max_id_clo_ord 5180 5180 0 0.00 0 736 5 0 0
v_max_id_clo_res 973 973 0 0.00 0 5027 34 0 0
v_max_id_ord_sup 5254 5254 0 0.00 0 3995 7 0 0
v_max_non_paid_invoice 1311 1311 0 0.00 0 83 7 0 0
v_max_non_paid_realizn 3338 3338 0 0.00 0 9129 11 0 0
v_min_id_clo_ord 5180 5180 0 0.00 0 4790 10 0 0
v_min_id_clo_res 973 973 0 0.00 0 2250 11 0 0
v_min_id_ord_sup 5254 5254 0 0.00 0 4058 11 0 0
v_min_non_paid_invoice 5355 5355 0 0.00 0 6486 18 0 0
v_min_non_paid_realizn 6597 6597 0 0.00 0 4988 21 0 0
v_our_firm 9308 9308 0 0.00 0 63 4 0 0
v_reserve_write_off 18650 18650 0 0.00 0 3560 10 0 0
x_lock_depdocs_on_canc_invoice 11063 11063 0 0.00 0 22981 134 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 8362 374 0 0
x_make_qstorno_1000_1200 5185 5185 0 0.00 2 33330 1248 0 0
x_make_qstorno_1200_2000 5263 5263 0 0.00 5 124461 3403 0 0
x_make_qstorno_2100_3300 438627 438627 0 0.00 0 54760 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-06-2022 07:51:23 to 24-06-2022 07:51:27.

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:18:49 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:19:50 29298 8192000000 9722839392 10022486016 800664064 8.903 100 85 170 0 829175280 7624912 20886304
05:21:30 19434 8192000000 9770308400 10081947648 848668816 9.387 100 84 168 1 880489504 12295616 19525072
05:22:54 18634 8192000000 9807927408 10130292736 877491456 9.675 100 80 159 0 917655328 21731536 18432336
05:24:21 12384 8192000000 9861064880 10195841024 915002560 10.047 100 83 166 0 976320544 42788400 18529584
05:25:39 4541 8192000000 9915492944 10262917120 959848656 10.488 100 83 164 1 1031758176 54207024 17702496
05:26:49 2460 8192000000 9966073872 10321100800 997449520 10.854 100 90 176 2 1087028160 71503104 18075536
05:27:53 1202 8192000000 10010435520 10376278016 1019216000 11.064 100 94 183 0 1130179488 92606352 18357136
05:28:56 1231 8192000000 10017856976 10388729856 1039267424 11.258 100 97 185 4 1151856352 93610192 18978736
05:29:58 690 8192000000 10051158896 10427686912 1056993392 11.428 100 92 171 0 1172010480 97648064 17369024
05:31:00 12389 8192000000 10030036384 10411163648 1067751024 11.531 100 76 144 1 1165922592 84013680 14157888
05:32:14 1132 8192000000 10043051520 10423918592 1077956720 11.628 100 96 189 1 1163555952 66185392 19413840
05:33:16 1422 8192000000 10041998016 10428989440 1082911136 11.675 100 88 167 2 1163094576 63520208 16663232
05:34:21 1067 8192000000 10062719872 10450309120 1089376560 11.737 100 93 184 1 1185197472 75934768 19886144
05:35:24 1372 8192000000 10067361472 10459545600 1097190336 11.811 100 90 177 0 1188610896 73018384 18402176
05:36:27 945 8192000000 10062136592 10450247680 1100386832 11.841 100 91 180 1 1181479312 62199248 18893232
05:37:29 1207 8192000000 10069070080 10457714688 1101733584 11.854 100 96 181 1 1191938944 71003936 19201424
05:38:32 10722 8192000000 10035151840 10434883584 1101049872 11.848 100 61 116 0 1182621056 70215264 11355920
05:39:43 2796 8192000000 10015039024 10401030144 1072061744 11.572 98 86 166 3 1143223424 54191488 16970192
05:40:47 3715 8192000000 10049492016 10434146304 1090730608 11.750 100 78 156 1 1173585504 67527136 15327760
05:41:51 1045 8192000000 10044165840 10428801024 1084631616 11.692 100 93 181 1 1163385936 60507344 18246976
05:42:54 1094 8192000000 10050402512 10431098880 1066921616 11.523 100 93 175 1 1171274960 85991008 18362336
05:43:56 2726 8192000000 10030029952 10419814400 1061449936 11.470 99 79 156 2 1161217760 83694208 16073616
05:44:59 1062 8192000000 9975617440 10347298816 1043930016 11.302 99 90 172 1 1095677424 32622064 19125344
05:46:01 1274 8192000000 9976924672 10350657536 1032177104 11.189 100 75 138 0 1098205488 51843504 14184880
05:47:04 1671 8192000000 9987694912 10362023936 1041974640 11.284 100 78 139 0 1109355904 54309520 13071744
05:48:06 1581 8192000000 10013551744 10395709440 1060741792 11.464 100 72 136 1 1132132048 56933552 14456704
05:49:14 1057 8192000000 10001083728 10376384512 1055550848 11.414 100 84 157 1 1119722032 49413776 14757408
05:50:22 942 8192000000 10044404176 10423787520 1077589504 11.624 100 92 177 2 1159599824 63980592 18029728
05:51:38 9246 8192000000 10026146848 10420772864 1077803440 11.627 100 72 134 1 1146131056 54180928 14146688
05:52:54 1151 8192000000 10085621872 10469216256 1094588816 11.786 100 98 191 1 1205315440 89315808 21410816
05:54:26 1678 8192000000 10100638560 10496786432 1113112384 11.962 100 79 155 2 1215413280 86379648 15921248
05:55:36 4226 8192000000 10134878768 10533228544 1116057744 11.990 100 61 118 0 1246124592 117455200 12611648
05:56:44 7776 8192000000 10162405376 10550370304 1119586448 12.023 100 79 152 2 1267673280 132286704 15800128
05:57:56 769 8192000000 10214051184 10607386624 1139715744 12.213 100 88 168 1 1324294992 166052576 18526672
05:59:01 2937 8192000000 10230659888 10625794048 1153320112 12.341 100 82 158 1 1345234704 174830720 17083872
06:00:08 4947 8192000000 10253434016 10669580288 1160052128 12.404 100 79 147 0 1358223104 181961472 16209504
06:01:17 1905 8192000000 10275609520 10691317760 1170063408 12.497 100 89 177 2 1384358304 194558224 19736672
06:02:24 1540 8192000000 10275687648 10679070720 1173514656 12.530 100 90 175 1 1389077792 195629280 19933856
06:03:30 1269 8192000000 10328638816 10729713664 1178773776 12.579 100 94 185 1 1435248416 235033968 21440672
06:04:36 1115 8192000000 10380574448 10786607104 1185616064 12.643 100 94 184 2 1489671696 282330880 21724752
06:05:42 1144 8192000000 10391864384 10802880512 1194234800 12.723 100 95 186 1 1500520752 284847248 21438704
06:06:49 904 8192000000 10442636256 10857050112 1203584400 12.810 100 94 182 1 1543066400 317869616 21612384
06:07:55 1196 8192000000 10483028784 10891300864 1201148928 12.787 100 93 184 1 1587589216 363778464 22661824
06:09:00 875 8192000000 10490018064 10921496576 1212192288 12.889 100 97 187 1 1594464320 359816752 22455280
06:10:04 1205 8192000000 10515746272 10928148480 1211879376 12.887 100 94 185 1 1625676592 391651552 22145664
06:11:09 876 8192000000 10547115696 10953998336 1197810656 12.756 100 98 194 0 1652643568 431202928 23629984
06:12:13 1164 8192000000 10542975184 10994864128 1188399936 12.668 100 96 186 0 1646706672 434297200 24009536
06:13:16 731 8192000000 10500060768 10960523264 1176065904 12.553 99 84 169 0 1605922448 409419632 20436912
06:14:21 1121 8192000000 10488095296 10990256128 1191402496 12.696 100 80 158 2 1592612304 381718864 19490944
06:15:24 10087 8192000000 10445990448 10909536256 1194613008 12.726 100 54 108 0 1565555728 357341328 13601392
06:16:41 12260 8192000000 10454914176 10887507968 1184859072 12.635 100 41 79 2 1553568112 358663568 10045472
06:17:55 9307 8192000000 10437813088 10886078464 1164990016 12.450 100 42 84 0 1554619760 378463680 11166064
06:19:11 13525 8192000000 10371194224 10899230720 1173202128 12.527 100 34 68 0 1480242352 298090304 8949920
06:20:56 28372 8192000000 10289630544 10808242176 1146403904 12.276 100 16 32 1 1400718400 250009696 4304800
06:22:25 80470 8192000000 10088960384 10569834496 1103007792 11.866 96 34 68 3 1148153952 36813584 8332576
06:24:47 51164 8192000000 10048939280 10498371584 1110198480 11.934 98 33 63 1 1142219744 25892224 6129040
06:26:42 43641 8192000000 10100009488 10570821632 1137456576 12.192 100 14 29 0 1184852560 44402768 2993216
06:30:02 97689 8192000000 10115204800 10623016960 1136506784 12.183 100 30 60 1 1231726976 88058544 7161648
06:33:00 19314 8192000000 10067338816 10514407424 1137806832 12.195 100 68 129 0 1172694208 19908592 14978784
06:34:30 38511 8192000000 10077993456 10557837312 1131720896 12.138 99 18 36 0 1196911728 61543600 3647232
06:36:30 26086 8192000000 10112861136 10554634240 1151135392 12.320 100 33 66 0 1220261600 62386176 6740032
06:37:57 14236 8192000000 10114624672 10562461696 1135774640 12.176 99 48 96 1 1209126416 62545840 10805936
06:39:15 61476 8192000000 10106940544 10576089088 1158185872 12.386 100 12 24 2 1219431840 58359632 2886336
06:41:21 33693 8192000000 10109786112 10593198080 1128994192 12.112 98 8 14 2 1188212096 57879952 1337952
06:42:55 44802 8192000000 10176829552 10618208256 1116479920 11.994 97 28 56 0 1235291840 112458928 6352992
06:44:43 34937 8192000000 10141905632 10612510720 1119475744 12.022 97 37 70 2 1231189328 103700992 8012592
06:46:44 61424 8192000000 10175835072 10615214080 1127881392 12.101 98 21 42 0 1236872416 104484400 4506624
06:48:46 49689 8192000000 10190098384 10642927616 1146275376 12.275 99 35 70 0 1323727632 169697824 7754432
06:50:37 26063 8192000000 10210926304 10660786176 1150675440 12.316 99 44 88 0 1288826992 128784544 9367008
06:52:10 107985 8192000000 10114344592 10636795904 1133380336 12.153 99 15 30 0 1239150080 102203824 3565920
06:55:05 52733 8192000000 10142267584 10583490560 1141738000 12.232 100 45 89 1 1235342048 84250560 9353488
06:57:22 36110 8192000000 10099349648 10596593664 1132728112 12.147 99 16 32 1 1197012736 60543168 3741456
06:59:00 31052 8192000000 10058462768 10543132672 1134051696 12.160 99 16 32 0 1163960880 26795952 3113232
07:00:33 33444 8192000000 10061334528 10522324992 1130488224 12.126 99 16 32 1 1165420528 32017056 2915248
07:02:07 48424 8192000000 10074423040 10528583680 1139593712 12.212 100 13 26 0 1180743696 38284848 2865136
07:03:56 67043 8192000000 10069058800 10510913536 1079527904 11.643 95 16 32 0 1112886192 29330336 4027952
07:06:09 54721 8192000000 10059306384 10515202048 1126504512 12.088 100 12 23 0 1175762304 46920144 2337648
07:08:06 49045 8192000000 10073603248 10542149632 1094022416 11.781 97 17 34 0 1159109744 61822496 3264832
07:09:56 64383 8192000000 10079849008 10528759808 1101622480 11.853 98 26 50 0 1138354976 31745984 4986512
07:12:03 51816 8192000000 10111101856 10547511296 1128168144 12.104 99 26 50 1 1171810416 38272960 5369312
07:13:57 25002 8192000000 10121965776 10556948480 1114348416 11.974 98 54 108 1 1200607520 75660848 10598256
07:15:24 34596 8192000000 10149806944 10607513600 1134687504 12.166 100 28 56 0 1231666992 91139840 5839648
07:17:10 56146 8192000000 10062476048 10549198848 1104568528 11.881 97 19 38 0 1175732336 67031584 4132224
07:19:19 15991 8192000000 10124066464 10565632000 1124150320 12.066 99 52 102 0 1206043600 71996752 9896528
07:20:35 40599 8192000000 10114018400 10560679936 1140151408 12.217 100 41 83 0 1215236976 66280256 8805312
07:22:23 118597 8192000000 10072214896 10508156928 1113847216 11.969 98 41 75 0 1153201664 30558992 8795456
07:25:22 41805 8192000000 10055076944 10534215680 1120210624 12.029 99 12 24 0 1169025648 46564544 2250480
07:27:05 63190 8192000000 10071856880 10523381760 1109669792 11.929 98 10 21 0 1152551776 40631312 2250672
07:29:21 55552 8192000000 10069872176 10530959360 1122586352 12.051 99 10 18 1 1154207648 29388704 2232592
07:31:19 34791 8192000000 10083234336 10525179904 1101767760 11.854 98 25 50 1 1163857264 56661568 5427936
07:32:55 25937 8192000000 10139551584 10586120192 1121126464 12.038 99 38 74 1 1237493632 107440000 8927168
07:34:21 23487 8192000000 10177569440 10608500736 1106163104 11.896 97 45 90 1 1248920000 132515504 10241392
07:35:49 23347 8192000000 10211791200 10660757504 1144888336 12.261 100 33 63 0 1292716480 140689200 7138944
07:37:16 16179 8192000000 10246548816 10686898176 1141570656 12.230 99 41 82 1 1331157760 180435872 9151232
07:38:46 19620 8192000000 10257071360 10690150400 1157750800 12.382 99 53 106 1 1329866512 160455968 11659744
07:40:07 34723 8192000000 10256512368 10695307264 1154064368 12.348 99 37 74 1 1310384256 147266176 9053712
07:41:45 47392 8192000000 10230542976 10698895360 1147456528 12.286 99 14 28 1 1311511200 160502736 3551936
07:44:18 28555 8192000000 10119973952 10542108672 1129919344 12.121 98 54 101 0 1184143472 43921648 10302480
07:45:48 96682 8192000000 10083911072 10561454080 1145942592 12.271 100 34 68 0 1225640256 72828624 6869040
07:48:27 31708 8192000000 9402777616 9741361152 529890560 6.075 47 2 4 0 544910912 14726032 294320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-06-2022 07:51:29 to 24-06-2022 07:51:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-06-2022 07:51:46 to 24-06-2022 07:51:48.

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          213798
MON$OLDEST_ACTIVE               213799
MON$OLDEST_SNAPSHOT             213799
MON$NEXT_TRANSACTION            213799
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                       2048480
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2787 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2787 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2787 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2787 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 Jun 24 07:49:17 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
:26: 
:27: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:29: 
:30: 
:31: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:32:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:33: 
:34: 
:35: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:36:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:37: 
:38: 
:39: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:40:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:41: 
:42: 
:43: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:45: 
:46: 
:47: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Fri Jun 24 07:49:25 2022
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
--- end of diff output ---

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

24.06.22 07:51:49. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-06-24 07:51:52 No severe PSQL-related problems occured
24.06.22 07:51:53. End of report.