Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.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 15747704 kB
MemAvailable 31091144 kB
Buffers 20 kB
Cached 15877484 kB
SwapCached 2552 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-06 04:37:18
DTS_END                         2022-07-06 04:37:18.9540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56322
TRN_ID                          212846
ATT_ID                          645
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: 8503.53 28274 1020424
customer order: creation 158.44 486 19013
customer order: refuse 13.88 455 1665
order to supplier: creation 43.12 1547 5174
order to supplier: removal 3.28 167068 394
invoice (draft): creation 44.97 3800 5396
invoice (draft): removal 47.51 1133 5701
invoice accept: apply 40.13 27940 4815
invoice accept: cancel 42.58 1368 5110
customer reserve: creation 3707.24 265 444869
customer reserve: removal 4131.73 102 495807
realization accept: apply 77.53 319 9304
realization accept: cancel 70.89 192 8507
payment from customer: creation 56.86 205 6823
payment from customer: removal 5.13 150 616
payment to supplier: creation 45.00 223 5400
payment to supplier: removal 3.33 131 399
service: total inventory turnovers 3.55 4681 426
service: total monetary turnovers 7.50 421 900
service: aggregate perf. data 0.83 16987 100
service: refresh index statistics 0.04 338011 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-07-2022 04:38:10 to 06-07-2022 04:38:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5139
TEST_TIME 2 5616
TEST_TIME 3 5709
TEST_TIME 4 5485
TEST_TIME 5 5675
TEST_TIME 6 5409
TEST_TIME 7 5416
TEST_TIME 8 5285
TEST_TIME 9 5392
TEST_TIME 10 5418
TEST_TIME 11 5901
TEST_TIME 12 6212
TEST_TIME 13 6608
TEST_TIME 14 6691
TEST_TIME 15 6962
TEST_TIME 16 6959
TEST_TIME 17 7302
TEST_TIME 18 7324
TEST_TIME 19 7544
TEST_TIME 20 7798
TEST_TIME 21 7808
TEST_TIME 22 7945
TEST_TIME 23 7939
TEST_TIME 24 8408
TEST_TIME 25 8455
TEST_TIME 26 8677
TEST_TIME 27 8820
TEST_TIME 28 9064
TEST_TIME 29 9374
TEST_TIME 30 9413
TEST_TIME 31 9408
TEST_TIME 32 9776
TEST_TIME 33 9964
TEST_TIME 34 9894
TEST_TIME 35 9951
TEST_TIME 36 10115
TEST_TIME 37 10018
TEST_TIME 38 9892
TEST_TIME 39 10027
TEST_TIME 40 10180
TEST_TIME 41 10126
TEST_TIME 42 10008
TEST_TIME 43 9835
TEST_TIME 44 9710
TEST_TIME 45 9675
TEST_TIME 46 9731
TEST_TIME 47 9609
TEST_TIME 48 9558
TEST_TIME 49 9498
TEST_TIME 50 9493
TEST_TIME 51 9361
TEST_TIME 52 9317
TEST_TIME 53 9274
TEST_TIME 54 9148
TEST_TIME 55 9114
TEST_TIME 56 9064
TEST_TIME 57 8998
TEST_TIME 58 9007
TEST_TIME 59 9037
TEST_TIME 60 8950
TEST_TIME 61 8865
TEST_TIME 62 8774
TEST_TIME 63 8670
TEST_TIME 64 8689
TEST_TIME 65 8856
TEST_TIME 66 8820
TEST_TIME 67 8804
TEST_TIME 68 8852
TEST_TIME 69 8854
TEST_TIME 70 8810
TEST_TIME 71 8755
TEST_TIME 72 8771
TEST_TIME 73 8905
TEST_TIME 74 8903
TEST_TIME 75 8897
TEST_TIME 76 8844
TEST_TIME 77 8924
TEST_TIME 78 8832
TEST_TIME 79 8798
TEST_TIME 80 8750
TEST_TIME 81 8690
TEST_TIME 82 8716
TEST_TIME 83 8714
TEST_TIME 84 8676
TEST_TIME 85 8617
TEST_TIME 86 8570
TEST_TIME 87 8590
TEST_TIME 88 8592
TEST_TIME 89 8603
TEST_TIME 90 8598
TEST_TIME 91 8582
TEST_TIME 92 8582
TEST_TIME 93 8553
TEST_TIME 94 8652
TEST_TIME 95 8666
TEST_TIME 96 8665
TEST_TIME 97 8737
TEST_TIME 98 8739
TEST_TIME 99 8727
TEST_TIME 100 8710
TEST_TIME 101 8667
TEST_TIME 102 8627
TEST_TIME 103 8643
TEST_TIME 104 8594
TEST_TIME 105 8580
TEST_TIME 106 8545
TEST_TIME 107 8499
TEST_TIME 108 8477
TEST_TIME 109 8502
TEST_TIME 110 8500
TEST_TIME 111 8482
TEST_TIME 112 8463
TEST_TIME 113 8473
TEST_TIME 114 8496
TEST_TIME 115 8495
TEST_TIME 116 8492
TEST_TIME 117 8511
TEST_TIME 118 8578
TEST_TIME 119 8557
TEST_TIME 120 8528

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-07-2022 04:38:14 to 06-07-2022 04:38:17.

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 1428804 1428804 0 0.00 0 26100 14 0 0
doc_list_biud 1428786 1428786 0 0.00 0 56956 43 0 0
sp_add_invoice_to_stock 4940 4815 125 2.53 47 188340 27941 0 125
sp_cancel_adding_invoice 5110 5110 0 0.00 6 77576 1368 0 0
sp_cancel_client_order 1665 1665 0 0.00 6 18611 455 0 0
sp_cancel_customer_reserve 495807 495807 0 0.00 1 30117 103 0 0
sp_cancel_pay_from_customer 616 616 0 0.00 3 7131 150 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 1910 132 0 0
sp_cancel_supplier_invoice 5705 5701 4 0.07 3 57105 1133 0 4
sp_cancel_supplier_order 394 394 0 0.00 14 1337514 167068 0 0
sp_cancel_write_off 8507 8507 0 0.00 1 15590 192 0 0
sp_client_order 19013 19013 0 0.00 5 19733 487 0 0
sp_customer_reserve 444869 444869 0 0.00 0 53982 265 0 0
sp_fill_shopping_cart 29569 29569 0 0.00 1 35076 97 0 0
sp_fill_shopping_cart_clo_res 444836 444836 0 0.00 0 19299 21 0 0
sp_get_clo_for_invoice 4774 4774 0 0.00 0 26044 403 0 0
sp_kill_cost_storno 14632 14632 0 0.00 0 26095 87 0 0
sp_kill_qstorno_ret_qs2qd 1006216 1006216 0 0.00 0 56951 46 0 0
sp_kill_qty_storno 531260 531260 0 0.00 0 56954 106 0 0
sp_lock_selected_doc 19492 19492 0 0.00 0 7711 7 0 0
sp_make_cost_storno 26299 26299 0 0.00 0 21338 101 0 0
sp_multiply_rows_for_pdistr 26297 26297 0 0.00 0 13992 42 0 0
sp_multiply_rows_for_qdistr 19012 19012 0 0.00 0 12989 171 0 0
sp_pay_from_customer 6823 6823 0 0.00 5 10283 205 0 0
sp_pay_to_supplier 5400 5400 0 0.00 7 19323 223 0 0
sp_payment_common 12221 12221 0 0.00 2 10295 57 0 0
sp_qd_handle_on_cancel_clo 1665 1665 0 0.00 1 18580 377 0 0
sp_qd_handle_on_invoice_upd_sts 9884 9884 0 0.00 0 41010 591 0 0
sp_qd_handle_on_reserve_upd_sts 9302 9302 0 0.00 0 13207 73 0 0
sp_reserve_write_off 9304 9304 0 0.00 8 21357 319 0 0
sp_supplier_invoice 5396 5396 0 0.00 8 101994 3801 0 0
sp_supplier_order 5174 5174 0 0.00 6 35261 1547 0 0
srv_aggregate_perf_data 100 100 0 0.00 2113 231877 16988 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 751 145815 33377 0 0
srv_find_qd_qs_mism 973425 973425 0 0.00 0 13080 11 0 0
srv_make_invnt_saldo 426 426 0 0.00 48 125981 4681 0 0
srv_make_money_saldo 900 900 0 0.00 1 30260 422 0 0
srv_recalc_idx_stat 5 5 0 0.00 228027 451474 338011 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4821 4821 0 0.00 1 17972 1060 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 2 5168 460 0 0
t$perf-norm:sp_cancel_client_order 1665 1665 0 0.00 0 8891 36 0 0
t$perf-norm:sp_cancel_customer_reserve 618 618 0 0.00 0 2643 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 616 616 0 0.00 0 2259 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 251 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1859 43 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 102725 7760 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 3101 23 0 0
t$perf-norm:sp_client_order 19015 19015 0 0.00 0 10719 41 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 3067 18 0 0
t$perf-norm:sp_pay_from_customer 6823 6823 0 0.00 0 11193 25 0 0
t$perf-norm:sp_pay_to_supplier 5400 5400 0 0.00 0 3257 19 0 0
t$perf-norm:sp_reserve_write_off 9304 9304 0 0.00 0 12516 28 0 0
t$perf-norm:sp_supplier_invoice 5396 5396 0 0.00 0 6971 36 0 0
t$perf-norm:sp_supplier_order 5174 5174 0 0.00 0 4294 36 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 3400 41 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 655 13 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 754 15 0 0
t$perf-norm:srv_make_money_saldo 900 900 0 0.00 0 1266 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 12 184 90 0 0
v_add_invoice_to_stock 9790 9540 250 2.55 0 3837 28 0 250
v_all_customers 35158 35158 0 0.00 0 93 4 0 0
v_all_suppliers 28934 28934 0 0.00 0 101 5 0 0
v_all_wares 38016 38016 0 0.00 0 104 4 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 1795 18 0 0
v_cancel_client_order 3330 3330 0 0.00 0 2736 12 0 0
v_cancel_customer_prepayment 1232 1232 0 0.00 0 2177 14 0 0
v_cancel_customer_reserve 1236 1236 0 0.00 1 318 11 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 1780 13 0 0
v_cancel_supplier_invoice 806 798 8 0.99 0 1216 25 0 8
v_cancel_supplier_order 788 788 0 0.00 0 962 15 0 0
v_cancel_write_off 1912 1912 0 0.00 0 3394 29 0 0
v_max_id_clo_ord 5167 5167 0 0.00 0 1385 6 0 0
v_max_id_clo_res 930 930 0 0.00 0 1467 11 0 0
v_max_id_ord_sup 5389 5389 0 0.00 0 5924 11 0 0
v_max_non_paid_invoice 1488 1488 0 0.00 0 3868 11 0 0
v_max_non_paid_realizn 3503 3503 0 0.00 0 4140 10 0 0
v_min_id_clo_ord 5167 5167 0 0.00 0 3215 12 0 0
v_min_id_clo_res 930 930 0 0.00 0 2713 12 0 0
v_min_id_ord_sup 5389 5389 0 0.00 0 8076 15 0 0
v_min_non_paid_invoice 5399 5399 0 0.00 0 10287 23 0 0
v_min_non_paid_realizn 6822 6822 0 0.00 0 8049 30 0 0
v_our_firm 9494 9494 0 0.00 0 56 5 0 0
v_reserve_write_off 18602 18602 0 0.00 0 5412 14 0 0
x_lock_depdocs_on_canc_invoice 10412 10412 0 0.00 0 21892 144 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 8173 409 0 0
x_make_qstorno_1000_1200 5174 5174 0 0.00 2 35249 1363 0 0
x_make_qstorno_1200_2000 5396 5396 0 0.00 4 101572 3513 0 0
x_make_qstorno_2100_3300 444869 444869 0 0.00 0 42208 231 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-07-2022 04:38:18 to 06-07-2022 04:38:22.

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 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:24 22744 8192000000 9718039680 10015756288 799566544 8.892 100 85 170 0 828132576 7963168 20602864
02:09:54 20366 8192000000 9759854000 10069745664 836155808 9.261 100 88 175 0 869280144 11951392 21172944
02:11:24 11363 8192000000 9792625136 10112466944 865628272 9.556 100 86 172 0 904419504 19254352 19536880
02:12:53 8035 8192000000 9862884288 10198163456 912321856 10.020 100 86 172 0 972883184 42049408 18511920
02:14:10 3154 8192000000 9923763184 10265231360 951439008 10.405 100 88 174 1 1046745120 76723376 18582736
02:15:16 2287 8192000000 9947693248 10300841984 988210816 10.764 100 89 174 2 1062095392 56075584 17808992
02:16:21 1150 8192000000 9980331552 10339172352 1015719856 11.031 100 93 179 0 1098691136 65098368 17872912
02:17:25 1296 8192000000 10018185024 10384957440 1030731952 11.175 100 97 191 0 1133113072 83442800 18938320
02:18:28 1458 8192000000 10036551280 10408562688 1051157552 11.372 100 92 175 0 1156990096 88252832 17579712
02:19:31 1137 8192000000 10033411488 10413264896 1059717008 11.454 100 88 164 0 1159031840 81969552 17345280
02:20:34 1098 8192000000 10049400336 10432561152 1066130800 11.515 100 96 184 0 1171214736 86848688 18235248
02:21:36 5541 8192000000 10024061488 10402013184 1073925888 11.590 100 88 165 2 1146060176 57223152 14911136
02:22:42 933 8192000000 10047990448 10432851968 1080221616 11.650 100 92 174 0 1173996464 75872704 17902144
02:23:44 3584 8192000000 10048364672 10437210112 1085224624 11.697 100 81 156 3 1165097744 64292768 15580352
02:24:48 1100 8192000000 10058799440 10447319040 1087650592 11.720 100 96 185 2 1182253856 75968912 18634352
02:25:50 4124 8192000000 10064072048 10453553152 1088696688 11.730 100 70 133 1 1186666832 84343872 13626272
02:26:56 979 8192000000 10060665104 10446098432 1096822144 11.807 100 97 180 1 1179762096 65625808 17314144
02:27:57 1095 8192000000 10069259792 10460045312 1104884896 11.884 100 98 194 0 1194977440 71279232 18813312
02:29:00 1273 8192000000 10058979504 10444120064 1089125168 11.734 100 88 165 0 1173480272 69255552 15099552
02:30:03 1388 8192000000 10056173952 10442891264 1088704880 11.730 100 95 183 2 1179556240 71990032 18861328
02:31:05 1113 8192000000 10006468752 10383429632 1064903136 11.503 100 88 163 3 1125231376 44471600 15856640
02:32:08 835 8192000000 10002899936 10382991360 1052146400 11.381 100 77 150 0 1127718320 59712528 15859392
02:33:15 898 8192000000 9977770272 10348122112 1036800848 11.234 100 92 173 1 1097942208 43548784 17592576
02:34:17 916 8192000000 9990707408 10364416000 1039947952 11.264 100 89 167 1 1108578368 51212480 17417936
02:35:19 1124 8192000000 10020172448 10398494720 1051893856 11.379 100 95 179 0 1135224624 64614816 18715952
02:36:22 1352 8192000000 9981377952 10365104128 1042311520 11.287 100 73 136 3 1106080064 50130608 13637936
02:37:25 11279 8192000000 9959206432 10327531520 1030982080 11.178 98 84 162 3 1084620960 38662096 14976784
02:38:43 1541 8192000000 10018524416 10396114944 1060280480 11.459 100 87 166 1 1141049072 63381072 17387520
02:39:51 24297 8192000000 10045894752 10431373312 1079039840 11.638 100 60 119 0 1173579344 82087920 12451584
02:41:18 949 8192000000 10071171456 10456883200 1082005408 11.667 100 81 144 4 1191323376 93226368 16091600
02:42:25 2562 8192000000 10105348288 10492928000 1097172352 11.811 100 90 174 1 1219448464 103652864 18623248
02:43:35 1410 8192000000 10145213728 10532732928 1098810688 11.826 100 83 164 0 1267101664 149768384 18522592
02:44:42 1270 8192000000 10173533568 10563407872 1117002736 11.999 100 82 160 0 1292284688 157503392 17778560
02:45:49 2903 8192000000 10216325248 10619273216 1128035712 12.103 100 70 139 0 1331130064 188052128 15042224
02:46:58 2624 8192000000 10247643152 10641076224 1134493824 12.164 100 92 181 2 1363960256 209170512 20295920
02:48:07 746 8192000000 10276316640 10681274368 1125536640 12.079 99 85 169 0 1388652720 242625824 20490256
02:49:13 2194 8192000000 10322002064 10728169472 1155004144 12.356 100 86 169 0 1438680336 264729872 18946320
02:50:19 1186 8192000000 10276505008 10708070400 1162948048 12.431 100 92 178 3 1393397104 210835216 19613840
02:51:25 2027 8192000000 10307228144 10720444416 1167845888 12.477 100 86 170 2 1420796848 233870960 19080000
02:52:35 971 8192000000 10330473728 10744373248 1175390352 12.547 100 93 182 2 1439937872 243473728 21073792
02:53:40 1148 8192000000 10348197472 10764423168 1168680944 12.484 100 88 166 0 1454337616 267508288 18148384
02:54:45 2726 8192000000 10357141632 10786832384 1164058304 12.441 100 90 171 2 1461598976 277832384 19708288
02:55:50 1383 8192000000 10369731520 10779213824 1154800240 12.355 99 94 184 0 1478063808 301861440 21402128
02:56:54 859 8192000000 10359512752 10779308032 1151074592 12.320 99 89 171 1 1470504048 299052624 20376832
02:57:59 1344 8192000000 10399444848 10800779264 1159148368 12.395 99 92 172 1 1504483392 326059584 19275440
02:59:04 2287 8192000000 10423223888 10847596544 1168280912 12.481 100 99 198 0 1522160960 330793824 23086224
03:00:09 1475 8192000000 10428462352 10870259712 1169744880 12.494 100 90 179 1 1530304640 340002544 20557216
03:01:17 812 8192000000 10435657424 10898239488 1174410944 12.538 100 93 184 0 1542996096 345159136 23426016
03:02:22 4670 8192000000 10402026544 10855153664 1159545728 12.399 100 49 98 0 1521951552 350149040 12256784
03:03:29 6928 8192000000 10380432176 10825224192 1163323888 12.434 100 45 88 0 1485265712 310741264 11200560
03:04:38 19036 8192000000 10374809888 10822205440 1152434080 12.332 100 37 73 0 1485049184 323288736 9326368
03:06:01 18295 8192000000 10327063664 10804551680 1137741552 12.194 99 30 60 0 1411133712 265708928 7683232
03:07:23 7112 8192000000 10284731712 10748342272 1145449232 12.267 99 48 89 2 1381298896 225186464 10663200
03:08:33 1512 8192000000 10276828304 10757476352 1149835648 12.308 99 82 163 1 1384165232 215317136 19012448
03:09:36 15045 8192000000 10243463264 10688765952 1140557632 12.221 99 37 74 0 1348173904 198864800 8751472
03:10:55 11906 8192000000 10261912000 10701934592 1157052928 12.376 99 35 69 0 1369198560 204056240 8089392
03:12:10 19001 8192000000 10286074016 10779942912 1160305088 12.406 100 43 84 0 1410833904 240910816 9618000
03:13:30 36484 8192000000 10239565088 10725380096 1145405296 12.266 99 33 61 0 1366676912 213198112 8073504
03:15:26 24925 8192000000 10230131968 10701856768 1147387888 12.285 100 43 76 1 1339540368 183031088 9121392
03:16:53 74060 8192000000 10097915200 10592223232 1096756176 11.807 96 18 34 1 1234981472 134139680 4085616
03:20:17 145815 8192000000 10132421584 10623856640 1106798432 11.902 97 25 52 0 1185350576 73127872 5424272
03:23:45 58958 8192000000 10088272144 10535944192 1123363344 12.059 99 24 48 0 1187176336 58059728 5753264
03:25:54 143694 8192000000 10061954480 10505355264 1131627904 12.137 99 26 49 1 1179377424 42471328 5278192
03:29:22 115165 8192000000 10114182480 10554789888 1121871232 12.045 97 30 56 0 1179274000 51832080 5570688
03:32:34 22577 8192000000 10094371840 10534055936 1131736432 12.138 99 30 60 2 1182934432 45081760 6116240
03:33:58 53502 8192000000 10142543552 10599555072 1134934240 12.168 99 45 86 1 1246173184 101373648 9865296
03:36:07 29677 8192000000 10091664016 10584637440 1130150240 12.123 99 16 31 0 1196742432 62642240 3949952
03:37:43 53926 8192000000 10106705792 10578731008 1109496704 11.928 97 13 26 0 1177509312 65093664 2918944
03:39:38 85753 8192000000 10163247168 10613182464 1119383488 12.021 98 27 53 0 1242756112 117253344 6119280
03:42:09 89444 8192000000 10053207104 10518974464 1084731296 11.693 96 14 28 0 1140211888 52439616 3040976
03:44:39 58811 8192000000 10107993600 10570842112 1131683520 12.137 99 29 58 0 1202608384 64830640 6094224
03:46:39 44617 8192000000 10042766368 10483544064 1106797472 11.902 98 21 40 0 1136603584 25065328 4740784
03:48:27 30713 8192000000 10122028784 10554798080 1127770432 12.100 99 31 62 0 1206845568 72144752 6930384
03:49:58 63465 8192000000 10099668704 10611253248 1137125888 12.188 100 29 58 0 1251421904 108167744 6128272
03:52:19 55024 8192000000 10102092848 10557784064 1109100304 11.924 97 17 34 0 1169141728 56705568 3335856
03:54:18 51365 8192000000 10076905632 10506436608 1119377360 12.021 98 32 58 0 1160492544 35326160 5789024
03:56:10 76045 8192000000 10053892432 10484514816 1117521536 12.004 98 49 85 6 1175126592 48525952 9079104
03:58:30 14437 8192000000 10110721104 10543587328 1141774768 12.232 100 48 91 0 1208595008 57513632 9306608
03:59:44 52125 8192000000 10069768880 10549813248 1102386672 11.860 97 11 22 0 1170147408 65574560 2186176
04:01:38 53665 8192000000 10064544080 10511806464 1104569776 11.881 98 14 28 0 1156297120 48752144 2975200
04:04:16 54770 8192000000 10072785376 10509799424 1133157936 12.151 100 26 45 1 1187594608 49685376 4751296
04:06:11 34411 8192000000 10062231136 10499923968 1108114336 11.915 98 28 56 0 1149897392 35198528 6584528
04:07:56 40001 8192000000 10063256944 10499117056 1089426816 11.737 96 22 45 0 1130475520 36197056 4851648
04:09:38 67045 8192000000 10141773536 10587488256 1124730880 12.072 99 25 47 1 1217639920 88131136 4777904
04:11:50 35537 8192000000 10113469104 10553331712 1133255328 12.152 100 36 68 0 1189111968 48404560 7452080
04:13:27 44291 8192000000 10115694720 10558070784 1122923264 12.055 99 32 61 1 1196449680 66735904 6790512
04:15:12 49087 8192000000 10060916496 10482917376 1123568304 12.061 100 25 50 1 1152180752 23330480 5281968
04:17:01 52771 8192000000 10126203440 10583638016 1125373696 12.078 99 42 84 0 1222982800 89101392 8507712
04:19:20 33337 8192000000 10047977136 10502885376 1098329120 11.822 97 30 59 0 1151113552 46278992 6505440
04:20:55 69447 8192000000 10069897552 10536558592 1106230128 11.897 98 5 10 0 1157052400 49943728 878544
04:23:04 42434 8192000000 10042282880 10492502016 1102282384 11.859 97 17 34 1 1144780656 39000928 3497344
04:25:47 81580 8192000000 10074371824 10566868992 1123621744 12.061 99 10 14 2 1188164224 62961552 1580928
04:28:11 33649 8192000000 10063014400 10494894080 1104115888 11.877 97 42 81 2 1139598096 27251792 8230416
04:29:45 33741 8192000000 10104788544 10554122240 1129240192 12.114 99 35 71 0 1207475152 70419664 7815296
04:31:21 24456 8192000000 10062674528 10505740288 1109623088 11.929 98 28 54 0 1152153040 37191728 5338224
04:32:46 23963 8192000000 10129832448 10554720256 1132601104 12.146 100 55 108 0 1225751520 81760176 11390240
04:34:13 25251 8192000000 10133998336 10596368384 1130765888 12.129 100 42 84 0 1237169952 97824384 8579680
04:35:41 51322 8192000000 10119576592 10560679936 1131527280 12.136 99 37 74 0 1211577696 72268944 7781472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-07-2022 04:38:24 to 06-07-2022 04:38:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-07-2022 04:38:41 to 06-07-2022 04:38:43.

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


ISQL Version: LI-V4.0.2.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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Wed Jul  6 04:37:31 2022
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
--- end of diff output ---

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

06.07.22 04:38:44. 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-06 04:38:47 No severe PSQL-related problems occured
06.07.22 04:38:48. End of report.