Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.04.2022 01: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 16655740 kB
MemAvailable 31569580 kB
Buffers 0 kB
Cached 15087396 kB
SwapCached 3224 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-04-30 01:38:12
DTS_END                         2022-04-30 01:38:12.4700
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(57:17)
IP                              ::1/32838
TRN_ID                          246923
ATT_ID                          642
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: 9529.07 19122 1143488
customer order: creation 184.58 355 22150
customer order: refuse 15.75 354 1890
order to supplier: creation 48.83 1351 5860
order to supplier: removal 3.58 110413 430
invoice (draft): creation 48.69 3462 5843
invoice (draft): removal 52.50 884 6300
invoice accept: apply 43.38 18568 5205
invoice accept: cancel 47.32 844 5678
customer reserve: creation 4087.07 181 490448
customer reserve: removal 4685.36 65 562243
realization accept: apply 87.64 188 10517
realization accept: cancel 87.08 120 10449
payment from customer: creation 63.18 113 7581
payment from customer: removal 6.34 88 761
payment to supplier: creation 51.30 121 6156
payment to supplier: removal 3.68 70 442
service: total inventory turnovers 5.13 1035 616
service: total monetary turnovers 6.83 39 819
service: aggregate perf. data 0.80 24356 96
service: refresh index statistics 0.03 219831 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-04-2022 01:40:00 to 30-04-2022 01:40:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7036
TEST_TIME 2 7309
TEST_TIME 3 7154
TEST_TIME 4 6904
TEST_TIME 5 6414
TEST_TIME 6 7128
TEST_TIME 7 7659
TEST_TIME 8 7980
TEST_TIME 9 7695
TEST_TIME 10 7548
TEST_TIME 11 7244
TEST_TIME 12 7284
TEST_TIME 13 7198
TEST_TIME 14 7325
TEST_TIME 15 7313
TEST_TIME 16 7349
TEST_TIME 17 7612
TEST_TIME 18 7741
TEST_TIME 19 7789
TEST_TIME 20 7755
TEST_TIME 21 8238
TEST_TIME 22 8164
TEST_TIME 23 8145
TEST_TIME 24 8152
TEST_TIME 25 8307
TEST_TIME 26 8225
TEST_TIME 27 8862
TEST_TIME 28 9673
TEST_TIME 29 9634
TEST_TIME 30 10116
TEST_TIME 31 11244
TEST_TIME 32 11092
TEST_TIME 33 11173
TEST_TIME 34 10941
TEST_TIME 35 10773
TEST_TIME 36 10678
TEST_TIME 37 10579
TEST_TIME 38 10769
TEST_TIME 39 10663
TEST_TIME 40 10539
TEST_TIME 41 10417
TEST_TIME 42 10319
TEST_TIME 43 10228
TEST_TIME 44 10196
TEST_TIME 45 10247
TEST_TIME 46 10409
TEST_TIME 47 10289
TEST_TIME 48 10224
TEST_TIME 49 10248
TEST_TIME 50 10091
TEST_TIME 51 9953
TEST_TIME 52 10090
TEST_TIME 53 10066
TEST_TIME 54 9931
TEST_TIME 55 9787
TEST_TIME 56 9793
TEST_TIME 57 9840
TEST_TIME 58 9743
TEST_TIME 59 9733
TEST_TIME 60 9810
TEST_TIME 61 9913
TEST_TIME 62 9881
TEST_TIME 63 9941
TEST_TIME 64 9962
TEST_TIME 65 10040
TEST_TIME 66 10019
TEST_TIME 67 9963
TEST_TIME 68 10014
TEST_TIME 69 10012
TEST_TIME 70 10065
TEST_TIME 71 10086
TEST_TIME 72 9954
TEST_TIME 73 9887
TEST_TIME 74 9848
TEST_TIME 75 9822
TEST_TIME 76 9792
TEST_TIME 77 9744
TEST_TIME 78 9679
TEST_TIME 79 9628
TEST_TIME 80 9605
TEST_TIME 81 9560
TEST_TIME 82 9533
TEST_TIME 83 9585
TEST_TIME 84 9576
TEST_TIME 85 9582
TEST_TIME 86 9617
TEST_TIME 87 9631
TEST_TIME 88 9622
TEST_TIME 89 9601
TEST_TIME 90 9674
TEST_TIME 91 9699
TEST_TIME 92 9684
TEST_TIME 93 9705
TEST_TIME 94 9749
TEST_TIME 95 9751
TEST_TIME 96 9688
TEST_TIME 97 9638
TEST_TIME 98 9603
TEST_TIME 99 9631
TEST_TIME 100 9579
TEST_TIME 101 9544
TEST_TIME 102 9496
TEST_TIME 103 9556
TEST_TIME 104 9517
TEST_TIME 105 9512
TEST_TIME 106 9557
TEST_TIME 107 9540
TEST_TIME 108 9549
TEST_TIME 109 9570
TEST_TIME 110 9606
TEST_TIME 111 9609
TEST_TIME 112 9604
TEST_TIME 113 9594
TEST_TIME 114 9600
TEST_TIME 115 9612
TEST_TIME 116 9710
TEST_TIME 117 9670
TEST_TIME 118 9597
TEST_TIME 119 9589
TEST_TIME 120 9544

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-04-2022 01:40:04 to 30-04-2022 01:40:07.

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 1597040 1597040 0 0.00 0 13236 6 0 0
doc_list_biud 1597040 1597040 0 0.00 0 77470 29 0 0
sp_add_invoice_to_stock 5311 5205 106 1.99 22 199179 18568 0 106
sp_cancel_adding_invoice 5678 5678 0 0.00 5 72718 845 0 0
sp_cancel_client_order 1890 1890 0 0.00 4 8716 354 0 0
sp_cancel_customer_reserve 562243 562243 0 0.00 1 34209 66 0 0
sp_cancel_pay_from_customer 761 761 0 0.00 2 14131 88 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 2974 71 0 0
sp_cancel_supplier_invoice 6309 6300 9 0.14 1 77587 885 0 9
sp_cancel_supplier_order 430 430 0 0.00 6 1152654 110414 0 0
sp_cancel_write_off 10449 10449 0 0.00 1 38227 121 0 0
sp_client_order 22150 22150 0 0.00 3 31010 355 0 0
sp_customer_reserve 490448 490448 0 0.00 0 50131 181 0 0
sp_fill_shopping_cart 33850 33850 0 0.00 1 11363 38 0 0
sp_fill_shopping_cart_clo_res 490424 490424 0 0.00 0 12779 12 0 0
sp_get_clo_for_invoice 5180 5180 0 0.00 0 30712 272 0 0
sp_kill_cost_storno 17330 17330 0 0.00 0 9775 59 0 0
sp_kill_qstorno_ret_qs2qd 1141665 1141665 0 0.00 0 77468 36 0 0
sp_kill_qty_storno 602687 602687 0 0.00 0 77468 77 0 0
sp_lock_selected_doc 21907 21907 0 0.00 0 5677 4 0 0
sp_make_cost_storno 29434 29434 0 0.00 0 13156 53 0 0
sp_multiply_rows_for_pdistr 29434 29434 0 0.00 0 10373 31 0 0
sp_multiply_rows_for_qdistr 22150 22150 0 0.00 0 10409 143 0 0
sp_pay_from_customer 7581 7581 0 0.00 4 11831 113 0 0
sp_pay_to_supplier 6156 6156 0 0.00 4 13156 122 0 0
sp_payment_common 13737 13737 0 0.00 1 10451 24 0 0
sp_qd_handle_on_cancel_clo 1890 1890 0 0.00 0 8704 324 0 0
sp_qd_handle_on_invoice_upd_sts 10858 10858 0 0.00 0 36762 352 0 0
sp_qd_handle_on_reserve_upd_sts 10517 10517 0 0.00 0 19949 65 0 0
sp_reserve_write_off 10517 10517 0 0.00 6 19985 188 0 0
sp_supplier_invoice 5843 5843 0 0.00 5 141761 3463 0 0
sp_supplier_order 5860 5860 0 0.00 5 30093 1351 0 0
srv_aggregate_perf_data 96 96 0 0.00 2787 805058 24357 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 2453 139301 42342 0 0
srv_find_qd_qs_mism 1092398 1092398 0 0.00 0 11481 6 0 0
srv_make_invnt_saldo 616 616 0 0.00 31 51383 1035 0 0
srv_make_money_saldo 819 819 0 0.00 1 8321 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 188245 262141 219832 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5205 5205 0 0.00 0 10182 181 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 5272 94 0 0
t$perf-norm:sp_cancel_client_order 1890 1890 0 0.00 0 2212 11 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 2146 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 761 761 0 0.00 0 3249 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 118 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 2349 29 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 26310 1699 0 0
t$perf-norm:sp_cancel_write_off 1096 1096 0 0.00 0 6198 22 0 0
t$perf-norm:sp_client_order 22150 22150 0 0.00 0 20849 22 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 1120 3 0 0
t$perf-norm:sp_pay_from_customer 7581 7581 0 0.00 0 20880 12 0 0
t$perf-norm:sp_pay_to_supplier 6156 6156 0 0.00 0 4634 9 0 0
t$perf-norm:sp_reserve_write_off 10517 10517 0 0.00 0 18684 21 0 0
t$perf-norm:sp_supplier_invoice 5843 5843 0 0.00 0 22521 22 0 0
t$perf-norm:sp_supplier_order 5860 5860 0 0.00 0 5804 16 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 165 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 322 7 0 0
t$perf-norm:srv_make_invnt_saldo 616 616 0 0.00 0 4252 34 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 9578 32 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10570 10358 212 2.00 0 13054 22 0 212
v_all_customers 40570 40570 0 0.00 0 15 1 0 0
v_all_suppliers 32682 32682 0 0.00 0 19 1 0 0
v_all_wares 44300 44300 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 11810 23 0 0
v_cancel_client_order 3780 3780 0 0.00 0 2708 6 0 0
v_cancel_customer_prepayment 1522 1522 0 0.00 0 14098 16 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 1760 6 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 2962 8 0 0
v_cancel_supplier_invoice 896 878 18 2.00 0 2583 19 0 18
v_cancel_supplier_order 860 860 0 0.00 0 7236 15 0 0
v_cancel_write_off 2192 2192 0 0.00 0 13170 20 0 0
v_max_id_clo_ord 5860 5860 0 0.00 0 474 1 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 3460 18 0 0
v_max_id_ord_sup 5840 5840 0 0.00 0 3617 4 0 0
v_max_non_paid_invoice 1515 1515 0 0.00 0 482 3 0 0
v_max_non_paid_realizn 4000 4000 0 0.00 0 4166 6 0 0
v_min_id_clo_ord 5860 5860 0 0.00 0 4224 7 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 6068 18 0 0
v_min_id_ord_sup 5840 5840 0 0.00 0 7415 9 0 0
v_min_non_paid_invoice 6156 6156 0 0.00 0 7294 14 0 0
v_min_non_paid_realizn 7581 7581 0 0.00 0 10444 21 0 0
v_our_firm 10892 10892 0 0.00 0 24 1 0 0
v_reserve_write_off 21034 21034 0 0.00 0 12074 8 0 0
x_lock_depdocs_on_canc_invoice 11539 11539 0 0.00 0 22163 130 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 15449 487 0 0
x_make_qstorno_1000_1200 5860 5860 0 0.00 2 30073 1270 0 0
x_make_qstorno_1200_2000 5843 5843 0 0.00 2 141730 3320 0 0
x_make_qstorno_2100_3300 490448 490448 0 0.00 0 50129 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-04-2022 01:40:08 to 30-04-2022 01:40:12.

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
23:08:16 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:16 40623 8192000000 9928489264 10240843776 989424816 10.776 100 91 182 1 1019449056 6568992 23455248
23:11:06 31518 8192000000 9964399200 10290720768 1038792752 11.253 100 89 178 1 1071091232 9602528 22695952
23:12:40 14482 8192000000 9998466352 10335985664 1084007136 11.686 100 79 158 0 1113863520 10873408 18982976
23:14:02 9188 8192000000 10049855856 10398027776 1133853408 12.158 100 79 158 0 1163507072 11121264 18532400
23:15:19 7923 8192000000 10095430400 10455674880 1181635360 12.605 100 84 168 0 1213759872 12888704 19235808
23:16:30 4946 8192000000 10143505264 10514276352 1227460032 13.031 100 77 151 0 1257567808 13477552 16630224
23:17:37 2395 8192000000 10171293664 10546610176 1257409040 13.306 100 87 174 1 1291091552 14234592 19447920
23:18:45 2473 8192000000 10213060080 10595192832 1292925600 13.631 100 93 186 1 1327202320 13164144 21112576
23:19:48 3729 8192000000 10216501488 10601426944 1302575408 13.719 100 68 135 0 1328071024 10991744 14503872
23:20:53 7927 8192000000 10203197952 10610737152 1313409296 13.817 100 34 68 0 1329406976 8130096 7867584
23:22:03 47635 8192000000 10221023136 10631004160 1321677280 13.892 100 46 91 1 1338857472 6551920 10628272
23:23:53 7701 8192000000 10214824320 10633158656 1326712880 13.937 100 20 40 2 1341293696 10211776 4369040
23:25:04 5192 8192000000 10218126288 10640248832 1335421280 14.016 100 20 40 1 1346055776 6287872 4346624
23:26:10 12236 8192000000 10229880800 10646515712 1331242672 13.978 99 21 42 1 1341917168 5461232 5213264
23:27:23 3468 8192000000 10251576624 10659557376 1344630320 14.099 100 54 107 0 1364377216 6976448 12770448
23:28:27 5515 8192000000 10233121456 10653040640 1342323296 14.078 100 35 70 0 1357888960 8109456 7456208
23:29:35 7842 8192000000 10169034224 10578022400 1214155872 12.908 93 12 24 0 1222227872 4653792 3418208
23:30:43 10718 8192000000 10139636320 10534559744 1199480896 12.772 95 18 34 2 1208931920 4963648 4487376
23:31:55 38457 8192000000 10146816912 10546491392 1199948528 12.776 95 24 48 2 1211028352 6056240 5023584
23:33:33 7613 8192000000 10155976496 10552664064 1237499152 13.123 97 32 64 2 1254224720 9429008 7296560
23:34:42 4927 8192000000 10190295696 10595295232 1288501600 13.591 100 27 54 2 1309094208 15042384 5550224
23:35:52 12085 8192000000 10189448640 10601992192 1298731392 13.684 100 21 42 0 1309390208 5378512 5280304
23:37:10 34268 8192000000 10188872800 10609164288 1307250512 13.761 100 17 34 1 1316725696 5490096 3985088
23:38:51 20166 8192000000 10241264096 10649837568 1333289344 13.997 100 51 102 0 1352738912 8378064 11071504
23:40:19 38598 8192000000 10261492272 10679898112 1353366480 14.178 100 38 76 2 1371615824 9217616 9031728
23:42:01 18538 8192000000 10278586096 10696622080 1369162560 14.320 100 42 83 2 1389289840 9566368 10560912
23:43:28 16922 8192000000 10290820304 10718486528 1385036464 14.462 100 42 85 2 1407932864 11905456 10990944
23:44:54 3099 8192000000 10313937968 10732572672 1395307856 14.553 100 74 148 3 1426198752 13507712 17383184
23:46:00 6440 8192000000 10321644208 10741317632 1399056112 14.587 100 66 128 0 1429385888 15663120 14666656
23:47:24 14714 8192000000 10330122336 10758463488 1404553120 14.636 100 51 100 1 1437591424 21569872 11468432
23:48:42 12461 8192000000 10333541968 10763079680 1412722928 14.708 99 53 105 0 1445364240 20437456 12203856
23:49:58 4506 8192000000 10346386000 10777935872 1422201056 14.792 100 44 88 1 1457332976 24278992 10852928
23:51:09 6079 8192000000 10357192880 10780770304 1419777872 14.771 100 69 137 1 1462838688 26130896 16929920
23:52:25 2452 8192000000 10368082720 10785361920 1412707536 14.708 99 82 164 1 1462835120 31048656 19078928
23:53:33 5016 8192000000 10349968192 10764181504 1402922224 14.621 100 72 143 0 1455231328 34558480 17750624
23:54:46 3470 8192000000 10352321072 10758025216 1388679872 14.494 99 80 159 1 1445602928 37715392 19207664
23:55:56 3046 8192000000 10341525264 10749538304 1384572656 14.457 100 78 157 2 1446087920 41099936 20415328
23:57:05 4814 8192000000 10334303616 10740137984 1366603472 14.297 99 66 132 2 1429696592 45466128 17626992
23:58:17 3183 8192000000 10365761968 10773385216 1384809408 14.460 99 82 163 0 1464623328 58568288 21245632
23:59:25 3313 8192000000 10375391696 10778832896 1398626304 14.583 100 79 157 1 1478081424 58956720 20498400
00:00:33 29827 8192000000 10366748512 10780516352 1402665600 14.619 100 50 100 1 1471406464 55062848 13678016
00:02:13 5676 8192000000 10379733760 10797920256 1412259360 14.704 100 52 104 0 1485456688 59041136 14156192
00:03:30 9741 8192000000 10382584256 10807910400 1402839888 14.620 99 45 90 1 1481308672 65850992 12617792
00:04:53 34260 8192000000 10375516688 10809843712 1381871808 14.433 99 38 76 0 1474432464 80358704 12201952
00:06:39 52962 8192000000 10326727280 10781294592 1367432224 14.304 100 22 44 0 1432821520 58665136 6724160
00:08:39 70537 8192000000 10280115232 10724855808 1324246000 13.915 96 20 40 1 1367116224 37797600 5072624
00:10:50 61212 8192000000 10282391120 10737631232 1353190960 14.176 98 15 30 0 1370884848 14285872 3408016
00:12:52 34796 8192000000 10301605760 10736099328 1380479520 14.421 100 45 90 0 1405478032 14100256 10898256
00:14:36 139301 8192000000 10292408192 10761584640 1359037200 14.229 98 31 62 1 1384222944 17619072 7566672
00:17:58 34133 8192000000 10301509424 10726064128 1365423056 14.286 99 44 84 3 1383086816 7810928 9852832
00:19:36 49757 8192000000 10299004480 10738294784 1377392272 14.393 100 38 76 4 1398765120 12576832 8796016
00:21:26 31021 8192000000 10294417600 10743001088 1374079680 14.364 99 24 48 0 1393062256 13308080 5674496
00:22:58 38884 8192000000 10304767440 10742054912 1388736672 14.495 100 55 109 0 1414800480 14108544 11955264
00:24:42 27151 8192000000 10301896512 10753970176 1393534048 14.537 100 16 31 0 1410467280 12810096 4123136
00:26:10 53798 8192000000 10294289568 10755313664 1397694304 14.574 100 7 14 0 1410284336 10767920 1822112
00:28:05 39448 8192000000 10304728560 10758082560 1392296032 14.526 99 23 47 0 1407114352 8530448 6287872
00:30:00 45418 8192000000 10296953104 10759122944 1387021088 14.479 99 15 30 1 1401736048 11467696 3247264
00:31:50 66932 8192000000 10276670704 10742194176 1354066496 14.184 97 7 14 0 1366299696 11018928 1214272
00:34:01 65143 8192000000 10288806784 10755289088 1392025744 14.524 100 15 30 0 1410467120 14681776 3759600
00:36:09 33201 8192000000 10307988848 10758070272 1395518992 14.555 100 26 49 1 1410710192 9297536 5893664
00:37:44 60048 8192000000 10308788128 10774052864 1402267088 14.615 99 18 36 0 1418829552 12475200 4087264
00:39:45 62510 8192000000 10316642704 10773291008 1386970832 14.479 98 14 28 0 1400580704 10733408 2876464
00:41:48 46679 8192000000 10271990528 10721361920 1321190192 13.887 94 19 38 2 1339611248 13887216 4533840
00:43:48 49820 8192000000 10280530896 10726604800 1351976576 14.165 98 27 52 1 1367877856 8785152 7116128
00:45:41 46631 8192000000 10264115296 10704416768 1335946416 14.021 98 34 68 1 1356357408 13079104 7331888
00:47:30 65563 8192000000 10291871008 10733441024 1336171584 14.023 97 18 36 1 1355580080 15098928 4309568
00:49:36 92708 8192000000 10258248080 10704711680 1342175344 14.077 99 10 20 0 1355506224 10780640 2550240
00:52:36 27951 8192000000 10243330736 10687176704 1317502960 13.854 98 35 68 1 1336453760 11290976 7659824
00:54:05 62872 8192000000 10247265568 10704117760 1340842832 14.065 99 8 17 0 1350329232 7836640 1649760
00:56:10 62409 8192000000 10237008688 10690039808 1329726144 13.965 99 7 14 0 1339988448 8633408 1628896
00:58:15 70366 8192000000 10259787744 10710892544 1358090784 14.220 100 11 22 0 1368753232 8065312 2597136
01:00:26 75907 8192000000 10254698816 10718294016 1341777280 14.073 98 14 28 0 1360005200 15100848 3127072
01:02:44 46615 8192000000 10272370816 10723844096 1349346688 14.142 98 22 44 0 1365491760 10964448 5180624
01:04:34 41728 8192000000 10264879232 10713104384 1350249152 14.150 99 17 34 0 1362465760 8500272 3716336
01:06:25 55527 8192000000 10275954256 10719141888 1343116416 14.085 98 18 36 1 1362223104 14485648 4621040
01:08:21 38006 8192000000 10264648224 10715480064 1339785888 14.055 98 18 36 0 1356011536 12453376 3772272
01:09:59 17156 8192000000 10289150320 10731388928 1366690352 14.297 99 16 32 0 1377186000 6976432 3519216
01:11:17 39702 8192000000 10307444608 10751696896 1380609824 14.422 99 29 58 0 1398962304 12047824 6304656
01:13:01 23148 8192000000 10302838864 10749267968 1384095456 14.453 99 31 62 1 1400405248 9274992 7034800
01:14:29 117539 8192000000 10290584656 10744979456 1355646320 14.198 97 35 70 1 1372632864 9199696 7786848
01:17:31 54831 8192000000 10281191232 10754506752 1396072448 14.560 100 18 35 1 1408507008 8535232 3899328
01:19:26 63876 8192000000 10279419712 10743631872 1374280112 14.365 99 10 20 0 1382877088 6169296 2427680
01:21:30 50712 8192000000 10288493856 10762784768 1380159792 14.418 99 12 25 0 1396494576 13766592 2568192
01:23:23 56955 8192000000 10266294304 10719440896 1311926144 13.804 94 19 38 0 1326811136 10658816 4226176
01:25:25 18291 8192000000 10295767552 10738561024 1375700208 14.378 100 39 79 1 1392900528 8389776 8810544
01:26:48 41885 8192000000 10248713232 10701365248 1314997008 13.831 97 22 44 0 1332232992 12221120 5014864
01:28:30 56278 8192000000 10256349728 10697281536 1319146160 13.869 98 20 40 0 1333098608 9520624 4431824
01:30:29 76444 8192000000 10259189632 10700308480 1282261392 13.534 94 16 32 0 1294757920 9286272 3210256
01:32:52 97079 8192000000 10283572944 10746454016 1345152208 14.104 98 22 44 0 1371181488 20701872 5327408
01:35:46 127543 8192000000 10251011488 10707570688 1297712144 13.674 95 24 48 0 1315136832 11804976 5619712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-04-2022 01:40:14 to 30-04-2022 01:40:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-04-2022 01:40:30 to 30-04-2022 01:40:32.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          246013
MON$OLDEST_ACTIVE               247144
MON$OLDEST_SNAPSHOT             247144
MON$NEXT_TRANSACTION            247144
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-03 16:41:55.3470
MON$PAGES                       2026528
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33580 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33580 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33580 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33580 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:53: 
:54: mscs-test-161.budzdorov.ru    Sat Apr 30 01:38:28 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
--- end of diff output ---

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

30.04.22 01:40:33. There are 3 logs in /var/tmp/logs-oltp30 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-04-30 01:40:36 No severe PSQL-related problems occured
30.04.22 01:40:37. End of report.