Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.07.2022 04:37
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 15866296 kB
MemAvailable 31066824 kB
Buffers 20 kB
Cached 15743908 kB
SwapCached 2540 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-07 04:37:17
DTS_END                         2022-07-07 04:37:17.9140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43390
TRN_ID                          219881
ATT_ID                          664
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: 9310.27 24362 1117232
customer order: creation 164.50 475 19740
customer order: refuse 13.47 369 1616
order to supplier: creation 43.86 1377 5263
order to supplier: removal 3.38 162676 405
invoice (draft): creation 44.96 2984 5395
invoice (draft): removal 52.94 901 6353
invoice accept: apply 41.18 24571 4941
invoice accept: cancel 48.29 1084 5795
customer reserve: creation 3824.33 234 458920
customer reserve: removal 4777.03 92 573244
realization accept: apply 81.42 248 9770
realization accept: cancel 88.31 160 10597
payment from customer: creation 57.46 167 6895
payment from customer: removal 5.67 112 680
payment to supplier: creation 47.99 187 5759
payment to supplier: removal 3.52 115 422
service: total inventory turnovers 3.67 3546 440
service: total monetary turnovers 7.45 325 894
service: aggregate perf. data 0.83 14894 99
service: refresh index statistics 0.03 272732 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-07-2022 04:37:58 to 07-07-2022 04:38:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6360
TEST_TIME 2 6028
TEST_TIME 3 5250
TEST_TIME 4 5330
TEST_TIME 5 6053
TEST_TIME 6 6001
TEST_TIME 7 5700
TEST_TIME 8 5470
TEST_TIME 9 5840
TEST_TIME 10 6072
TEST_TIME 11 6202
TEST_TIME 12 6600
TEST_TIME 13 6776
TEST_TIME 14 6941
TEST_TIME 15 7071
TEST_TIME 16 7090
TEST_TIME 17 7322
TEST_TIME 18 7829
TEST_TIME 19 7986
TEST_TIME 20 7840
TEST_TIME 21 7874
TEST_TIME 22 7709
TEST_TIME 23 8000
TEST_TIME 24 8089
TEST_TIME 25 8653
TEST_TIME 26 8707
TEST_TIME 27 9133
TEST_TIME 28 10019
TEST_TIME 29 10217
TEST_TIME 30 10490
TEST_TIME 31 11024
TEST_TIME 32 11019
TEST_TIME 33 10845
TEST_TIME 34 10839
TEST_TIME 35 10606
TEST_TIME 36 10632
TEST_TIME 37 10560
TEST_TIME 38 10440
TEST_TIME 39 10273
TEST_TIME 40 10309
TEST_TIME 41 10190
TEST_TIME 42 10227
TEST_TIME 43 10107
TEST_TIME 44 10009
TEST_TIME 45 9907
TEST_TIME 46 9802
TEST_TIME 47 9653
TEST_TIME 48 9807
TEST_TIME 49 9744
TEST_TIME 50 10086
TEST_TIME 51 10224
TEST_TIME 52 10226
TEST_TIME 53 10108
TEST_TIME 54 10014
TEST_TIME 55 9969
TEST_TIME 56 10314
TEST_TIME 57 10303
TEST_TIME 58 10283
TEST_TIME 59 10235
TEST_TIME 60 10212
TEST_TIME 61 10158
TEST_TIME 62 10002
TEST_TIME 63 9981
TEST_TIME 64 9891
TEST_TIME 65 9836
TEST_TIME 66 9740
TEST_TIME 67 9746
TEST_TIME 68 9646
TEST_TIME 69 9643
TEST_TIME 70 9645
TEST_TIME 71 9581
TEST_TIME 72 9579
TEST_TIME 73 9520
TEST_TIME 74 9487
TEST_TIME 75 9421
TEST_TIME 76 9430
TEST_TIME 77 9435
TEST_TIME 78 9507
TEST_TIME 79 9488
TEST_TIME 80 9525
TEST_TIME 81 9514
TEST_TIME 82 9554
TEST_TIME 83 9635
TEST_TIME 84 9572
TEST_TIME 85 9481
TEST_TIME 86 9531
TEST_TIME 87 9437
TEST_TIME 88 9399
TEST_TIME 89 9365
TEST_TIME 90 9303
TEST_TIME 91 9252
TEST_TIME 92 9309
TEST_TIME 93 9324
TEST_TIME 94 9290
TEST_TIME 95 9279
TEST_TIME 96 9310
TEST_TIME 97 9285
TEST_TIME 98 9274
TEST_TIME 99 9334
TEST_TIME 100 9348
TEST_TIME 101 9381
TEST_TIME 102 9433
TEST_TIME 103 9417
TEST_TIME 104 9393
TEST_TIME 105 9316
TEST_TIME 106 9294
TEST_TIME 107 9271
TEST_TIME 108 9239
TEST_TIME 109 9232
TEST_TIME 110 9253
TEST_TIME 111 9228
TEST_TIME 112 9223
TEST_TIME 113 9185
TEST_TIME 114 9223
TEST_TIME 115 9179
TEST_TIME 116 9173
TEST_TIME 117 9248
TEST_TIME 118 9393
TEST_TIME 119 9396
TEST_TIME 120 9340

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-07-2022 04:38:02 to 07-07-2022 04:38: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 1539525 1539525 0 0.00 0 24540 12 0 0
doc_list_biud 1539516 1539516 0 0.00 0 62725 40 0 0
sp_add_invoice_to_stock 5085 4941 144 2.83 43 242251 24572 0 144
sp_cancel_adding_invoice 5795 5795 0 0.00 5 80795 1084 0 0
sp_cancel_client_order 1616 1616 0 0.00 6 16950 369 0 0
sp_cancel_customer_reserve 573244 573244 0 0.00 1 62727 92 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 4 6339 112 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 4 2935 115 0 0
sp_cancel_supplier_invoice 6369 6353 16 0.25 2 43323 902 0 16
sp_cancel_supplier_order 405 405 0 0.00 14 1436087 162677 0 0
sp_cancel_write_off 10597 10597 0 0.00 2 12887 160 0 0
sp_client_order 19740 19740 0 0.00 5 20715 476 0 0
sp_customer_reserve 458920 458920 0 0.00 0 104093 235 0 0
sp_fill_shopping_cart 30393 30393 0 0.00 1 32664 73 0 0
sp_fill_shopping_cart_clo_res 458898 458898 0 0.00 0 16525 19 0 0
sp_get_clo_for_invoice 4904 4904 0 0.00 0 27217 279 0 0
sp_kill_cost_storno 17494 17494 0 0.00 0 14344 66 0 0
sp_kill_qstorno_ret_qs2qd 1163843 1163843 0 0.00 0 62721 41 0 0
sp_kill_qty_storno 612683 612683 0 0.00 0 62725 93 0 0
sp_lock_selected_doc 20240 20240 0 0.00 0 7184 6 0 0
sp_make_cost_storno 27328 27328 0 0.00 0 14154 72 0 0
sp_multiply_rows_for_pdistr 27325 27325 0 0.00 0 8423 34 0 0
sp_multiply_rows_for_qdistr 19740 19740 0 0.00 0 14915 207 0 0
sp_pay_from_customer 6895 6895 0 0.00 5 14947 167 0 0
sp_pay_to_supplier 5759 5759 0 0.00 6 11260 187 0 0
sp_payment_common 12651 12651 0 0.00 2 14906 46 0 0
sp_qd_handle_on_cancel_clo 1616 1616 0 0.00 1 16935 302 0 0
sp_qd_handle_on_invoice_upd_sts 10698 10698 0 0.00 0 40833 450 0 0
sp_qd_handle_on_reserve_upd_sts 9770 9770 0 0.00 0 25162 61 0 0
sp_reserve_write_off 9770 9770 0 0.00 8 25180 249 0 0
sp_supplier_invoice 5395 5395 0 0.00 9 103231 2984 0 0
sp_supplier_order 5263 5263 0 0.00 6 75286 1378 0 0
srv_aggregate_perf_data 99 99 0 0.00 2517 155288 14894 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 763 116374 39370 0 0
srv_find_qd_qs_mism 1069231 1069231 0 0.00 0 24540 9 0 0
srv_make_invnt_saldo 440 440 0 0.00 81 45307 3546 0 0
srv_make_money_saldo 894 894 0 0.00 1 10013 326 0 0
srv_recalc_idx_stat 4 4 0 0.00 151465 424508 272732 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4945 4945 0 0.00 0 15078 789 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 2 8399 441 0 0
t$perf-norm:sp_cancel_client_order 1616 1616 0 0.00 0 2530 25 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 6606 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 3234 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 303 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 35487 128 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 91893 6047 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 756 14 0 0
t$perf-norm:sp_client_order 19740 19740 0 0.00 0 11751 31 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 564 13 0 0
t$perf-norm:sp_pay_from_customer 6895 6895 0 0.00 0 35363 21 0 0
t$perf-norm:sp_pay_to_supplier 5759 5759 0 0.00 0 8063 18 0 0
t$perf-norm:sp_reserve_write_off 9770 9770 0 0.00 0 6837 20 0 0
t$perf-norm:sp_supplier_invoice 5395 5395 0 0.00 0 7229 27 0 0
t$perf-norm:sp_supplier_order 5263 5263 0 0.00 0 2294 27 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 166 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 3813 60 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 1893 19 0 0
t$perf-norm:srv_make_money_saldo 894 894 0 0.00 0 1663 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 10 121 55 0 0
v_add_invoice_to_stock 10090 9802 288 2.85 0 7891 24 0 288
v_all_customers 36102 36102 0 0.00 0 65 3 0 0
v_all_suppliers 29698 29698 0 0.00 0 79 4 0 0
v_all_wares 39476 39476 0 0.00 0 146 3 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 3326 16 0 0
v_cancel_client_order 3232 3232 0 0.00 0 4795 11 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 696 10 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 1507 13 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 1201 12 0 0
v_cancel_supplier_invoice 768 736 32 4.16 0 3851 26 0 32
v_cancel_supplier_order 810 810 0 0.00 0 866 12 0 0
v_cancel_write_off 1978 1978 0 0.00 0 1954 21 0 0
v_max_id_clo_ord 5261 5261 0 0.00 0 6334 6 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 2733 18 0 0
v_max_id_ord_sup 5392 5392 0 0.00 0 4884 6 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 1454 8 0 0
v_max_non_paid_realizn 3572 3572 0 0.00 0 14875 11 0 0
v_min_id_clo_ord 5261 5261 0 0.00 0 3233 10 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 3767 12 0 0
v_min_id_ord_sup 5392 5392 0 0.00 0 10385 9 0 0
v_min_non_paid_invoice 5758 5758 0 0.00 0 6160 22 0 0
v_min_non_paid_realizn 6893 6893 0 0.00 0 3589 19 0 0
v_our_firm 10012 10012 0 0.00 0 72 4 0 0
v_reserve_write_off 19540 19540 0 0.00 0 3083 9 0 0
x_lock_depdocs_on_canc_invoice 11780 11780 0 0.00 0 20324 115 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 10979 361 0 0
x_make_qstorno_1000_1200 5263 5263 0 0.00 2 53595 1225 0 0
x_make_qstorno_1200_2000 5395 5395 0 0.00 4 103205 2783 0 0
x_make_qstorno_2100_3300 458918 458918 0 0.00 0 104089 205 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-07-2022 04:38:06 to 07-07-2022 04:38: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:21 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 19046 8192000000 9713118160 10008236032 793476160 8.830 100 86 172 0 821576400 7476736 20623504
02:09:53 20370 8192000000 9767534432 10076278784 837999472 9.280 100 88 175 0 870904816 12026096 20879248
02:11:27 10207 8192000000 9808737856 10128400384 875704672 9.657 100 79 157 0 913593744 20124000 17765072
02:12:41 13283 8192000000 9871293344 10207858688 918005360 10.076 100 78 152 1 985144672 50737280 16402032
02:13:58 3490 8192000000 9942735056 10285543424 962799760 10.516 100 87 171 0 1050724160 69703232 18221168
02:15:03 2948 8192000000 9952396656 10312019968 995851568 10.838 100 88 174 0 1075668064 61885152 17931344
02:16:09 1191 8192000000 9997264848 10358317056 1019968480 11.072 100 95 187 1 1114922768 76578912 18375376
02:17:14 1083 8192000000 10000757408 10366963712 1040846304 11.273 100 97 183 1 1123956224 64812240 18297680
02:18:16 831 8192000000 10029040944 10406051840 1053418688 11.393 100 96 190 0 1149810112 77435936 18955488
02:19:19 1241 8192000000 10033659008 10413420544 1058429568 11.441 100 96 187 0 1158283744 80809344 19044832
02:20:22 1028 8192000000 10032735632 10412998656 1065804480 11.512 100 93 183 0 1155824800 71870224 18150096
02:21:24 1137 8192000000 10026359200 10409455616 1075888176 11.608 100 95 175 2 1151328992 57195232 18245584
02:22:27 1146 8192000000 10042291376 10424971264 1077242416 11.621 100 97 188 2 1165700544 68993552 19464576
02:23:29 2781 8192000000 10037938352 10424369152 1083947840 11.685 100 75 145 0 1160590208 62947792 13694576
02:24:32 1155 8192000000 10054566752 10442067968 1091993520 11.762 100 96 187 1 1173558048 63008912 18555616
02:25:35 1282 8192000000 10065706896 10453008384 1097158592 11.811 100 97 185 1 1186124864 69038976 19927296
02:26:37 1062 8192000000 10085167424 10473242624 1102291984 11.859 100 98 195 1 1207758768 85418816 20047968
02:27:40 1273 8192000000 10071187264 10457944064 1100164656 11.839 100 95 183 1 1192725248 73932000 18628592
02:28:42 2281 8192000000 10030099632 10434740224 1088561264 11.729 100 63 124 2 1161736720 60052064 13123392
02:29:47 1634 8192000000 10035357040 10424946688 1072586464 11.577 100 72 140 1 1155091728 66802496 15702768
02:30:49 916 8192000000 10018601152 10406555648 1066664560 11.520 100 79 153 1 1137337584 55066144 15606880
02:31:52 1055 8192000000 10025837408 10405732352 1057659504 11.434 100 89 166 1 1148700400 73226592 17814304
02:32:53 1129 8192000000 10004118656 10380783616 1039767152 11.262 100 95 178 0 1125743664 65731024 20245488
02:33:55 1009 8192000000 9996211376 10368077824 1033756336 11.205 100 92 173 0 1117579392 66202752 17620304
02:34:58 1477 8192000000 10009149744 10386055168 1035088944 11.217 99 80 149 1 1124726016 73700912 15936160
02:36:00 7393 8192000000 10000121360 10391007232 1030304192 11.171 99 57 112 0 1113861376 72459744 11097440
02:37:10 8957 8192000000 10016607120 10401140736 1051946064 11.379 100 69 131 2 1137968624 73257488 12765072
02:38:28 25423 8192000000 10003944912 10385379328 1056692656 11.425 100 62 120 0 1119561760 50964992 11904112
02:40:00 8086 8192000000 10024860080 10401910784 1067623808 11.529 100 81 151 2 1140511120 56724160 16163152
02:41:13 8783 8192000000 10084461760 10474971136 1089053072 11.734 100 67 132 0 1206627104 103410224 14163808
02:42:26 1106 8192000000 10116207152 10502668288 1105306592 11.888 100 89 169 3 1237460384 115173728 16980064
02:43:34 11050 8192000000 10128847008 10518503424 1087518528 11.719 98 70 124 1 1230652640 130357152 12776960
02:44:49 10309 8192000000 10156569888 10559344640 1111962528 11.951 100 57 104 0 1268658432 144575248 12120656
02:46:11 803 8192000000 10229026896 10625855488 1141348368 12.228 100 96 186 0 1343416656 183053328 19014960
02:47:15 862 8192000000 10261260128 10654937088 1153628880 12.344 100 98 191 2 1372660288 197714752 21316656
02:48:21 2292 8192000000 10286687152 10687270912 1168114816 12.479 100 84 163 0 1390580720 204408416 18057488
02:49:26 971 8192000000 10272541296 10702204928 1166967120 12.468 99 95 178 1 1383002192 195878288 20156784
02:50:29 1096 8192000000 10331592816 10730594304 1179155168 12.582 100 97 190 0 1438224160 237187552 21881440
02:51:34 1602 8192000000 10380225168 10793943040 1187118512 12.657 100 93 176 1 1489431840 282180704 20132624
02:52:38 996 8192000000 10402628208 10822553600 1186141632 12.647 100 92 184 0 1506846288 299166288 21538368
02:53:43 930 8192000000 10416536608 10841821184 1194745136 12.728 100 97 194 0 1528823792 311449392 22629264
02:54:48 752 8192000000 10450649680 10886778880 1198078992 12.758 100 95 184 2 1560911008 340511696 22320320
02:55:51 5498 8192000000 10430458128 10875207680 1191602432 12.698 99 65 130 0 1547328576 340321904 15404240
02:56:58 14888 8192000000 10461243264 10886967296 1193432384 12.715 100 52 100 1 1566746144 360308752 13005008
02:58:15 5373 8192000000 10490172112 10928402432 1194308720 12.723 100 55 108 1 1600257424 392034944 13913760
02:59:28 2640 8192000000 10548463808 10973523968 1194943552 12.729 100 72 140 0 1653366064 440978544 17443968
03:00:37 5856 8192000000 10519845104 10989895680 1171043856 12.507 99 52 100 0 1632047376 448049408 12954112
03:01:48 23620 8192000000 10502520720 10974027776 1155137632 12.358 99 46 92 0 1585732960 418030960 12564368
03:03:13 21248 8192000000 10448027616 10891501568 1141813120 12.233 99 29 58 0 1540035072 390092320 8129632
03:04:54 15889 8192000000 10306344960 10817138688 1117571856 12.004 98 37 66 1 1403231008 277794096 7865056
03:06:12 36298 8192000000 10218545664 10706157568 1133700896 12.156 99 29 56 0 1337716432 197298512 6717024
03:07:48 51021 8192000000 10145905472 10651619328 1134516112 12.164 100 16 32 0 1289435312 150691200 4228000
03:09:40 81892 8192000000 10102856400 10551001088 1098423040 11.823 97 20 40 0 1149633024 45953104 5256880
03:13:10 74582 8192000000 10045117056 10480877568 1090363312 11.746 96 17 30 1 1112491840 18893104 3235424
03:15:27 58125 8192000000 10021547504 10432786432 1063328736 11.488 96 59 99 0 1092666080 19364208 9973136
03:17:27 40520 8192000000 10080797456 10511343616 1089466560 11.738 97 27 53 0 1166607936 71528480 5612896
03:19:09 23774 8192000000 10140506928 10614198272 1114044976 11.971 99 39 76 1 1248662176 126239840 8377360
03:20:41 38824 8192000000 10136426208 10582822912 1108479616 11.918 98 29 55 2 1233596464 118284512 6832336
03:22:22 66875 8192000000 10194434864 10634985472 1122578512 12.051 99 24 46 1 1286697056 158762144 5356400
03:24:40 73015 8192000000 10210571744 10703921152 1123730080 12.062 99 36 71 0 1320565648 187951536 8884032
03:27:07 90599 8192000000 10074551184 10632605696 1124917504 12.073 100 10 20 1 1238823312 111430800 2475008
03:29:38 58855 8192000000 10129172816 10570362880 1145109104 12.264 100 22 44 1 1231346480 81601712 4635664
03:31:48 46368 8192000000 10200821888 10653118464 1133378400 12.153 99 31 62 0 1280017136 139969888 6668848
03:33:38 84012 8192000000 10091369072 10558177280 1133950016 12.159 99 19 38 0 1214232592 76332240 3950336
03:36:03 47582 8192000000 10076535168 10503012352 1141587680 12.230 100 29 59 0 1193067920 45685312 5794928
03:38:00 116373 8192000000 10115111088 10595930112 1111575600 11.947 97 32 64 1 1199236992 81505776 6155616
03:40:59 41564 8192000000 10064097200 10523054080 1122028272 12.046 98 13 25 0 1154173264 29297728 2847264
03:42:41 71602 8192000000 10103758496 10556092416 1121712448 12.043 98 11 22 0 1171404976 47366928 2325600
03:44:53 66931 8192000000 10108976464 10580643840 1133922256 12.158 99 15 29 0 1200206352 62907680 3376416
03:47:00 36179 8192000000 10080043376 10554691584 1145325840 12.266 100 17 31 1 1200928704 52513376 3089488
03:48:37 39435 8192000000 10086893936 10559254528 1148218160 12.293 100 27 54 0 1194965312 41285296 5461856
03:50:16 39541 8192000000 10110597808 10558521344 1123214832 12.057 97 33 66 1 1167174112 37005776 6953504
03:51:56 57526 8192000000 10115346464 10553954304 1076845008 11.617 94 23 45 1 1144960304 63490144 4625152
03:53:54 90511 8192000000 10109002400 10583900160 1094215360 11.783 97 18 34 0 1201041376 103073280 3752736
03:56:26 30708 8192000000 10129230048 10563698688 1114208816 11.972 99 48 93 0 1183558368 60050464 9299088
03:58:03 28797 8192000000 10076634880 10504171520 1103804592 11.874 98 33 64 0 1159480624 49208960 6467072
03:59:34 107495 8192000000 10065065760 10548260864 1080315328 11.650 96 23 46 0 1171031584 86179088 4537168
04:02:26 87718 8192000000 10064823792 10545270784 1123440704 12.059 100 41 82 0 1208222960 76684384 8097872
04:05:39 62539 8192000000 10099637760 10572603392 1127094544 12.094 99 14 28 0 1209067680 78972736 3000400
04:07:43 62635 8192000000 10114532240 10562289664 1095240848 11.792 96 16 32 0 1187726304 89016720 3468736
04:09:53 75005 8192000000 10106192704 10609205248 1109646624 11.929 98 9 18 0 1168193136 56405696 2140816
04:12:08 69382 8192000000 10099865888 10544984064 1085092176 11.696 96 31 62 1 1147209184 55226544 6890464
04:14:25 33702 8192000000 10124145904 10567467008 1109006000 11.923 98 43 80 2 1199902128 82241616 8654512
04:16:05 45343 8192000000 10158462736 10635665408 1122401552 12.050 100 30 60 0 1266781936 137340848 7039536
04:18:04 57910 8192000000 10096065232 10539466752 1058168912 11.439 94 24 48 0 1122425040 59862096 4394032
04:20:02 24268 8192000000 10095508864 10520289280 1123764448 12.063 100 42 82 1 1179058112 47003968 8289696
04:21:28 77915 8192000000 10128485568 10555031552 1126870288 12.092 100 55 109 2 1203169024 65126544 11172192
04:23:59 36626 8192000000 10126105344 10582392832 1126168640 12.085 99 27 52 0 1232846272 101063808 5613824
04:25:37 93482 8192000000 10107162896 10648973312 1125657520 12.080 99 11 22 0 1199945824 71714112 2574192
04:28:11 41930 8192000000 10117915088 10570682368 1141654144 12.231 100 27 52 0 1199909104 51962944 6292016
04:30:23 26159 8192000000 10148662576 10584174592 1124546944 12.070 98 39 69 3 1232123712 99955712 7621056
04:31:52 42468 8192000000 10171756720 10610806784 1135185600 12.170 99 24 48 0 1254088096 113378208 5524288
04:33:36 44962 8192000000 10208432000 10711539712 1139515088 12.211 99 25 50 0 1313175488 167665952 5994448
04:35:29 47427 8192000000 10096543712 10669015040 1122845520 12.054 99 5 10 0 1232810144 108870640 1093984
04:37:16 8780 8192000000 9013846240 9242521600 166759696 1.995 15 1 2 0 173418080 6622976 35408

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-07-2022 04:38:12 to 07-07-2022 04:38:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 07-07-2022 04:38:29 to 07-07-2022 04:38:30.

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          220224
MON$OLDEST_ACTIVE               220225
MON$OLDEST_SNAPSHOT             220225
MON$NEXT_TRANSACTION            220225
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                       2055328
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 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.07.22 04:38:31. 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-07 04:38:34 No severe PSQL-related problems occured
07.07.22 04:38:35. End of report.