Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.08.2022 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15862244 kB
MemAvailable 30972900 kB
Buffers 0 kB
Cached 15877604 kB
SwapCached 2512 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-08-09 10:37:19
DTS_END                         2022-08-09 10:37:19.4070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55838
TRN_ID                          227800
ATT_ID                          606
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: 9015.56 21706 1081867
customer order: creation 178.68 465 21442
customer order: refuse 15.03 382 1803
order to supplier: creation 47.61 1328 5713
order to supplier: removal 3.42 144155 410
invoice (draft): creation 48.64 3183 5837
invoice (draft): removal 48.88 948 5865
invoice accept: apply 43.41 26782 5209
invoice accept: cancel 43.47 1102 5216
customer reserve: creation 4067.13 254 488056
customer reserve: removal 4230.03 91 507604
realization accept: apply 87.08 271 10450
realization accept: cancel 68.55 157 8226
payment from customer: creation 62.07 169 7448
payment from customer: removal 6.14 136 737
payment to supplier: creation 48.85 195 5862
payment to supplier: removal 3.72 122 446
service: total inventory turnovers 4.33 3380 520
service: total monetary turnovers 7.67 278 920
service: aggregate perf. data 0.82 16886 98
service: refresh index statistics 0.04 233845 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-08-2022 10:38:47 to 09-08-2022 10:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5447
TEST_TIME 2 5723
TEST_TIME 3 5816
TEST_TIME 4 6085
TEST_TIME 5 6013
TEST_TIME 6 5910
TEST_TIME 7 6009
TEST_TIME 8 6162
TEST_TIME 9 6304
TEST_TIME 10 6433
TEST_TIME 11 6988
TEST_TIME 12 7107
TEST_TIME 13 7121
TEST_TIME 14 7137
TEST_TIME 15 7300
TEST_TIME 16 7282
TEST_TIME 17 7458
TEST_TIME 18 7626
TEST_TIME 19 7946
TEST_TIME 20 7971
TEST_TIME 21 8599
TEST_TIME 22 8703
TEST_TIME 23 8707
TEST_TIME 24 9024
TEST_TIME 25 9046
TEST_TIME 26 9410
TEST_TIME 27 9775
TEST_TIME 28 10312
TEST_TIME 29 11065
TEST_TIME 30 11407
TEST_TIME 31 11381
TEST_TIME 32 11333
TEST_TIME 33 11472
TEST_TIME 34 11343
TEST_TIME 35 11205
TEST_TIME 36 11024
TEST_TIME 37 10916
TEST_TIME 38 10942
TEST_TIME 39 10817
TEST_TIME 40 10726
TEST_TIME 41 10637
TEST_TIME 42 10600
TEST_TIME 43 10504
TEST_TIME 44 10475
TEST_TIME 45 10463
TEST_TIME 46 10301
TEST_TIME 47 10347
TEST_TIME 48 10265
TEST_TIME 49 10126
TEST_TIME 50 9987
TEST_TIME 51 10015
TEST_TIME 52 10086
TEST_TIME 53 10040
TEST_TIME 54 9980
TEST_TIME 55 10002
TEST_TIME 56 9986
TEST_TIME 57 10021
TEST_TIME 58 10005
TEST_TIME 59 9945
TEST_TIME 60 9970
TEST_TIME 61 9917
TEST_TIME 62 10029
TEST_TIME 63 10116
TEST_TIME 64 10046
TEST_TIME 65 9919
TEST_TIME 66 9827
TEST_TIME 67 9923
TEST_TIME 68 9862
TEST_TIME 69 9821
TEST_TIME 70 9817
TEST_TIME 71 9744
TEST_TIME 72 9700
TEST_TIME 73 9660
TEST_TIME 74 9652
TEST_TIME 75 9667
TEST_TIME 76 9637
TEST_TIME 77 9654
TEST_TIME 78 9648
TEST_TIME 79 9731
TEST_TIME 80 9779
TEST_TIME 81 9820
TEST_TIME 82 9748
TEST_TIME 83 9668
TEST_TIME 84 9610
TEST_TIME 85 9561
TEST_TIME 86 9564
TEST_TIME 87 9505
TEST_TIME 88 9466
TEST_TIME 89 9423
TEST_TIME 90 9380
TEST_TIME 91 9333
TEST_TIME 92 9302
TEST_TIME 93 9334
TEST_TIME 94 9296
TEST_TIME 95 9294
TEST_TIME 96 9333
TEST_TIME 97 9313
TEST_TIME 98 9340
TEST_TIME 99 9326
TEST_TIME 100 9350
TEST_TIME 101 9425
TEST_TIME 102 9390
TEST_TIME 103 9436
TEST_TIME 104 9480
TEST_TIME 105 9445
TEST_TIME 106 9460
TEST_TIME 107 9393
TEST_TIME 108 9334
TEST_TIME 109 9305
TEST_TIME 110 9281
TEST_TIME 111 9228
TEST_TIME 112 9175
TEST_TIME 113 9149
TEST_TIME 114 9172
TEST_TIME 115 9147
TEST_TIME 116 9148
TEST_TIME 117 9135
TEST_TIME 118 9107
TEST_TIME 119 9092
TEST_TIME 120 9033

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-08-2022 10:38:51 to 09-08-2022 10:38:54.

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 1530076 1530076 0 0.00 0 16675 13 0 0
doc_list_biud 1530074 1530074 0 0.00 0 59172 36 0 0
sp_add_invoice_to_stock 5348 5209 139 2.59 33 297761 26782 0 139
sp_cancel_adding_invoice 5216 5216 0 0.00 6 117250 1102 0 0
sp_cancel_client_order 1803 1803 0 0.00 6 13208 383 0 0
sp_cancel_customer_reserve 507604 507604 0 0.00 1 59175 92 0 0
sp_cancel_pay_from_customer 737 737 0 0.00 4 7637 136 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 4 6334 123 0 0
sp_cancel_supplier_invoice 5872 5865 7 0.11 2 24353 948 0 7
sp_cancel_supplier_order 410 410 0 0.00 8 1330524 144155 0 0
sp_cancel_write_off 8226 8226 0 0.00 2 13025 158 0 0
sp_client_order 21442 21442 0 0.00 5 20641 465 0 0
sp_customer_reserve 488056 488056 0 0.00 1 119102 255 0 0
sp_fill_shopping_cart 32987 32987 0 0.00 2 25704 79 0 0
sp_fill_shopping_cart_clo_res 488031 488031 0 0.00 0 16308 20 0 0
sp_get_clo_for_invoice 5184 5184 0 0.00 0 23252 301 0 0
sp_kill_cost_storno 14625 14625 0 0.00 0 16666 67 0 0
sp_kill_qstorno_ret_qs2qd 1029709 1029709 0 0.00 0 59166 39 0 0
sp_kill_qty_storno 544758 544758 0 0.00 0 59172 92 0 0
sp_lock_selected_doc 21608 21608 0 0.00 0 6982 7 0 0
sp_make_cost_storno 28944 28944 0 0.00 0 13195 75 0 0
sp_multiply_rows_for_pdistr 28944 28944 0 0.00 0 8772 37 0 0
sp_multiply_rows_for_qdistr 21442 21442 0 0.00 0 14608 183 0 0
sp_pay_from_customer 7448 7448 0 0.00 5 11867 170 0 0
sp_pay_to_supplier 5862 5862 0 0.00 6 12687 196 0 0
sp_payment_common 13310 13310 0 0.00 2 11434 43 0 0
sp_qd_handle_on_cancel_clo 1803 1803 0 0.00 1 13169 300 0 0
sp_qd_handle_on_invoice_upd_sts 10400 10400 0 0.00 0 26862 479 0 0
sp_qd_handle_on_reserve_upd_sts 10450 10450 0 0.00 0 20479 66 0 0
sp_reserve_write_off 10450 10450 0 0.00 7 20560 271 0 0
sp_supplier_invoice 5837 5837 0 0.00 8 100609 3183 0 0
sp_supplier_order 5713 5713 0 0.00 6 24142 1329 0 0
srv_aggregate_perf_data 98 98 0 0.00 2294 186311 16887 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 967 150067 34844 0 0
srv_find_qd_qs_mism 1030178 1030178 0 0.00 0 15653 11 0 0
srv_make_invnt_saldo 520 520 0 0.00 48 62887 3380 0 0
srv_make_money_saldo 920 920 0 0.00 1 8557 278 0 0
srv_recalc_idx_stat 5 5 0 0.00 68862 510097 233846 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5212 5212 0 0.00 1 8164 802 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 6401 329 0 0
t$perf-norm:sp_cancel_client_order 1803 1803 0 0.00 0 3903 27 0 0
t$perf-norm:sp_cancel_customer_reserve 742 742 0 0.00 0 1453 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 737 737 0 0.00 0 1220 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 127 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 704 24 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 91338 5734 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 1747 21 0 0
t$perf-norm:sp_client_order 21442 21442 0 0.00 0 4908 31 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 2730 13 0 0
t$perf-norm:sp_pay_from_customer 7448 7448 0 0.00 0 2710 15 0 0
t$perf-norm:sp_pay_to_supplier 5862 5862 0 0.00 0 3105 17 0 0
t$perf-norm:sp_reserve_write_off 10450 10450 0 0.00 0 4724 20 0 0
t$perf-norm:sp_supplier_invoice 5837 5837 0 0.00 0 5179 26 0 0
t$perf-norm:sp_supplier_order 5713 5713 0 0.00 0 6789 31 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 286 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 580 13 0 0
t$perf-norm:srv_make_invnt_saldo 520 520 0 0.00 0 3805 17 0 0
t$perf-norm:srv_make_money_saldo 920 920 0 0.00 0 1488 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 197 86 0 0
v_add_invoice_to_stock 10644 10366 278 2.61 0 8236 29 0 278
v_all_customers 39120 39120 0 0.00 0 73 3 0 0
v_all_suppliers 31550 31550 0 0.00 0 124 4 0 0
v_all_wares 42884 42884 0 0.00 0 116 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 749 14 0 0
v_cancel_client_order 3606 3606 0 0.00 0 8249 18 0 0
v_cancel_customer_prepayment 1474 1474 0 0.00 0 5131 15 0 0
v_cancel_customer_reserve 1484 1484 0 0.00 0 8312 18 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 1197 11 0 0
v_cancel_supplier_invoice 884 870 14 1.58 0 2867 31 0 14
v_cancel_supplier_order 820 820 0 0.00 0 8070 20 0 0
v_cancel_write_off 1986 1986 0 0.00 0 5206 27 0 0
v_max_id_clo_ord 5713 5713 0 0.00 0 1013 5 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 3609 24 0 0
v_max_id_ord_sup 5832 5832 0 0.00 0 3596 7 0 0
v_max_non_paid_invoice 1632 1632 0 0.00 0 192 7 0 0
v_max_non_paid_realizn 3940 3940 0 0.00 0 720 7 0 0
v_min_id_clo_ord 5713 5713 0 0.00 0 3049 8 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 1586 10 0 0
v_min_id_ord_sup 5832 5832 0 0.00 0 7475 10 0 0
v_min_non_paid_invoice 5862 5862 0 0.00 0 3981 16 0 0
v_min_non_paid_realizn 7448 7448 0 0.00 0 7149 18 0 0
v_our_firm 10780 10780 0 0.00 0 53 4 0 0
v_reserve_write_off 20900 20900 0 0.00 0 8336 13 0 0
x_lock_depdocs_on_canc_invoice 10646 10646 0 0.00 0 23328 107 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 9431 332 0 0
x_make_qstorno_1000_1200 5713 5713 0 0.00 2 24128 1179 0 0
x_make_qstorno_1200_2000 5837 5837 0 0.00 4 100576 2962 0 0
x_make_qstorno_2100_3300 488056 488056 0 0.00 0 119090 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-08-2022 10:38:55 to 09-08-2022 10:38:59.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:23 3 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 26376 8192000000 9725559584 10022154240 796708000 8.863 100 91 182 0 826885408 7808224 22369184
08:09:55 17486 8192000000 9759360992 10064437248 830939264 9.209 100 88 176 0 864923312 12960016 21024032
08:11:15 12686 8192000000 9791014912 10109370368 863788640 9.538 100 84 168 0 902065648 19262384 19014624
08:12:33 5356 8192000000 9849256400 10180419584 911789872 10.015 100 84 162 0 964911536 36276896 16844768
08:13:42 4090 8192000000 9924700224 10267574272 960645632 10.495 100 86 167 1 1035405968 56682432 18077904
08:14:53 2299 8192000000 9995533648 10348548096 995563104 10.835 100 92 181 0 1109964064 96368448 18032512
08:15:57 1423 8192000000 9986424048 10344894464 1013141344 11.006 100 90 172 2 1104401904 74834656 16425904
08:17:00 1496 8192000000 9998605744 10365059072 1040556496 11.270 100 89 170 0 1119896640 62471712 16868432
08:18:03 775 8192000000 10014850240 10387505152 1053426176 11.394 100 94 179 1 1136359744 64715504 18218064
08:19:06 1404 8192000000 10025912160 10397945856 1061446800 11.470 100 96 178 3 1147948640 67496512 19005328
08:20:09 960 8192000000 10043160032 10422538240 1073695232 11.587 100 92 179 1 1159581088 66907952 18977904
08:21:11 1034 8192000000 10048487280 10431725568 1073800256 11.588 100 94 172 1 1172484320 80936976 17747088
08:22:14 1107 8192000000 10064994000 10452779008 1083935360 11.685 100 96 188 1 1191189632 88056560 19197712
08:23:17 1141 8192000000 10056149072 10441830400 1087598208 11.720 100 98 187 0 1176536080 69765392 19172480
08:24:18 1101 8192000000 10064804688 10453766144 1092769856 11.769 100 100 194 0 1188107728 76182928 19154944
08:25:21 1013 8192000000 10067900912 10456719360 1094191136 11.782 100 96 184 1 1192081088 78922944 18967008
08:26:23 2011 8192000000 10048733936 10431119360 1093445328 11.775 100 80 145 2 1175412640 67968208 13999104
08:27:26 1079 8192000000 10064059200 10454528000 1088531712 11.729 100 91 168 1 1181941344 76707376 16702256
08:28:28 1076 8192000000 10034377776 10426302464 1086783296 11.712 100 84 158 1 1156226528 54515424 14927808
08:29:29 1290 8192000000 10032409008 10422734848 1082108864 11.668 100 79 143 6 1154855504 57377008 15369632
08:30:31 1030 8192000000 10036902464 10417557504 1067181456 11.525 100 92 175 1 1158189376 72725280 18282640
08:31:33 2031 8192000000 10006777328 10391162880 1063774736 11.493 100 75 135 1 1125964192 47573248 14616208
08:32:36 1085 8192000000 10020845776 10398179328 1051483760 11.375 100 92 174 2 1141993792 71629392 18880640
08:33:38 1130 8192000000 10021357824 10398588928 1056998640 11.428 100 96 183 0 1144756976 67735984 20022352
08:34:40 5480 8192000000 9968986160 10341711872 1016010368 11.033 97 55 98 1 1086451536 60141200 10299968
08:35:46 1476 8192000000 9937122528 10306998272 1004908048 10.926 99 86 145 3 1057607328 38099424 14599856
08:36:48 4150 8192000000 9999350288 10384031744 1054274032 11.402 100 61 115 2 1122055440 57045936 10735472
08:38:00 30079 8192000000 10013545056 10405617664 1076614928 11.615 100 55 103 1 1128703440 41833536 10254976
08:39:36 4886 8192000000 10056750256 10445598720 1097683520 11.816 100 71 139 1 1185718496 74005440 14029536
08:40:44 3083 8192000000 10074368928 10463834112 1102459280 11.861 100 71 138 0 1204464320 87703904 14301136
08:41:51 5669 8192000000 10118421408 10512887808 1118884320 12.016 100 71 139 0 1232061200 98356752 14820128
08:43:07 3864 8192000000 10129183824 10536128512 1130115904 12.122 100 61 116 0 1249170048 106726176 12327968
08:44:15 12918 8192000000 10211531552 10608013312 1137554208 12.193 100 85 164 3 1333354896 176043264 19757424
08:45:35 5114 8192000000 10215119632 10615308288 1147158896 12.283 100 76 145 1 1333998912 171060912 15779104
08:46:45 961 8192000000 10268476496 10664173568 1159839808 12.402 100 95 180 0 1380220144 200137072 20243264
08:47:49 1483 8192000000 10269719728 10681413632 1161711600 12.419 100 91 172 0 1379964240 200216032 18036608
08:48:56 1138 8192000000 10307186224 10705526784 1179754128 12.588 100 94 185 1 1419042016 219130832 20157056
08:49:59 2148 8192000000 10359245744 10759426048 1193730320 12.718 100 92 178 1 1470198432 256610592 19857520
08:51:03 984 8192000000 10419907232 10820857856 1192834048 12.710 100 99 188 2 1524429088 311489136 20105904
08:52:09 1630 8192000000 10437501440 10860425216 1200246128 12.779 100 94 179 1 1548976928 326147184 22583616
08:53:13 1274 8192000000 10475619920 10882289664 1199292112 12.770 100 98 188 2 1584115296 362591568 22231616
08:54:17 1007 8192000000 10510346432 10933661696 1189874512 12.682 100 99 189 0 1617173680 404180112 23119056
08:55:22 1197 8192000000 10544013072 10969387008 1197964064 12.757 100 96 187 2 1652822064 432377104 22480896
08:56:25 973 8192000000 10524943360 10963165184 1190633872 12.689 100 97 185 1 1631478064 418208960 22635232
08:57:30 1172 8192000000 10570168352 11011104768 1202543968 12.800 100 99 196 2 1673313600 446235008 24534624
08:58:32 1765 8192000000 10559514992 11044155392 1202133264 12.796 100 81 163 1 1663567616 441484624 19949728
08:59:38 5939 8192000000 10527306160 10953207808 1173402832 12.529 98 57 113 0 1617324608 429465440 14456336
09:00:52 3091 8192000000 10544432240 11008376832 1186232784 12.648 100 65 124 0 1646318976 443905232 16180960
09:02:19 17074 8192000000 10556491568 11014807552 1165717584 12.457 99 39 76 0 1642196256 465850000 10628672
09:03:58 25960 8192000000 10434871824 10947657728 1131762160 12.138 98 25 50 0 1516472272 377453936 7256176
09:05:29 31129 8192000000 10260032528 10795032576 1089402960 11.737 97 20 40 0 1398633728 303859088 5371680
09:07:01 52942 8192000000 10088426368 10585739264 1116269360 11.992 99 15 28 0 1201520576 81736240 3514976
09:08:55 79559 8192000000 10137053808 10638700544 1117050608 11.999 98 15 26 0 1180782896 61289696 2442592
09:11:39 124438 8192000000 10089865728 10529878016 1114596160 11.976 98 25 50 0 1192249360 72054384 5598816
09:14:51 32680 8192000000 10080250416 10519404544 1103434656 11.870 97 29 57 0 1142698912 34047072 5217184
09:16:25 13974 8192000000 10083255312 10502258688 1120587648 12.033 100 68 124 1 1180725472 45336688 14801136
09:17:41 15754 8192000000 10069775296 10516226048 1125810048 12.082 100 31 60 1 1168655648 36381872 6463728
09:19:18 1046 8192000000 10074065824 10487181312 1109038032 11.923 99 75 130 5 1160531584 39910496 11583056
09:20:21 17316 8192000000 10077719152 10507911168 1102295056 11.859 99 51 93 0 1151163312 38413824 10454432
09:21:47 150066 8192000000 10068970112 10516586496 1102605280 11.862 98 22 41 0 1169957344 62766656 4585408
09:25:18 36397 8192000000 10076560496 10521714688 1119488992 12.022 100 30 57 0 1164909024 39694144 5725888
09:26:54 97708 8192000000 10124684096 10565246976 1121537856 12.042 100 35 65 0 1236034352 107676992 6819504
09:29:33 44624 8192000000 10045201600 10506108928 1088837376 11.732 97 23 46 0 1137665392 44118240 4709776
09:31:18 33532 8192000000 10074692528 10509578240 1110840704 11.940 100 43 86 0 1182662976 63131808 8690464
09:32:55 33861 8192000000 10086490832 10538545152 1112430096 11.955 100 31 58 2 1162493184 43742064 6321024
09:34:31 23361 8192000000 10060692688 10505031680 1117817856 12.006 100 32 64 0 1171979696 47068144 7093696
09:36:08 16712 8192000000 10111839600 10537308160 1133587040 12.155 100 48 93 0 1195336512 52662848 9086624
09:37:26 28448 8192000000 10116353760 10567335936 1101975776 11.856 97 30 60 0 1206116192 97977168 6163248
09:38:55 48535 8192000000 10117262704 10607083520 1124898752 12.073 99 20 40 1 1225460208 96569920 3991536
09:40:44 28656 8192000000 10081418112 10512097280 1124379840 12.068 99 39 77 1 1158104064 26376528 7347696
09:42:14 103123 8192000000 10137432016 10602917888 1124906880 12.073 99 37 73 1 1242748784 110390944 7450960
09:46:30 56091 8192000000 10056141872 10514685952 1098784592 11.826 97 24 47 0 1126366800 22645872 4936336
09:48:26 16536 8192000000 10067474880 10500292608 1133177520 12.151 100 61 119 0 1160056576 14775680 12103376
09:49:48 23002 8192000000 10098175856 10518290432 1116114000 11.990 99 48 94 0 1186453296 60047792 10291504
09:51:13 35281 8192000000 10094390784 10539466752 1104969552 11.885 98 32 64 0 1187436928 75799616 6667760
09:52:54 10964 8192000000 10082925696 10512478208 1117184224 12.000 99 52 99 0 1169708080 42344192 10179664
09:54:06 65700 8192000000 10108541712 10549559296 1110175936 11.934 98 24 48 0 1176944704 61948672 4820096
09:56:14 66921 8192000000 10081458560 10550538240 1054872160 11.407 93 13 26 0 1122233216 64780224 2580832
09:58:27 48408 8192000000 10074118544 10556289024 1066703312 11.521 95 22 44 0 1130596832 59474208 4419312
10:00:16 109332 8192000000 10052421104 10496946176 1094607344 11.786 98 46 88 1 1146882400 41988048 10287008
10:03:44 52608 8192000000 10047592112 10521755648 1113280304 11.963 100 8 16 0 1163412928 48504384 1628240
10:05:40 42710 8192000000 10047979568 10494820352 1115940192 11.989 100 25 48 1 1148395744 27201264 5254288
10:07:25 43680 8192000000 10077559776 10531549184 1130408944 12.125 100 20 40 0 1191838976 57309888 4120144
10:09:31 100808 8192000000 10092025232 10585518080 1129597584 12.118 99 18 36 0 1212178096 78850272 3730240
10:12:12 39147 8192000000 10111289184 10574245888 1113240928 11.963 97 25 50 0 1195928832 76927968 5759936
10:13:53 71951 8192000000 10102903264 10580684800 1110055360 11.933 98 16 30 2 1193030016 79424960 3549696
10:16:07 28996 8192000000 10150353168 10584690688 1138055056 12.197 100 57 114 0 1241383488 91102224 12226208
10:17:53 62776 8192000000 10076537616 10558279680 1111028848 11.942 98 18 36 0 1144965760 30529104 3407808
10:20:02 27874 8192000000 10150998592 10594459648 1118505568 12.013 98 34 67 1 1219295328 93346896 7442864
10:21:31 51781 8192000000 10071924240 10504785920 1113182304 11.963 99 17 34 0 1161229392 45170768 2876320
10:23:29 14671 8192000000 10097682992 10541129728 1124111776 12.066 100 59 115 0 1220242112 84372240 11758096
10:24:45 40467 8192000000 10090096016 10511659008 1128680448 12.109 100 65 128 2 1185774080 44420016 12673616
10:26:26 47642 8192000000 10099021232 10561777664 1130611104 12.127 100 37 75 0 1200991520 63110192 7270224
10:28:15 87613 8192000000 10087783360 10563825664 1129421552 12.116 99 9 18 0 1177333600 45301360 2610688
10:30:47 60193 8192000000 10060535728 10557829120 1136052512 12.178 100 19 39 0 1183893328 43199360 4641456
10:32:49 63392 8192000000 10085851008 10545717248 1113745104 11.968 98 20 40 0 1183894544 65773552 4375888
10:35:04 14628 8192000000 10127622432 10553556992 1134044448 12.159 99 42 82 0 1210498288 67739392 8714448
10:36:19 43166 8192000000 10170048848 10617700352 1133071696 12.150 98 35 70 1 1258848896 118111808 7665392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-08-2022 10:39:01 to 09-08-2022 10:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-08-2022 10:39:18 to 09-08-2022 10:39:20.

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          220640
MON$OLDEST_ACTIVE               227949
MON$OLDEST_SNAPSHOT             227949
MON$NEXT_TRANSACTION            227949
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                       2046688
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Aug  9 10:37:27 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Aug  9 10:37:29 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Tue Aug  9 10:37:29 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

09.08.22 10:39:21. 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-08-09 10:39:24 No severe PSQL-related problems occured
09.08.22 10:39:25. End of report.