Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.2021 16: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16022212 kB
MemAvailable 30874376 kB
Buffers 0 kB
Cached 15694808 kB
SwapCached 4784 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.8
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 2021-10-28 16:37:54
DTS_END                         2021-10-28 16:37:53.7840
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/33382
TRN_ID                          246883
ATT_ID                          728
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: 9352.47 17257 1122296
customer order: creation 183.23 332 21988
customer order: refuse 15.68 358 1881
order to supplier: creation 48.03 1222 5763
order to supplier: removal 3.53 98139 424
invoice (draft): creation 48.85 3323 5862
invoice (draft): removal 52.60 778 6312
invoice accept: apply 43.25 19386 5190
invoice accept: cancel 47.33 831 5679
customer reserve: creation 3995.13 192 479416
customer reserve: removal 4609.35 58 553122
realization accept: apply 87.42 194 10490
realization accept: cancel 82.74 115 9929
payment from customer: creation 62.27 117 7472
payment from customer: removal 5.83 105 700
payment to supplier: creation 50.24 153 6029
payment to supplier: removal 3.83 59 460
service: total inventory turnovers 5.18 1000 621
service: total monetary turnovers 7.11 36 853
service: aggregate perf. data 0.84 23976 101
service: refresh index statistics 0.03 194768 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2021 16:39:34 to 28-10-2021 16:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2585
TEST_TIME 2 2796
TEST_TIME 3 3579
TEST_TIME 4 3634
TEST_TIME 5 3737
TEST_TIME 6 4248
TEST_TIME 7 4369
TEST_TIME 8 5008
TEST_TIME 9 4967
TEST_TIME 10 5246
TEST_TIME 11 5020
TEST_TIME 12 5632
TEST_TIME 13 5583
TEST_TIME 14 6081
TEST_TIME 15 6426
TEST_TIME 16 6832
TEST_TIME 17 7021
TEST_TIME 18 7378
TEST_TIME 19 7259
TEST_TIME 20 7420
TEST_TIME 21 7475
TEST_TIME 22 7936
TEST_TIME 23 8312
TEST_TIME 24 8415
TEST_TIME 25 9103
TEST_TIME 26 9046
TEST_TIME 27 9291
TEST_TIME 28 9602
TEST_TIME 29 9655
TEST_TIME 30 9523
TEST_TIME 31 10670
TEST_TIME 32 10633
TEST_TIME 33 10530
TEST_TIME 34 10586
TEST_TIME 35 10551
TEST_TIME 36 10494
TEST_TIME 37 10377
TEST_TIME 38 10310
TEST_TIME 39 10092
TEST_TIME 40 10161
TEST_TIME 41 10104
TEST_TIME 42 9908
TEST_TIME 43 9865
TEST_TIME 44 9781
TEST_TIME 45 9928
TEST_TIME 46 10005
TEST_TIME 47 9999
TEST_TIME 48 9894
TEST_TIME 49 9790
TEST_TIME 50 9644
TEST_TIME 51 9564
TEST_TIME 52 9716
TEST_TIME 53 9738
TEST_TIME 54 9680
TEST_TIME 55 9623
TEST_TIME 56 9520
TEST_TIME 57 9422
TEST_TIME 58 9421
TEST_TIME 59 9429
TEST_TIME 60 9385
TEST_TIME 61 9484
TEST_TIME 62 9618
TEST_TIME 63 9623
TEST_TIME 64 9671
TEST_TIME 65 9623
TEST_TIME 66 9612
TEST_TIME 67 9647
TEST_TIME 68 9633
TEST_TIME 69 9672
TEST_TIME 70 9669
TEST_TIME 71 9782
TEST_TIME 72 9780
TEST_TIME 73 9782
TEST_TIME 74 9720
TEST_TIME 75 9656
TEST_TIME 76 9588
TEST_TIME 77 9607
TEST_TIME 78 9590
TEST_TIME 79 9560
TEST_TIME 80 9529
TEST_TIME 81 9462
TEST_TIME 82 9446
TEST_TIME 83 9437
TEST_TIME 84 9466
TEST_TIME 85 9429
TEST_TIME 86 9468
TEST_TIME 87 9438
TEST_TIME 88 9403
TEST_TIME 89 9542
TEST_TIME 90 9528
TEST_TIME 91 9644
TEST_TIME 92 9617
TEST_TIME 93 9605
TEST_TIME 94 9596
TEST_TIME 95 9598
TEST_TIME 96 9618
TEST_TIME 97 9584
TEST_TIME 98 9531
TEST_TIME 99 9490
TEST_TIME 100 9447
TEST_TIME 101 9500
TEST_TIME 102 9475
TEST_TIME 103 9442
TEST_TIME 104 9409
TEST_TIME 105 9372
TEST_TIME 106 9375
TEST_TIME 107 9362
TEST_TIME 108 9420
TEST_TIME 109 9385
TEST_TIME 110 9389
TEST_TIME 111 9468
TEST_TIME 112 9440
TEST_TIME 113 9447
TEST_TIME 114 9458
TEST_TIME 115 9447
TEST_TIME 116 9476
TEST_TIME 117 9480
TEST_TIME 118 9446
TEST_TIME 119 9387
TEST_TIME 120 9354
TEST_TIME 121 9276

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-10-2021 16:39:38 to 28-10-2021 16:39:41.

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 1567308 1567308 0 0.00 0 40335 7 0 0
doc_list_biud 1567307 1567307 0 0.00 0 34656 27 0 0
sp_add_invoice_to_stock 5299 5190 109 2.05 54 162856 19386 0 109
sp_cancel_adding_invoice 5679 5679 0 0.00 4 66426 832 0 0
sp_cancel_client_order 1881 1881 0 0.00 4 14691 359 0 0
sp_cancel_customer_reserve 553122 553122 0 0.00 1 22379 59 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 13401 105 0 0
sp_cancel_pay_to_supplier 460 460 0 0.00 3 1911 60 0 0
sp_cancel_supplier_invoice 6320 6312 8 0.12 1 14050 778 0 8
sp_cancel_supplier_order 424 424 0 0.00 7 1135199 98140 0 0
sp_cancel_write_off 9929 9929 0 0.00 1 18920 115 0 0
sp_client_order 21988 21988 0 0.00 3 15129 333 0 0
sp_customer_reserve 479416 479416 0 0.00 0 78480 192 0 0
sp_fill_shopping_cart 33609 33609 0 0.00 1 15827 33 0 0
sp_fill_shopping_cart_clo_res 479414 479414 0 0.00 0 15577 14 0 0
sp_get_clo_for_invoice 5187 5187 0 0.00 0 33087 309 0 0
sp_kill_cost_storno 16768 16768 0 0.00 0 13391 58 0 0
sp_kill_qstorno_ret_qs2qd 1122909 1122909 0 0.00 0 22254 32 0 0
sp_kill_qty_storno 593024 593024 0 0.00 0 34656 69 0 0
sp_lock_selected_doc 21760 21760 0 0.00 0 9860 5 0 0
sp_make_cost_storno 29178 29178 0 0.00 0 40267 70 0 0
sp_multiply_rows_for_pdistr 29178 29178 0 0.00 0 18329 27 0 0
sp_multiply_rows_for_qdistr 21988 21988 0 0.00 0 11044 152 0 0
sp_pay_from_customer 7472 7472 0 0.00 4 20066 117 0 0
sp_pay_to_supplier 6029 6029 0 0.00 4 19523 153 0 0
sp_payment_common 13501 13501 0 0.00 1 20002 46 0 0
sp_qd_handle_on_cancel_clo 1881 1881 0 0.00 0 14681 336 0 0
sp_qd_handle_on_invoice_upd_sts 10866 10866 0 0.00 0 34655 334 0 0
sp_qd_handle_on_reserve_upd_sts 10490 10490 0 0.00 0 24297 65 0 0
sp_reserve_write_off 10490 10490 0 0.00 6 24315 194 0 0
sp_supplier_invoice 5862 5862 0 0.00 6 99981 3324 0 0
sp_supplier_order 5763 5763 0 0.00 5 17602 1222 0 0
srv_aggregate_perf_data 101 101 0 0.00 2369 503132 23977 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1465 116290 45884 0 0
srv_find_qd_qs_mism 1072838 1072838 0 0.00 0 34668 6 0 0
srv_make_invnt_saldo 621 621 0 0.00 28 80480 1001 0 0
srv_make_money_saldo 853 853 0 0.00 1 8979 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 95322 285210 194768 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5190 5190 0 0.00 0 17941 183 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 3971 116 0 0
t$perf-norm:sp_cancel_client_order 1881 1881 0 0.00 0 2426 9 0 0
t$perf-norm:sp_cancel_customer_reserve 769 769 0 0.00 0 681 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 3475 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 460 460 0 0.00 0 2122 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 721 12 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 22266 1732 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 1031 6 0 0
t$perf-norm:sp_client_order 21988 21988 0 0.00 0 13568 18 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 1276 6 0 0
t$perf-norm:sp_pay_from_customer 7472 7472 0 0.00 0 8082 12 0 0
t$perf-norm:sp_pay_to_supplier 6029 6029 0 0.00 0 10668 14 0 0
t$perf-norm:sp_reserve_write_off 10490 10490 0 0.00 0 7891 14 0 0
t$perf-norm:sp_supplier_invoice 5862 5862 0 0.00 0 6893 20 0 0
t$perf-norm:sp_supplier_order 5763 5763 0 0.00 0 3952 15 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1153 43 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 67 2 0 0
t$perf-norm:srv_make_invnt_saldo 621 621 0 0.00 0 2660 23 0 0
t$perf-norm:srv_make_money_saldo 853 853 0 0.00 0 2725 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10592 10374 218 2.05 0 5888 18 0 218
v_all_customers 40234 40234 0 0.00 0 20 1 0 0
v_all_suppliers 31612 31612 0 0.00 0 21 1 0 0
v_all_wares 43974 43974 0 0.00 0 89 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 514 7 0 0
v_cancel_client_order 3762 3762 0 0.00 0 1784 3 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 3759 7 0 0
v_cancel_customer_reserve 1538 1538 0 0.00 0 683 3 0 0
v_cancel_payment_to_supplier 920 920 0 0.00 0 1715 5 0 0
v_cancel_supplier_invoice 852 836 16 1.87 0 184 11 0 16
v_cancel_supplier_order 848 848 0 0.00 0 2279 8 0 0
v_cancel_write_off 1980 1980 0 0.00 0 3239 17 0 0
v_max_id_clo_ord 5763 5763 0 0.00 0 9763 3 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 1450 12 0 0
v_max_id_ord_sup 5859 5859 0 0.00 0 9971 6 0 0
v_max_non_paid_invoice 1845 1845 0 0.00 0 304 2 0 0
v_max_non_paid_realizn 3881 3881 0 0.00 0 2452 5 0 0
v_min_id_clo_ord 5763 5763 0 0.00 0 9456 8 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 3349 11 0 0
v_min_id_ord_sup 5859 5859 0 0.00 0 5851 6 0 0
v_min_non_paid_invoice 6029 6029 0 0.00 0 18387 46 0 0
v_min_non_paid_realizn 7472 7472 0 0.00 0 18341 31 0 0
v_our_firm 10922 10922 0 0.00 0 24 1 0 0
v_reserve_write_off 20976 20976 0 0.00 0 2841 4 0 0
x_lock_depdocs_on_canc_invoice 11573 11573 0 0.00 0 18794 122 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 11590 375 0 0
x_make_qstorno_1000_1200 5763 5763 0 0.00 1 17589 1136 0 0
x_make_qstorno_1200_2000 5862 5862 0 0.00 2 99956 3219 0 0
x_make_qstorno_2100_3300 479416 479416 0 0.00 0 78477 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2021 16:39:42 to 28-10-2021 16:39:46.

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
14:07:57 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:08:58 30672 8192000000 9903036608 10218078208 978820528 10.673 100 81 162 0 1005488080 5806880 20860672
14:10:33 19327 8192000000 9953199040 10277502976 1034819632 11.215 100 90 180 1 1066543360 8446320 23277408
14:11:54 20318 8192000000 9976949584 10308382720 1056230928 11.420 100 85 170 0 1087593072 9796416 21565728
14:13:18 10803 8192000000 10022922688 10372149248 1110831840 11.940 100 82 164 0 1140890832 10669776 19389216
14:14:32 7547 8192000000 10071615504 10430451712 1160259216 12.406 100 78 157 1 1190240944 12247712 17734016
14:15:44 5849 8192000000 10109346208 10478665728 1202284720 12.798 100 81 163 1 1232854032 12650368 17918944
14:16:52 5330 8192000000 10151153888 10524696576 1232621072 13.078 100 75 150 0 1262405360 13130864 16653424
14:18:00 3041 8192000000 10192304000 10574176256 1275795168 13.475 100 90 181 2 1307954832 11789440 20370224
14:19:06 1262 8192000000 10206117760 10591469568 1291968336 13.622 100 89 174 2 1326144528 14176272 19999920
14:20:09 4216 8192000000 10205237840 10603139072 1299788896 13.693 100 54 108 1 1322525184 10850064 11886224
14:21:14 7355 8192000000 10195760944 10603671552 1304703248 13.738 100 41 82 2 1321847584 8287504 8856832
14:22:22 15647 8192000000 10204802288 10622722048 1315086560 13.832 100 18 36 0 1329520304 10675712 3758032
14:23:43 48889 8192000000 10210253664 10632937472 1321070256 13.886 100 37 72 1 1335057792 5758544 8228992
14:25:36 4780 8192000000 10218193920 10636947456 1326266848 13.933 100 28 56 0 1338688736 5436976 6984912
14:26:42 9924 8192000000 10211872736 10629537792 1311646672 13.801 99 19 38 0 1320816016 5095008 4074336
14:27:54 11272 8192000000 10192221488 10603454464 1254315104 13.278 95 14 28 2 1266987264 9369520 3302640
14:29:07 18775 8192000000 10185394272 10597089280 1252974640 13.266 96 44 87 1 1270243312 7000480 10268192
14:30:26 7466 8192000000 10153082144 10563309568 1248149136 13.221 98 21 42 1 1258970112 6187728 4633248
14:31:35 4641 8192000000 10116705424 10515021824 1183540880 12.623 95 19 35 1 1193128736 4725200 4862656
14:32:43 9280 8192000000 10162770896 10569510912 1257063232 13.303 99 26 52 0 1269457184 6263888 6130064
14:33:54 28654 8192000000 10158721856 10572161024 1262941376 13.357 99 22 44 0 1273220800 4719264 5560160
14:35:25 3691 8192000000 10170648096 10584047616 1283747824 13.547 100 22 44 0 1295574224 6464144 5362256
14:36:29 10651 8192000000 10192603968 10608676864 1305709520 13.747 100 21 41 0 1315562896 5268784 4584592
14:37:41 10842 8192000000 10210269536 10632372224 1324981392 13.922 100 20 39 2 1335246368 5706864 4558112
14:38:55 55843 8192000000 10246092896 10662727680 1343002384 14.084 100 44 88 3 1363862960 11103120 9757456
14:40:55 26939 8192000000 10268734464 10684788736 1356103264 14.202 100 59 118 1 1382068368 12795296 13169808
14:42:28 28744 8192000000 10279079440 10701467648 1370977104 14.336 100 34 68 3 1394149360 14706688 8465568
14:44:01 14043 8192000000 10298268176 10718244864 1373222400 14.356 99 58 115 1 1407209088 19543296 14443392
14:45:31 6874 8192000000 10329401584 10747187200 1402637808 14.618 100 56 109 0 1433828672 18967536 12223328
14:46:45 11838 8192000000 10344012688 10762428416 1412777312 14.709 100 60 120 3 1450666800 23980736 13908752
14:48:01 21311 8192000000 10322719120 10754531328 1398169664 14.579 99 32 63 2 1429047952 22939616 7938672
14:49:37 2703 8192000000 10335961520 10758750208 1409113136 14.676 100 60 118 1 1444856624 21087712 14655776
14:50:44 7122 8192000000 10349432192 10765348864 1403430064 14.626 99 77 154 0 1448625088 26976816 18218208
14:51:55 6992 8192000000 10329749024 10740224000 1376833680 14.388 98 76 151 3 1423026400 27783104 18409616
14:53:10 4022 8192000000 10335627024 10745110528 1386545392 14.475 100 81 159 2 1437066208 31938368 18582448
14:54:20 4278 8192000000 10340257952 10756722688 1394882096 14.549 100 67 133 0 1444085232 32969968 16233168
14:55:30 2521 8192000000 10366076960 10776879104 1412044704 14.702 100 85 170 1 1467606432 34883744 20677984
14:56:47 4781 8192000000 10350217952 10762227712 1396170800 14.561 100 67 134 1 1451308848 37552368 17585680
14:57:57 4167 8192000000 10361447056 10776723456 1400274816 14.597 100 70 139 1 1462939200 44427792 18236592
14:59:11 2791 8192000000 10364459440 10774233088 1389047680 14.497 100 77 154 0 1462140416 53618400 19474336
15:00:19 15735 8192000000 10316199552 10745221120 1361297264 14.249 99 35 70 2 1414391104 43611232 9482608
15:01:38 17437 8192000000 10307816304 10735898624 1357615632 14.216 100 31 62 1 1414360848 47533136 9212080
15:03:10 13177 8192000000 10297952032 10736848896 1358204208 14.221 100 31 62 0 1405220944 38601040 8415696
15:04:28 16414 8192000000 10321836080 10757976064 1373688608 14.360 100 31 62 2 1428334560 45981984 8663968
15:05:46 29135 8192000000 10324705696 10773217280 1381598256 14.431 100 21 42 2 1428202576 40639696 5964624
15:07:42 47048 8192000000 10317771184 10782740480 1377394192 14.393 100 22 43 1 1438470448 54456128 6620128
15:09:37 66977 8192000000 10290093296 10730172416 1361655584 14.252 99 21 42 1 1381154560 14566256 4932720
15:12:08 116290 8192000000 10274896064 10713935872 1302128256 13.715 94 13 26 0 1317925952 13289888 2507808
15:15:49 90742 8192000000 10292204512 10733027328 1365976272 14.291 99 44 88 1 1388481568 11927520 10577776
15:18:43 85373 8192000000 10296064368 10746257408 1362094896 14.256 98 18 36 0 1377989264 12150784 3743584
15:21:10 43796 8192000000 10306296992 10756079616 1376775536 14.388 99 30 58 3 1400336016 15808624 7751856
15:22:59 48247 8192000000 10266368944 10720714752 1346734144 14.118 97 20 41 1 1361978032 10613520 4630368
15:24:54 48028 8192000000 10283659568 10740596736 1369918752 14.326 99 18 34 1 1382547408 9081488 3547168
15:26:52 43576 8192000000 10295189088 10753781760 1394609344 14.547 100 14 27 1 1410039840 11784768 3645728
15:28:37 69636 8192000000 10267473024 10737840128 1357892640 14.218 98 17 34 0 1370862400 8373840 4595920
15:30:53 33743 8192000000 10270403520 10722840576 1356686496 14.208 99 26 50 2 1372536320 9850240 5999584
15:32:28 43878 8192000000 10278148608 10739027968 1371921776 14.344 99 30 58 1 1388815120 10084496 6808848
15:34:26 93231 8192000000 10269531584 10739105792 1336313936 14.024 97 24 47 1 1353984720 12494000 5176784
15:37:00 50769 8192000000 10290597344 10753884160 1376684592 14.387 100 17 34 0 1393204384 12724784 3795008
15:38:51 63270 8192000000 10260613888 10725892096 1297934032 13.676 94 12 24 0 1313152720 12932192 2286496
15:40:55 54359 8192000000 10263117744 10714169344 1309160608 13.778 96 21 42 0 1323550272 9887872 4501792
15:42:55 10987 8192000000 10267444064 10705866752 1343545104 14.089 99 47 94 0 1362985584 8505072 10935408
15:44:09 18351 8192000000 10285280112 10724532224 1356535904 14.206 99 34 68 2 1374957856 11220928 7201024
15:45:28 26354 8192000000 10247328704 10678919168 1283650160 13.546 95 41 82 1 1304571088 12131200 8789728
15:46:56 53771 8192000000 10256773552 10692005888 1314585296 13.828 97 40 80 1 1336205376 12767312 8852768
15:48:51 86366 8192000000 10231377296 10672844800 1270934976 13.430 94 12 24 0 1286594112 13325792 2333344
15:51:26 69112 8192000000 10257175424 10702307328 1315709360 13.838 97 28 55 0 1329644416 7718624 6216432
15:53:42 1464 8192000000 10261155472 10697203712 1349312880 14.141 100 54 107 0 1371166064 9223184 12630000
15:54:44 57778 8192000000 10252245840 10716176384 1342773008 14.082 99 18 36 0 1361332944 14109344 4450592
15:56:42 68670 8192000000 10247875040 10708766720 1353419152 14.178 100 9 18 1 1366536112 10969360 2147600
15:59:07 76087 8192000000 10291881264 10732789760 1355327584 14.195 99 16 31 0 1371348992 12234128 3787280
16:01:24 63434 8192000000 10275428832 10732982272 1346052352 14.112 98 11 22 0 1357173104 8434272 2686480
16:03:31 71952 8192000000 10298091104 10743001088 1361952416 14.255 99 30 60 0 1383094096 14642944 6498736
16:06:08 86907 8192000000 10300959744 10750664704 1353515840 14.179 98 9 18 1 1369037296 13516864 2004592
16:08:35 54874 8192000000 10299915040 10765864960 1387091760 14.480 100 8 15 1 1407059328 18346720 1620848
16:10:30 77187 8192000000 10293704288 10736013312 1354522704 14.188 97 35 70 3 1372447888 9718128 8207056
16:12:55 18492 8192000000 10298368864 10739957760 1369777296 14.325 99 36 71 2 1388623600 10591472 8254832
16:14:16 91450 8192000000 10290384992 10744401920 1370527168 14.332 99 19 38 1 1387193344 12358720 4307456
16:16:48 13703 8192000000 10295649840 10739617792 1377815792 14.397 99 35 70 2 1395244976 9761040 7668144
16:18:03 43884 8192000000 10270058848 10745360384 1345126256 14.104 97 8 16 0 1358013504 11496224 1391024
16:19:47 60861 8192000000 10260183168 10715062272 1338947056 14.048 97 13 26 0 1352100688 10388576 2765056
16:21:48 110138 8192000000 10293298960 10758459392 1379821088 14.415 100 15 30 0 1393773824 10442000 3510736
16:24:39 97687 8192000000 10297294864 10748854272 1365879936 14.290 98 27 54 0 1391781072 20208192 5692944
16:27:18 71588 8192000000 10294605536 10765529088 1377070464 14.390 99 17 34 0 1398178672 17002064 4106144
16:29:31 21326 8192000000 10275643936 10717364224 1349512192 14.143 98 32 64 0 1362728368 6030912 7185264
16:31:03 66613 8192000000 10281889376 10725867520 1331865856 13.984 97 28 56 0 1351343344 13053504 6423984
16:33:18 108368 8192000000 10250843280 10691129344 1250311264 13.241 92 18 36 0 1264256128 10204784 3740080
16:36:07 113251 8192000000 10112857616 10543943680 1212540288 12.893 90 37 74 0 1229361296 8724720 8096288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-10-2021 16:39:48 to 28-10-2021 16:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-10-2021 16:40:04 to 28-10-2021 16:40:06.

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          246523
MON$OLDEST_ACTIVE               247718
MON$OLDEST_SNAPSHOT             247718
MON$NEXT_TRANSACTION            247718
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                       2039776
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

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

28.10.21 16:40:07. 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
2021-10-28 16:40:09 No severe PSQL-related problems occured
28.10.21 16:40:10. End of report.