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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16121224 kB
MemAvailable 31311780 kB
Buffers 20 kB
Cached 15556556 kB
SwapCached 2448 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-07 04:37:19
DTS_END                         2022-06-07 04:37:19.2120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36578
TRN_ID                          224559
ATT_ID                          652
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: 9012.62 18819 1081514
customer order: creation 175.05 453 21006
customer order: refuse 14.47 398 1736
order to supplier: creation 45.66 1373 5479
order to supplier: removal 3.39 152731 407
invoice (draft): creation 46.70 3087 5604
invoice (draft): removal 48.94 934 5873
invoice accept: apply 43.58 26143 5229
invoice accept: cancel 44.38 1177 5326
customer reserve: creation 3965.33 257 475839
customer reserve: removal 4328.34 95 519401
realization accept: apply 84.91 243 10189
realization accept: cancel 77.64 160 9317
payment from customer: creation 61.30 158 7356
payment from customer: removal 5.85 153 702
payment to supplier: creation 50.59 174 6071
payment to supplier: removal 3.78 142 453
service: total inventory turnovers 4.03 3762 483
service: total monetary turnovers 7.68 437 921
service: aggregate perf. data 0.98 14612 117
service: refresh index statistics 0.04 169892 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-06-2022 04:38:58 to 07-06-2022 04:39:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6990
TEST_TIME 2 6634
TEST_TIME 3 6409
TEST_TIME 4 5980
TEST_TIME 5 6008
TEST_TIME 6 5887
TEST_TIME 7 5924
TEST_TIME 8 5964
TEST_TIME 9 6103
TEST_TIME 10 6608
TEST_TIME 11 6827
TEST_TIME 12 6936
TEST_TIME 13 6885
TEST_TIME 14 7125
TEST_TIME 15 7487
TEST_TIME 16 7513
TEST_TIME 17 7567
TEST_TIME 18 7663
TEST_TIME 19 7675
TEST_TIME 20 7678
TEST_TIME 21 7815
TEST_TIME 22 8008
TEST_TIME 23 8037
TEST_TIME 24 8589
TEST_TIME 25 8673
TEST_TIME 26 8876
TEST_TIME 27 9035
TEST_TIME 28 9378
TEST_TIME 29 9682
TEST_TIME 30 10194
TEST_TIME 31 10445
TEST_TIME 32 10915
TEST_TIME 33 11179
TEST_TIME 34 11112
TEST_TIME 35 11028
TEST_TIME 36 11016
TEST_TIME 37 10960
TEST_TIME 38 10740
TEST_TIME 39 10613
TEST_TIME 40 10413
TEST_TIME 41 10434
TEST_TIME 42 10389
TEST_TIME 43 10371
TEST_TIME 44 10157
TEST_TIME 45 10122
TEST_TIME 46 10095
TEST_TIME 47 10012
TEST_TIME 48 9972
TEST_TIME 49 10014
TEST_TIME 50 9910
TEST_TIME 51 9811
TEST_TIME 52 9975
TEST_TIME 53 9885
TEST_TIME 54 9812
TEST_TIME 55 9789
TEST_TIME 56 9821
TEST_TIME 57 9798
TEST_TIME 58 9788
TEST_TIME 59 9898
TEST_TIME 60 9839
TEST_TIME 61 9778
TEST_TIME 62 9766
TEST_TIME 63 9899
TEST_TIME 64 9972
TEST_TIME 65 9915
TEST_TIME 66 9895
TEST_TIME 67 9950
TEST_TIME 68 10015
TEST_TIME 69 9922
TEST_TIME 70 9847
TEST_TIME 71 9778
TEST_TIME 72 9735
TEST_TIME 73 9767
TEST_TIME 74 9745
TEST_TIME 75 9683
TEST_TIME 76 9635
TEST_TIME 77 9606
TEST_TIME 78 9565
TEST_TIME 79 9494
TEST_TIME 80 9500
TEST_TIME 81 9458
TEST_TIME 82 9416
TEST_TIME 83 9415
TEST_TIME 84 9370
TEST_TIME 85 9350
TEST_TIME 86 9317
TEST_TIME 87 9369
TEST_TIME 88 9440
TEST_TIME 89 9443
TEST_TIME 90 9407
TEST_TIME 91 9441
TEST_TIME 92 9508
TEST_TIME 93 9495
TEST_TIME 94 9505
TEST_TIME 95 9497
TEST_TIME 96 9447
TEST_TIME 97 9375
TEST_TIME 98 9367
TEST_TIME 99 9314
TEST_TIME 100 9261
TEST_TIME 101 9232
TEST_TIME 102 9238
TEST_TIME 103 9249
TEST_TIME 104 9232
TEST_TIME 105 9213
TEST_TIME 106 9202
TEST_TIME 107 9232
TEST_TIME 108 9212
TEST_TIME 109 9222
TEST_TIME 110 9251
TEST_TIME 111 9225
TEST_TIME 112 9275
TEST_TIME 113 9289
TEST_TIME 114 9233
TEST_TIME 115 9202
TEST_TIME 116 9162
TEST_TIME 117 9117
TEST_TIME 118 9078
TEST_TIME 119 9098
TEST_TIME 120 9037

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-06-2022 04:39:02 to 07-06-2022 04:39:05.

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 1520760 1520760 0 0.00 0 21377 14 0 0
doc_list_biud 1520747 1520747 0 0.00 0 93051 38 0 0
sp_add_invoice_to_stock 5376 5229 147 2.73 30 212452 26143 0 147
sp_cancel_adding_invoice 5326 5326 0 0.00 7 87609 1178 0 0
sp_cancel_client_order 1736 1736 0 0.00 6 9287 398 0 0
sp_cancel_customer_reserve 519401 519401 0 0.00 1 79237 95 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 4 15429 154 0 0
sp_cancel_pay_to_supplier 453 453 0 0.00 3 9002 142 0 0
sp_cancel_supplier_invoice 5884 5873 11 0.18 4 93064 934 0 11
sp_cancel_supplier_order 407 407 0 0.00 10 1451320 152732 0 0
sp_cancel_write_off 9317 9317 0 0.00 2 9317 160 0 0
sp_client_order 21006 21006 0 0.00 6 31300 453 0 0
sp_customer_reserve 475839 475839 0 0.00 1 56855 257 0 0
sp_fill_shopping_cart 32081 32081 0 0.00 1 20015 73 0 0
sp_fill_shopping_cart_clo_res 475815 475815 0 0.00 0 23261 19 0 0
sp_get_clo_for_invoice 5189 5189 0 0.00 0 28853 287 0 0
sp_kill_cost_storno 15798 15798 0 0.00 0 19748 68 0 0
sp_kill_qstorno_ret_qs2qd 1054399 1054399 0 0.00 0 93048 41 0 0
sp_kill_qty_storno 557435 557435 0 0.00 0 93050 95 0 0
sp_lock_selected_doc 21191 21191 0 0.00 0 7572 7 0 0
sp_make_cost_storno 28804 28804 0 0.00 0 15561 72 0 0
sp_multiply_rows_for_pdistr 28800 28800 0 0.00 0 8828 34 0 0
sp_multiply_rows_for_qdistr 21006 21006 0 0.00 0 10255 165 0 0
sp_pay_from_customer 7356 7356 0 0.00 6 14241 158 0 0
sp_pay_to_supplier 6071 6071 0 0.00 6 19283 175 0 0
sp_payment_common 13423 13423 0 0.00 2 7301 38 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 1 8051 301 0 0
sp_qd_handle_on_invoice_upd_sts 10515 10515 0 0.00 0 35724 470 0 0
sp_qd_handle_on_reserve_upd_sts 10186 10186 0 0.00 0 14962 52 0 0
sp_reserve_write_off 10189 10189 0 0.00 8 17304 243 0 0
sp_supplier_invoice 5604 5604 0 0.00 10 109678 3087 0 0
sp_supplier_order 5479 5479 0 0.00 8 35939 1374 0 0
srv_aggregate_perf_data 117 117 0 0.00 2618 126642 14613 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 924 196139 36671 0 0
srv_find_qd_qs_mism 1031820 1031820 0 0.00 0 21375 12 0 0
srv_make_invnt_saldo 483 483 0 0.00 46 67087 3763 0 0
srv_make_money_saldo 921 921 0 0.00 1 43083 438 0 0
srv_recalc_idx_stat 5 5 0 0.00 64790 278236 169892 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5232 5232 0 0.00 1 42314 828 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 1 34899 513 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 14461 37 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 1598 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 694 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 453 453 0 0.00 0 282 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 1109 32 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 76054 6233 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 4472 25 0 0
t$perf-norm:sp_client_order 21006 21006 0 0.00 0 14298 36 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 2316 13 0 0
t$perf-norm:sp_pay_from_customer 7356 7356 0 0.00 0 2001 15 0 0
t$perf-norm:sp_pay_to_supplier 6072 6072 0 0.00 0 42888 27 0 0
t$perf-norm:sp_reserve_write_off 10189 10189 0 0.00 0 13572 28 0 0
t$perf-norm:sp_supplier_invoice 5604 5604 0 0.00 0 15433 35 0 0
t$perf-norm:sp_supplier_order 5479 5479 0 0.00 0 14100 35 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 334 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 353 8 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 7021 27 0 0
t$perf-norm:srv_make_money_saldo 921 921 0 0.00 0 2365 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 188 57 0 0
v_add_invoice_to_stock 10668 10374 294 2.75 0 16682 26 0 294
v_all_customers 38488 38488 0 0.00 0 65 3 0 0
v_all_suppliers 30782 30782 0 0.00 0 141 4 0 0
v_all_wares 42007 42007 0 0.00 0 144 3 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 3548 17 0 0
v_cancel_client_order 3472 3472 0 0.00 0 8902 18 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 15346 22 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 846 9 0 0
v_cancel_payment_to_supplier 906 906 0 0.00 0 8905 23 0 0
v_cancel_supplier_invoice 810 788 22 2.71 0 912 20 0 22
v_cancel_supplier_order 814 814 0 0.00 0 802 10 0 0
v_cancel_write_off 2024 2024 0 0.00 0 3837 23 0 0
v_max_id_clo_ord 5477 5477 0 0.00 0 773 4 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 2794 15 0 0
v_max_id_ord_sup 5597 5597 0 0.00 0 3255 6 0 0
v_max_non_paid_invoice 1752 1752 0 0.00 0 864 7 0 0
v_max_non_paid_realizn 3694 3694 0 0.00 0 777 7 0 0
v_min_id_clo_ord 5477 5477 0 0.00 0 3594 7 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 5972 16 0 0
v_min_id_ord_sup 5597 5597 0 0.00 0 5804 9 0 0
v_min_non_paid_invoice 6070 6070 0 0.00 0 3599 10 0 0
v_min_non_paid_realizn 7353 7353 0 0.00 0 7298 14 0 0
v_our_firm 10834 10834 0 0.00 0 44 4 0 0
v_reserve_write_off 20372 20372 0 0.00 0 16758 11 0 0
x_lock_depdocs_on_canc_invoice 10805 10805 0 0.00 0 12750 117 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 20587 321 0 0
x_make_qstorno_1000_1200 5479 5479 0 0.00 2 35794 1223 0 0
x_make_qstorno_1200_2000 5604 5604 0 0.00 5 109652 2889 0 0
x_make_qstorno_2100_3300 475839 475839 0 0.00 0 56854 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-06-2022 04:39:06 to 07-06-2022 04:39:10.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:22 2 8192000000 8798038320 8809205760 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:24 22021 8192000000 9724028208 10023215104 801873600 8.915 100 85 170 1 830792928 7869952 21049376
02:09:50 17653 8192000000 9771297200 10078396416 838681120 9.287 100 88 176 0 872017312 12017584 21318608
02:11:15 14783 8192000000 9801897232 10121404416 871654096 9.617 100 85 168 0 909491776 17877152 19960528
02:12:35 10023 8192000000 9861074960 10193244160 917561328 10.072 100 82 161 0 974347984 39059472 17727184
02:13:48 3427 8192000000 9929195168 10270347264 954277216 10.433 100 87 171 2 1037955632 65591088 18087328
02:14:54 1247 8192000000 9970147984 10321145856 986577440 10.748 100 89 172 0 1090882528 86952288 17352800
02:15:57 1350 8192000000 9992442528 10355011584 1021703600 11.088 100 89 173 0 1112124096 73580416 16840080
02:17:00 1450 8192000000 10022686832 10394435584 1047602064 11.338 100 91 179 1 1141565520 75917744 18045712
02:18:03 1455 8192000000 10043460608 10419187712 1058794848 11.445 100 95 184 1 1166409088 88778800 18835440
02:19:05 1147 8192000000 10055382896 10436739072 1070699616 11.559 100 96 184 1 1177445632 87488480 19257536
02:20:08 1049 8192000000 10057487296 10437750784 1077584448 11.624 100 92 178 2 1180495840 84195792 18715600
02:21:10 1257 8192000000 10045558528 10430271488 1081832784 11.665 100 75 140 1 1164463904 69797104 12834016
02:22:12 3757 8192000000 10059316448 10447880192 1084210896 11.688 100 71 135 0 1184882560 87990832 12680832
02:23:17 1201 8192000000 10035548512 10426642432 1093322464 11.774 100 83 155 0 1157266592 47813376 16130752
02:24:20 1258 8192000000 10047406624 10431442944 1095197872 11.792 100 85 158 2 1166818240 56505632 15114736
02:25:22 1304 8192000000 10080770112 10468900864 1101620176 11.853 100 96 185 0 1203145968 82821008 18704784
02:26:25 1037 8192000000 10074219088 10462093312 1099748960 11.835 100 93 185 0 1195031552 76496576 18786016
02:27:27 1337 8192000000 10062086720 10451984384 1097386288 11.813 100 86 162 1 1185118752 70497488 17234976
02:28:29 1320 8192000000 10065374960 10454974464 1097345376 11.812 100 88 163 2 1186742848 71942160 17455312
02:29:32 3130 8192000000 10027204048 10421456896 1087424240 11.718 100 77 147 4 1156997824 53136384 16437200
02:30:36 1538 8192000000 10011328704 10397294592 1081125136 11.658 100 79 153 2 1137122192 40332256 15664800
02:31:38 1562 8192000000 10052082800 10437136384 1078165792 11.630 100 86 159 2 1172249680 77452560 16631328
02:32:44 1071 8192000000 10031860432 10404958208 1053827616 11.397 100 96 183 1 1149650480 78353360 17469504
02:33:46 1123 8192000000 10028064208 10404442112 1038819568 11.253 100 94 181 0 1149145680 91587376 18738736
02:34:48 961 8192000000 10006719456 10383147008 1044448224 11.307 100 88 171 3 1129560624 66239008 18873392
02:35:50 1103 8192000000 10004500944 10378694656 1033884128 11.206 100 95 182 1 1124619232 72174608 18560496
02:36:52 1166 8192000000 9985210320 10357534720 1038823056 11.253 100 93 179 1 1104905552 48380144 17702352
02:37:56 3529 8192000000 10020621056 10402521088 1067036368 11.524 100 92 177 0 1141340448 56775088 17528992
02:39:35 10361 8192000000 10053321376 10437677056 1084793328 11.693 100 71 139 2 1172092080 73598080 13700672
02:40:51 3188 8192000000 10080261008 10474037248 1098418336 11.823 100 79 145 2 1197095408 84116672 14560400
02:41:57 3971 8192000000 10106591536 10495528960 1112919344 11.960 100 78 154 0 1226961552 97530096 16512112
02:43:04 9575 8192000000 10104616800 10507644928 1113086560 11.962 100 70 131 2 1223361408 96583168 13691680
02:44:18 3924 8192000000 10160642480 10550374400 1113834544 11.969 99 74 141 2 1262673232 133726912 15111776
02:45:25 4040 8192000000 10194468672 10590126080 1134475136 12.164 100 71 134 1 1311572576 161076048 16021392
02:46:34 1000 8192000000 10243516704 10635472896 1149822336 12.308 100 92 178 1 1355843280 186599632 19421312
02:47:38 1195 8192000000 10278557984 10675458048 1156485552 12.370 100 91 178 0 1392900976 215998224 20417200
02:48:44 1199 8192000000 10299785200 10710097920 1168328448 12.481 100 82 157 0 1407488336 221838352 17321536
02:49:49 1465 8192000000 10303216992 10712481792 1174808272 12.542 100 77 138 0 1414335888 225527648 13999968
02:50:56 1441 8192000000 10341951712 10769825792 1182866048 12.617 100 96 184 1 1451635072 246677056 22091968
02:52:00 1727 8192000000 10356839664 10792321024 1184106608 12.628 100 97 189 2 1465718304 259995456 21616240
02:53:06 1007 8192000000 10382585552 10789703680 1190475952 12.688 100 95 187 1 1491402704 279290272 21636480
02:54:11 1128 8192000000 10383401488 10799497216 1176567488 12.558 99 95 188 1 1487367200 289699792 21099920
02:55:15 910 8192000000 10438187856 10851606528 1185266912 12.639 100 98 188 2 1542104432 334072112 22765408
02:56:17 2972 8192000000 10477815680 10902155264 1191129536 12.694 100 67 127 2 1585757696 378419056 16209104
02:57:34 2051 8192000000 10516432784 10929291264 1176437296 12.557 100 96 185 2 1615016656 416495040 22084320
02:58:40 2513 8192000000 10518782272 10945413120 1183609104 12.624 100 82 163 0 1631907968 428541760 19757104
02:59:46 4145 8192000000 10524345344 10969767936 1180179856 12.592 100 72 140 0 1634604752 436049808 18375088
03:00:54 10289 8192000000 10539743808 11002413056 1182452976 12.613 100 59 115 0 1634076256 436470576 15152704
03:02:12 2856 8192000000 10460155424 10941259776 1187538640 12.660 100 59 116 2 1562340464 360310832 14490992
03:03:20 3357 8192000000 10465971296 10899820544 1178453408 12.576 100 56 112 0 1571149568 378607776 14088384
03:04:32 15450 8192000000 10487641856 10935574528 1160979728 12.412 99 34 66 0 1573907472 403901024 9026720
03:06:07 22811 8192000000 10410375824 10873331712 1157377120 12.379 100 29 58 0 1513204960 347795760 8032080
03:07:33 31320 8192000000 10311754096 10883620864 1138638720 12.203 100 17 34 1 1432498400 289163952 4695728
03:09:18 83787 8192000000 10089758208 10596380672 1105067232 11.886 96 14 27 3 1168703920 60109792 3526896
03:11:42 31036 8192000000 10065216704 10506260480 1111917952 11.951 98 8 16 0 1146909904 33204672 1787280
03:13:31 99849 8192000000 10082886240 10553970688 1084288096 11.688 96 9 18 0 1162718592 76388496 2042000
03:16:55 196139 8192000000 10110585680 10562326528 1087010096 11.714 96 21 42 1 1136438784 45050096 4378592
03:21:25 23277 8192000000 10086763024 10523541504 1141089152 12.226 100 43 83 0 1197095792 47576544 8430096
03:22:50 25319 8192000000 10101880000 10572967936 1146680656 12.278 100 36 69 0 1220662720 66776848 7205216
03:24:20 4296 8192000000 10134088656 10564849664 1146211360 12.274 100 65 124 2 1230197968 70486320 13500288
03:25:26 31992 8192000000 10135426704 10601742336 1123551600 12.061 99 13 26 1 1227276336 100407552 3317184
03:26:58 57717 8192000000 10118958704 10573852672 1108636368 11.920 97 10 20 0 1197971744 87340816 1994560
03:28:59 37399 8192000000 10106279072 10621722624 1120554960 12.032 99 18 36 3 1230593904 106205920 3833024
03:30:37 25339 8192000000 10046534304 10511380480 1108106768 11.914 99 20 39 1 1140787888 28475296 4205824
03:32:03 79837 8192000000 10071394064 10536140800 1108496480 11.918 99 13 26 1 1182277888 71067952 2713456
03:34:33 6233 8192000000 10069197696 10504044544 1121096864 12.037 100 49 94 2 1175842112 44460880 10284368
03:35:41 86563 8192000000 10044243280 10498637824 1084073616 11.686 96 19 31 2 1172208880 84448992 3686272
03:38:12 17688 8192000000 10108173392 10532466688 1115939440 11.989 99 58 117 0 1184083616 56739936 11404240
03:39:31 57674 8192000000 10078166752 10582863872 1126097600 12.085 99 28 56 1 1231125104 99260352 5767152
03:41:51 46266 8192000000 10075874560 10510999552 1114624608 11.976 98 28 55 2 1153478592 32642832 6211152
03:43:43 122312 8192000000 10083271168 10576859136 1111282032 11.945 97 20 40 2 1190209168 75263200 3663936
03:46:46 99044 8192000000 10115423952 10578788352 1107969728 11.913 98 43 86 0 1175136288 57016896 10149664
03:49:29 59035 8192000000 10073074112 10562191360 1099787408 11.836 99 28 56 0 1186262576 80493952 5981216
03:51:29 46957 8192000000 10061305632 10522828800 1126336352 12.087 100 17 34 0 1163586160 33412944 3836864
03:53:26 59546 8192000000 10059781712 10514554880 1087339872 11.717 97 14 28 1 1144161616 53724800 3096944
03:55:34 80995 8192000000 10081796416 10547212288 1124483568 12.069 100 27 54 0 1188483520 57887264 6112688
03:58:13 49888 8192000000 10038755408 10507055104 1104333552 11.879 98 7 14 0 1153544832 47735792 1475488
04:00:03 64918 8192000000 10062910144 10537549824 1092440064 11.766 97 8 14 1 1142172048 48189040 1542944
04:02:08 22605 8192000000 10096364400 10539393024 1122179120 12.048 99 26 52 1 1190662016 62803232 5679664
04:03:39 36935 8192000000 10161921632 10613768192 1108182576 11.915 98 45 90 2 1247160752 129576928 9401248
04:05:46 38087 8192000000 10072996144 10519314432 1112958448 11.960 99 23 46 0 1200481360 82236288 5286624
04:07:25 14607 8192000000 10132585056 10564505600 1111964432 11.951 98 42 84 1 1209184448 88661792 8558224
04:08:45 50092 8192000000 10153996656 10599084032 1118279216 12.011 99 51 102 0 1235003760 106691744 10032800
04:10:36 25048 8192000000 10111694720 10536800256 1123773360 12.063 99 54 108 0 1198714624 64648864 10292400
04:12:01 9762 8192000000 10118283328 10541993984 1134880416 12.167 100 61 119 1 1216814048 70209744 11723888
04:13:13 95153 8192000000 10082401184 10517848064 1105090352 11.886 97 36 71 0 1161354848 48812592 7451904
04:15:53 44061 8192000000 10054086480 10509594624 1101432096 11.851 97 18 36 2 1151366880 46436400 3498384
04:17:44 74937 8192000000 10062724400 10528706560 1090853296 11.751 97 8 16 0 1144226224 51830624 1542304
04:19:59 66298 8192000000 10064147760 10498060288 1105954336 11.894 99 20 40 2 1158971248 48500752 4516160
04:22:05 21914 8192000000 10074913488 10518937600 1113498800 11.966 99 26 52 2 1156463856 36645712 6319344
04:23:28 31440 8192000000 10087766176 10525822976 1079240608 11.640 96 29 58 1 1150032176 65191152 5600416
04:24:59 17255 8192000000 10090800320 10522075136 1118096736 12.009 100 49 98 3 1181921376 53969888 9854752
04:26:17 50017 8192000000 10112740704 10571067392 1117197904 12.001 100 38 76 2 1211541536 86548000 7795632
04:28:12 47397 8192000000 10127174928 10560004096 1135308928 12.171 100 35 70 1 1215320960 72862576 7149456
04:30:02 11484 8192000000 10110934144 10543837184 1131596352 12.136 100 51 100 0 1203558672 62280496 9681824
04:31:16 110093 8192000000 10048693616 10508599296 1131614880 12.137 100 22 44 1 1171602288 35523680 4463728
04:35:00 159031 8192000000 9213501520 9527693312 356690384 4.172 32 2 2 1 373600496 16596560 313552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-06-2022 04:39:12 to 07-06-2022 04:39:28.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-06-2022 04:39:29 to 07-06-2022 04:39:31.

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          224803
MON$OLDEST_ACTIVE               224804
MON$OLDEST_SNAPSHOT             224804
MON$NEXT_TRANSACTION            224804
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                       2058912
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
--- end of diff output ---

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

07.06.22 04:39:32. 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-07 04:39:35 No severe PSQL-related problems occured
07.06.22 04:39:36. End of report.