Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.07.2022 04:39
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 15577828 kB
MemAvailable 31025708 kB
Buffers 20 kB
Cached 16100040 kB
SwapCached 2516 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-07-28 04:37:21
DTS_END                         2022-07-28 04:37:21.2770
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/53250
TRN_ID                          216012
ATT_ID                          643
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: 9080.20 21014 1089624
customer order: creation 168.52 490 20222
customer order: refuse 14.24 404 1709
order to supplier: creation 43.63 1399 5236
order to supplier: removal 3.27 201483 392
invoice (draft): creation 45.23 3088 5428
invoice (draft): removal 53.06 1084 6367
invoice accept: apply 40.04 27824 4805
invoice accept: cancel 47.47 1274 5696
customer reserve: creation 3741.32 265 448958
customer reserve: removal 4633.70 113 556044
realization accept: apply 80.55 279 9666
realization accept: cancel 85.06 196 10207
payment from customer: creation 56.41 184 6769
payment from customer: removal 5.43 117 651
payment to supplier: creation 46.38 193 5565
payment to supplier: removal 3.18 131 381
service: total inventory turnovers 4.22 3825 506
service: total monetary turnovers 7.53 435 903
service: aggregate perf. data 0.96 13164 115
service: refresh index statistics 0.03 164327 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-07-2022 04:39:49 to 28-07-2022 04:39:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7261
TEST_TIME 2 7224
TEST_TIME 3 6814
TEST_TIME 4 6642
TEST_TIME 5 6279
TEST_TIME 6 5920
TEST_TIME 7 5708
TEST_TIME 8 5779
TEST_TIME 9 6691
TEST_TIME 10 6620
TEST_TIME 11 6747
TEST_TIME 12 6766
TEST_TIME 13 6751
TEST_TIME 14 7079
TEST_TIME 15 7012
TEST_TIME 16 6948
TEST_TIME 17 7086
TEST_TIME 18 6998
TEST_TIME 19 7007
TEST_TIME 20 7149
TEST_TIME 21 7371
TEST_TIME 22 7436
TEST_TIME 23 7622
TEST_TIME 24 7879
TEST_TIME 25 8091
TEST_TIME 26 8120
TEST_TIME 27 8021
TEST_TIME 28 8260
TEST_TIME 29 8749
TEST_TIME 30 9225
TEST_TIME 31 9512
TEST_TIME 32 10162
TEST_TIME 33 10438
TEST_TIME 34 10918
TEST_TIME 35 10980
TEST_TIME 36 10731
TEST_TIME 37 10626
TEST_TIME 38 10617
TEST_TIME 39 10434
TEST_TIME 40 10332
TEST_TIME 41 10207
TEST_TIME 42 10015
TEST_TIME 43 9850
TEST_TIME 44 9922
TEST_TIME 45 9806
TEST_TIME 46 9691
TEST_TIME 47 9744
TEST_TIME 48 9759
TEST_TIME 49 9679
TEST_TIME 50 9636
TEST_TIME 51 9521
TEST_TIME 52 9467
TEST_TIME 53 9359
TEST_TIME 54 9310
TEST_TIME 55 9406
TEST_TIME 56 9414
TEST_TIME 57 9831
TEST_TIME 58 9791
TEST_TIME 59 9759
TEST_TIME 60 9711
TEST_TIME 61 9758
TEST_TIME 62 9708
TEST_TIME 63 9659
TEST_TIME 64 9804
TEST_TIME 65 9757
TEST_TIME 66 9806
TEST_TIME 67 9823
TEST_TIME 68 9747
TEST_TIME 69 9698
TEST_TIME 70 9573
TEST_TIME 71 9535
TEST_TIME 72 9455
TEST_TIME 73 9465
TEST_TIME 74 9417
TEST_TIME 75 9395
TEST_TIME 76 9407
TEST_TIME 77 9372
TEST_TIME 78 9316
TEST_TIME 79 9288
TEST_TIME 80 9248
TEST_TIME 81 9211
TEST_TIME 82 9189
TEST_TIME 83 9189
TEST_TIME 84 9148
TEST_TIME 85 9122
TEST_TIME 86 9080
TEST_TIME 87 9195
TEST_TIME 88 9172
TEST_TIME 89 9230
TEST_TIME 90 9263
TEST_TIME 91 9293
TEST_TIME 92 9290
TEST_TIME 93 9310
TEST_TIME 94 9313
TEST_TIME 95 9272
TEST_TIME 96 9190
TEST_TIME 97 9233
TEST_TIME 98 9210
TEST_TIME 99 9168
TEST_TIME 100 9109
TEST_TIME 101 9042
TEST_TIME 102 9051
TEST_TIME 103 9024
TEST_TIME 104 9039
TEST_TIME 105 9065
TEST_TIME 106 9046
TEST_TIME 107 9008
TEST_TIME 108 8987
TEST_TIME 109 9005
TEST_TIME 110 9007
TEST_TIME 111 8979
TEST_TIME 112 8976
TEST_TIME 113 9029
TEST_TIME 114 9122
TEST_TIME 115 9247
TEST_TIME 116 9257
TEST_TIME 117 9191
TEST_TIME 118 9195
TEST_TIME 119 9147
TEST_TIME 120 9101

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-07-2022 04:39:53 to 28-07-2022 04:39:56.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1503274 1503274 0 0.00 0 36168 14 0 0
doc_list_biud 1503267 1503267 0 0.00 0 99359 48 0 0
sp_add_invoice_to_stock 4904 4805 99 2.01 47 230731 27824 0 99
sp_cancel_adding_invoice 5696 5696 0 0.00 7 103083 1275 0 0
sp_cancel_client_order 1709 1709 0 0.00 5 11531 405 0 0
sp_cancel_customer_reserve 556044 556044 0 0.00 1 63256 113 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 4 2652 117 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 4 5541 132 0 0
sp_cancel_supplier_invoice 6373 6367 6 0.09 3 30966 1085 0 6
sp_cancel_supplier_order 392 392 0 0.00 15 1407100 201483 0 0
sp_cancel_write_off 10207 10207 0 0.00 2 99603 197 0 0
sp_client_order 20222 20222 0 0.00 5 36461 491 0 0
sp_customer_reserve 448958 448958 0 0.00 0 98209 266 0 0
sp_fill_shopping_cart 30876 30876 0 0.00 1 12213 79 0 0
sp_fill_shopping_cart_clo_res 448941 448941 0 0.00 0 18807 19 0 0
sp_get_clo_for_invoice 4779 4779 0 0.00 0 26723 296 0 0
sp_kill_cost_storno 16935 16935 0 0.00 0 30717 71 0 0
sp_kill_qstorno_ret_qs2qd 1129054 1129054 0 0.00 0 99348 50 0 0
sp_kill_qty_storno 594857 594857 0 0.00 0 99354 112 0 0
sp_lock_selected_doc 19976 19976 0 0.00 0 11158 8 0 0
sp_make_cost_storno 26778 26778 0 0.00 0 34633 82 0 0
sp_multiply_rows_for_pdistr 26778 26778 0 0.00 0 12455 40 0 0
sp_multiply_rows_for_qdistr 20222 20222 0 0.00 0 20971 187 0 0
sp_pay_from_customer 6769 6769 0 0.00 5 14348 185 0 0
sp_pay_to_supplier 5565 5565 0 0.00 6 13451 194 0 0
sp_payment_common 12334 12334 0 0.00 2 13390 51 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 0 7270 322 0 0
sp_qd_handle_on_invoice_upd_sts 10473 10473 0 0.00 0 27381 518 0 0
sp_qd_handle_on_reserve_upd_sts 9665 9665 0 0.00 0 13862 61 0 0
sp_reserve_write_off 9666 9666 0 0.00 7 17469 279 0 0
sp_supplier_invoice 5428 5428 0 0.00 7 90389 3088 0 0
sp_supplier_order 5236 5236 0 0.00 7 38505 1399 0 0
srv_aggregate_perf_data 115 115 0 0.00 2383 177596 13164 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 882 111537 33240 0 0
srv_find_qd_qs_mism 1042392 1042392 0 0.00 0 36164 11 0 0
srv_make_invnt_saldo 506 506 0 0.00 62 57655 3826 0 0
srv_make_money_saldo 903 903 0 0.00 1 36246 436 0 0
srv_recalc_idx_stat 4 4 0 0.00 75887 289398 164327 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4808 4808 0 0.00 1 22451 935 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 1 5317 432 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 10132 34 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 1211 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 3634 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 1169 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 660 28 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 93174 6852 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 7520 32 0 0
t$perf-norm:sp_client_order 20222 20222 0 0.00 0 10987 37 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 5635 31 0 0
t$perf-norm:sp_pay_from_customer 6769 6769 0 0.00 0 13591 23 0 0
t$perf-norm:sp_pay_to_supplier 5565 5565 0 0.00 0 10391 25 0 0
t$perf-norm:sp_reserve_write_off 9666 9666 0 0.00 0 6336 24 0 0
t$perf-norm:sp_supplier_invoice 5428 5428 0 0.00 0 11070 37 0 0
t$perf-norm:sp_supplier_order 5236 5236 0 0.00 0 5849 32 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 443 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 1693 29 0 0
t$perf-norm:srv_make_invnt_saldo 506 506 0 0.00 0 1660 20 0 0
t$perf-norm:srv_make_money_saldo 903 903 0 0.00 0 2607 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 193 73 0 0
v_add_invoice_to_stock 9750 9552 198 2.03 0 5444 27 0 198
v_all_customers 36958 36958 0 0.00 0 69 4 0 0
v_all_suppliers 29524 29524 0 0.00 0 129 4 0 0
v_all_wares 40444 40444 0 0.00 0 63 4 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 480 15 0 0
v_cancel_client_order 3418 3418 0 0.00 0 5269 11 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 2563 16 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 1896 10 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 1342 15 0 0
v_cancel_supplier_invoice 804 792 12 1.49 0 1510 26 0 12
v_cancel_supplier_order 784 784 0 0.00 0 709 12 0 0
v_cancel_write_off 1892 1892 0 0.00 0 2944 25 0 0
v_max_id_clo_ord 5235 5235 0 0.00 0 532 5 0 0
v_max_id_clo_res 963 963 0 0.00 0 4678 22 0 0
v_max_id_ord_sup 5419 5419 0 0.00 0 3079 6 0 0
v_max_non_paid_invoice 1457 1457 0 0.00 0 1240 9 0 0
v_max_non_paid_realizn 3434 3434 0 0.00 0 952 8 0 0
v_min_id_clo_ord 5235 5235 0 0.00 0 948 7 0 0
v_min_id_clo_res 963 963 0 0.00 0 4183 25 0 0
v_min_id_ord_sup 5419 5419 0 0.00 0 4413 9 0 0
v_min_non_paid_invoice 5565 5565 0 0.00 0 8003 18 0 0
v_min_non_paid_realizn 6768 6768 0 0.00 0 13212 24 0 0
v_our_firm 10156 10156 0 0.00 0 53 5 0 0
v_reserve_write_off 19328 19328 0 0.00 0 2570 10 0 0
x_lock_depdocs_on_canc_invoice 11667 11667 0 0.00 0 18282 130 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 12449 381 0 0
x_make_qstorno_1000_1200 5236 5236 0 0.00 2 38489 1235 0 0
x_make_qstorno_1200_2000 5428 5428 0 0.00 3 90367 2879 0 0
x_make_qstorno_2100_3300 448958 448958 0 0.00 0 98201 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-07-2022 04:39:57 to 28-07-2022 04:40:01.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:23 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:24 21597 8192000000 9713462464 10011033600 788393520 8.779 100 84 168 0 816993504 7836560 20763424
02:09:53 29178 8192000000 9772595936 10081726464 843618784 9.336 100 88 176 0 878647680 14373696 20655200
02:11:30 13521 8192000000 9813554576 10136883200 878420128 9.684 100 84 168 0 923778752 26087040 19271584
02:12:48 5893 8192000000 9867857456 10204008448 917677616 10.073 100 85 164 0 981807680 45677824 18452240
02:13:59 4130 8192000000 9914967312 10256400384 957740832 10.467 100 95 189 0 1033633184 55583024 20309328
02:15:07 1767 8192000000 9973089552 10326278144 1000763584 10.886 100 93 184 0 1086747744 67232864 18751296
02:16:10 1813 8192000000 10008133648 10375086080 1014882432 11.023 100 88 172 0 1124261712 91971760 17407520
02:17:14 7967 8192000000 9993612528 10364391424 1034485952 11.212 100 77 143 2 1115418544 67577952 13354640
02:18:23 1015 8192000000 10031261920 10408611840 1054732352 11.406 100 94 182 0 1153703952 80450192 18521408
02:19:25 1236 8192000000 10047165920 10429521920 1064313776 11.498 100 94 181 2 1170696272 86762400 19620096
02:20:29 973 8192000000 10056503792 10439106560 1072405264 11.575 100 94 183 0 1179968128 88926240 18636624
02:21:31 943 8192000000 10037788336 10421002240 1074445792 11.595 100 93 163 1 1158858944 68150256 16262896
02:22:34 1080 8192000000 10043285600 10428637184 1078961216 11.638 100 92 180 1 1167177584 69146976 19069392
02:23:36 1454 8192000000 10043109808 10431721472 1080444752 11.652 100 94 185 1 1167462272 69240128 17777392
02:24:39 1130 8192000000 10052143824 10442797056 1084303984 11.688 100 92 167 0 1172755568 71165024 17286560
02:25:43 983 8192000000 10071726112 10459086848 1084913344 11.694 100 91 170 0 1191565776 89629856 17022576
02:26:45 2740 8192000000 10048011552 10434932736 1091306544 11.755 100 83 159 1 1173499920 66971744 15221632
02:27:49 1052 8192000000 10072212288 10460061696 1091494080 11.757 100 94 173 0 1192508032 83013040 18000912
02:28:51 968 8192000000 10057989344 10447118336 1096348848 11.803 100 92 171 2 1181127872 66524688 18254336
02:29:53 1110 8192000000 10073761248 10465812480 1087165984 11.716 99 85 168 0 1195807376 91035360 17606032
02:30:56 1216 8192000000 10048376160 10434625536 1080224448 11.650 100 94 181 1 1170151648 71921680 18005520
02:31:58 1243 8192000000 10024456224 10406531072 1069529936 11.548 100 94 173 1 1148704976 60430032 18745008
02:33:00 962 8192000000 9997093680 10377662464 1037414336 11.240 99 91 172 1 1117432976 61505056 18513584
02:34:02 752 8192000000 10007507504 10385817600 1049876448 11.359 100 90 170 4 1129102464 59247744 19978272
02:35:04 1177 8192000000 10016261824 10391027712 1047402992 11.336 100 86 160 1 1136955296 72817664 16734640
02:36:07 1619 8192000000 9988974112 10371969024 1056432224 11.422 100 80 150 3 1113817376 41613392 15771760
02:37:09 13487 8192000000 9987009008 10382266368 1048129568 11.343 99 54 102 0 1109865568 51514592 10221408
02:38:29 19210 8192000000 10030642416 10419777536 1079706848 11.645 100 65 129 0 1151465056 58999216 12758992
02:39:53 18530 8192000000 10030660992 10416267264 1069685568 11.549 98 64 122 4 1137599568 55341792 12572208
02:41:16 1205 8192000000 10065590528 10450300928 1103130640 11.867 100 94 179 2 1184505616 62134992 19239984
02:42:20 1202 8192000000 10080618944 10471440384 1120595184 12.033 100 80 146 3 1200343296 64229088 15519024
02:43:25 3726 8192000000 10140841472 10532683776 1133114768 12.151 100 74 142 0 1251339248 103276864 14947616
02:44:34 5519 8192000000 10168191184 10570088448 1133890544 12.158 100 74 136 2 1282822336 134426144 14505648
02:45:45 874 8192000000 10206688688 10611691520 1146530288 12.277 100 91 175 2 1321727008 156867680 18329040
02:46:49 1800 8192000000 10211514448 10631102464 1154011856 12.347 100 80 160 0 1328877408 157744016 17121536
02:47:55 1127 8192000000 10230799776 10638557184 1167642528 12.475 100 86 167 0 1342649440 156971696 18035216
02:48:58 1137 8192000000 10284021296 10690191360 1178127168 12.573 100 89 170 1 1393707632 194401184 21179280
02:50:02 1267 8192000000 10318558528 10716033024 1176196800 12.555 100 94 183 1 1429808496 232339968 21271728
02:51:06 1188 8192000000 10362295680 10782035968 1190671760 12.690 100 95 189 0 1471051808 256851504 23528544
02:52:11 6164 8192000000 10363273664 10777292800 1194214672 12.723 100 79 156 0 1472546784 260352912 17979200
02:53:21 1001 8192000000 10420102672 10827571200 1203368560 12.808 100 94 179 1 1531688944 307978256 20342128
02:54:25 1466 8192000000 10453737200 10872299520 1206268528 12.835 100 92 180 0 1559528448 330838816 22421104
02:55:31 2169 8192000000 10514825024 10938277888 1217229088 12.936 100 87 172 1 1624077568 385995680 20852800
02:56:37 910 8192000000 10584324240 10994962432 1226299232 13.020 100 95 187 0 1690314192 440614352 23400608
02:57:39 2179 8192000000 10566933248 11020148736 1220111232 12.963 100 94 184 0 1673981008 429540288 24329488
02:58:43 913 8192000000 10575906000 11042091008 1202496704 12.800 100 99 195 0 1679416080 453524432 23394944
02:59:47 1043 8192000000 10599939568 11028561920 1194523568 12.725 100 96 187 0 1699657296 483458544 21675184
03:00:50 3918 8192000000 10595475536 11074555904 1198323456 12.761 100 80 160 0 1699740096 480847872 20568768
03:02:03 4429 8192000000 10635999776 11074478080 1205075776 12.823 100 65 130 0 1741620160 519394384 17150000
03:03:15 7114 8192000000 10637015120 11090784256 1200997936 12.786 100 50 100 1 1734244112 520239616 13006560
03:04:38 7220 8192000000 10647908416 11090190336 1194937520 12.729 100 55 110 1 1750814240 541555760 14320960
03:05:51 23774 8192000000 10556864080 11077799936 1157801248 12.383 98 31 62 0 1694833120 528202784 8829088
03:07:20 27220 8192000000 10448193184 10935238656 1153621712 12.343 100 29 56 0 1564824368 404346912 6855744
03:08:50 27558 8192000000 10352680528 10874322944 1162253280 12.424 100 28 56 0 1519536032 349897200 7385552
03:10:21 32512 8192000000 10212333728 10651754496 1143054464 12.244 99 31 60 2 1276766384 127285584 6426336
03:12:02 85273 8192000000 10062166400 10517934080 1100229760 11.840 96 12 24 0 1140573312 37718000 2625552
03:14:40 111537 8192000000 10056294608 10526355456 1064468480 11.499 94 16 32 0 1119327456 51962480 2896496
03:17:34 56237 8192000000 10074641904 10518462464 1093909008 11.780 97 24 48 0 1136992336 38041888 5041440
03:19:50 57795 8192000000 10101970160 10536308736 1078610176 11.634 96 41 78 0 1153827184 66922272 8294736
03:21:50 110498 8192000000 10112595696 10529726464 1091166960 11.754 97 39 78 0 1149570016 49716800 8686256
03:24:41 40962 8192000000 10131266560 10587271168 1113816960 11.969 98 23 46 1 1221083840 102175552 5091328
03:26:27 5156 8192000000 10154130784 10576666624 1143094880 12.245 100 72 137 1 1243550656 85007360 15448416
03:27:41 70808 8192000000 10229204240 10675302400 1137036544 12.188 98 23 46 0 1293469088 150769120 5663424
03:30:03 41006 8192000000 10285052400 10729156608 1148387152 12.294 99 27 54 0 1364514864 209384032 6743680
03:32:19 44401 8192000000 10264806320 10790064128 1135086448 12.169 99 28 56 0 1392036016 249773664 7175904
03:34:16 80375 8192000000 10099944112 10534866944 1135316320 12.171 100 21 42 0 1216560976 76510144 4734512
03:36:38 35786 8192000000 10087938528 10513522688 1126107904 12.085 98 52 100 0 1177752144 41376464 10267776
03:38:16 38130 8192000000 10097644192 10563665920 1152840640 12.336 100 23 46 0 1216155312 58820400 4494272
03:40:06 34454 8192000000 10122462512 10566414336 1131376928 12.134 99 31 62 0 1227112656 89562416 6173312
03:42:29 30970 8192000000 10122534992 10556043264 1119858592 12.026 97 30 60 1 1180777552 54851840 6067120
03:44:04 44411 8192000000 10082589744 10550353920 1125253664 12.077 98 16 32 0 1186505424 57761856 3489904
03:45:54 15663 8192000000 10104898560 10529406976 1127986704 12.102 99 52 104 0 1180439792 42337328 10115760
03:47:21 86963 8192000000 10143614512 10589757440 1124284752 12.067 99 48 96 0 1246047760 112395568 9367440
03:49:52 57735 8192000000 10125103600 10607652864 1113566608 11.966 97 32 64 1 1169019040 47719984 7732448
03:52:11 47708 8192000000 10116022576 10592559104 1130270832 12.124 100 23 46 0 1216103792 80486144 5346816
03:53:59 59490 8192000000 10080673632 10559266816 1127322640 12.096 100 17 34 0 1179707968 48693408 3691920
03:56:01 68816 8192000000 10056371600 10507902976 1079765712 11.645 96 14 27 0 1125340240 43051872 2522656
03:58:44 58812 8192000000 10062634656 10518638592 1116570640 11.995 99 9 18 0 1145321408 26392320 2358448
04:00:43 18608 8192000000 10069950832 10502742016 1119350192 12.021 99 47 90 0 1166270880 37704160 9216528
04:02:06 18712 8192000000 10153301904 10576179200 1137006784 12.187 100 55 110 0 1239184272 90871120 11306368
04:03:28 28179 8192000000 10155558688 10587455488 1106034928 11.895 98 32 64 0 1241275104 127977008 7263168
04:04:59 41395 8192000000 10107132400 10549489664 1102277760 11.859 99 28 56 0 1199134944 91072832 5784352
04:06:45 42670 8192000000 10057600208 10479943680 1069274256 11.545 96 38 74 0 1140646896 64088864 7283776
04:08:30 46893 8192000000 10106395520 10545700864 1104330192 11.879 99 41 82 1 1184326368 71910688 8085488
04:10:18 11452 8192000000 10116557600 10539659264 1122345120 12.049 100 54 106 0 1204835952 71718752 10772080
04:11:35 49605 8192000000 10128270432 10560651264 1129414256 12.116 100 37 73 0 1215138128 78454528 7269344
04:13:25 84852 8192000000 10070429952 10539761664 1128643840 12.109 100 40 72 2 1194063424 57354032 8065552
04:15:52 76069 8192000000 10070715872 10525556736 1119627536 12.023 99 16 31 0 1177116480 54325552 3163392
04:18:10 63381 8192000000 10118128864 10600640512 1126818384 12.091 99 14 28 0 1220581920 90957280 2806256
04:20:19 88915 8192000000 10121140128 10597740544 1124643488 12.071 99 21 41 0 1219239680 90090176 4506016
04:22:53 35922 8192000000 10138563408 10584477696 1135435280 12.173 99 45 90 0 1227382864 82454624 9492960
04:24:29 21658 8192000000 10194070080 10623340544 1155578592 12.362 100 46 88 1 1286863280 120849056 10435632
04:26:12 35834 8192000000 10227660512 10700288000 1162818128 12.430 100 40 78 0 1317928048 146140464 8969456
04:27:51 26396 8192000000 10236583296 10669752320 1154984720 12.356 99 40 76 2 1324569712 160802448 8782544
04:29:19 45712 8192000000 10296983632 10774532096 1151893552 12.327 98 25 50 0 1366036336 207708128 6434656
04:31:05 28005 8192000000 10236295584 10752925696 1142415584 12.238 99 18 36 0 1340067728 192879616 4772528
04:32:38 49415 8192000000 10079467840 10565402624 1120467104 12.031 98 10 20 0 1178652432 56080416 2104912
04:34:33 53023 8192000000 10073641920 10527477760 1128615920 12.108 99 31 58 0 1169504640 34502480 6386240
04:36:29 56679 8192000000 9963372048 10442199040 1055410464 11.413 92 6 12 0 1088272224 31848672 1013088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-07-2022 04:40:03 to 28-07-2022 04:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-07-2022 04:40:20 to 28-07-2022 04:40:22.

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          216012
MON$OLDEST_ACTIVE               216313
MON$OLDEST_SNAPSHOT             216313
MON$NEXT_TRANSACTION            216313
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                       2080992
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

28.07.22 04:40:23. 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-07-28 04:40:26 No severe PSQL-related problems occured
28.07.22 04:40:27. End of report.