Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.2022 19:38
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 16274864 kB
MemAvailable 31288520 kB
Buffers 0 kB
Cached 15369628 kB
SwapCached 1200 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.9
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-01-05 19:37:57
DTS_END                         2022-01-05 19:37:57.5710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55640
TRN_ID                          246181
ATT_ID                          663
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: 9295.98 23542 1115517
customer order: creation 177.61 321 21313
customer order: refuse 14.95 325 1794
order to supplier: creation 47.55 1300 5706
order to supplier: removal 3.62 92612 434
invoice (draft): creation 47.86 3514 5743
invoice (draft): removal 54.26 752 6511
invoice accept: apply 41.92 16819 5030
invoice accept: cancel 48.57 855 5828
customer reserve: creation 3800.98 168 456118
customer reserve: removal 4754.69 55 570563
realization accept: apply 85.98 212 10317
realization accept: cancel 83.78 126 10054
payment from customer: creation 61.58 133 7390
payment from customer: removal 5.84 69 701
payment to supplier: creation 50.76 160 6091
payment to supplier: removal 3.39 68 407
service: total inventory turnovers 4.84 1188 581
service: total monetary turnovers 6.83 47 820
service: aggregate perf. data 0.93 18270 112
service: refresh index statistics 0.03 333844 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-01-2022 19:39:05 to 05-01-2022 19:39:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2322
TEST_TIME 2 3544
TEST_TIME 3 3563
TEST_TIME 4 3723
TEST_TIME 5 3876
TEST_TIME 6 4215
TEST_TIME 7 4636
TEST_TIME 8 4610
TEST_TIME 9 4455
TEST_TIME 10 4872
TEST_TIME 11 5310
TEST_TIME 12 5332
TEST_TIME 13 5945
TEST_TIME 14 6657
TEST_TIME 15 6909
TEST_TIME 16 6957
TEST_TIME 17 7307
TEST_TIME 18 7606
TEST_TIME 19 8591
TEST_TIME 20 8549
TEST_TIME 21 8601
TEST_TIME 22 8697
TEST_TIME 23 8944
TEST_TIME 24 9128
TEST_TIME 25 9438
TEST_TIME 26 9508
TEST_TIME 27 9555
TEST_TIME 28 9289
TEST_TIME 29 9249
TEST_TIME 30 9256
TEST_TIME 31 9688
TEST_TIME 32 10372
TEST_TIME 33 10324
TEST_TIME 34 10146
TEST_TIME 35 10158
TEST_TIME 36 10169
TEST_TIME 37 10220
TEST_TIME 38 10229
TEST_TIME 39 10036
TEST_TIME 40 10032
TEST_TIME 41 10120
TEST_TIME 42 10061
TEST_TIME 43 10029
TEST_TIME 44 9955
TEST_TIME 45 9977
TEST_TIME 46 9909
TEST_TIME 47 9713
TEST_TIME 48 9757
TEST_TIME 49 9732
TEST_TIME 50 9728
TEST_TIME 51 9582
TEST_TIME 52 9614
TEST_TIME 53 9563
TEST_TIME 54 9465
TEST_TIME 55 9613
TEST_TIME 56 9605
TEST_TIME 57 9531
TEST_TIME 58 9521
TEST_TIME 59 9528
TEST_TIME 60 9411
TEST_TIME 61 9356
TEST_TIME 62 9408
TEST_TIME 63 9357
TEST_TIME 64 9286
TEST_TIME 65 9322
TEST_TIME 66 9325
TEST_TIME 67 9279
TEST_TIME 68 9246
TEST_TIME 69 9378
TEST_TIME 70 9459
TEST_TIME 71 9402
TEST_TIME 72 9499
TEST_TIME 73 9470
TEST_TIME 74 9497
TEST_TIME 75 9535
TEST_TIME 76 9617
TEST_TIME 77 9570
TEST_TIME 78 9583
TEST_TIME 79 9582
TEST_TIME 80 9481
TEST_TIME 81 9457
TEST_TIME 82 9453
TEST_TIME 83 9447
TEST_TIME 84 9416
TEST_TIME 85 9403
TEST_TIME 86 9391
TEST_TIME 87 9393
TEST_TIME 88 9320
TEST_TIME 89 9273
TEST_TIME 90 9315
TEST_TIME 91 9331
TEST_TIME 92 9362
TEST_TIME 93 9371
TEST_TIME 94 9376
TEST_TIME 95 9413
TEST_TIME 96 9492
TEST_TIME 97 9448
TEST_TIME 98 9462
TEST_TIME 99 9449
TEST_TIME 100 9498
TEST_TIME 101 9507
TEST_TIME 102 9551
TEST_TIME 103 9518
TEST_TIME 104 9589
TEST_TIME 105 9578
TEST_TIME 106 9513
TEST_TIME 107 9462
TEST_TIME 108 9443
TEST_TIME 109 9447
TEST_TIME 110 9432
TEST_TIME 111 9392
TEST_TIME 112 9358
TEST_TIME 113 9340
TEST_TIME 114 9310
TEST_TIME 115 9296
TEST_TIME 116 9304
TEST_TIME 117 9269
TEST_TIME 118 9314
TEST_TIME 119 9324
TEST_TIME 120 9298

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-01-2022 19:39:09 to 05-01-2022 19:39:12.

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 1539711 1539711 0 0.00 0 71957 6 0 0
doc_list_biud 1539711 1539711 0 0.00 0 47498 26 0 0
sp_add_invoice_to_stock 5118 5030 88 1.71 31 152326 16819 0 88
sp_cancel_adding_invoice 5828 5828 0 0.00 4 68137 855 0 0
sp_cancel_client_order 1794 1794 0 0.00 4 9596 325 0 0
sp_cancel_customer_reserve 570563 570563 0 0.00 1 19294 55 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 4260 70 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 2 3484 68 0 0
sp_cancel_supplier_invoice 6524 6511 13 0.19 1 47504 752 0 13
sp_cancel_supplier_order 434 434 0 0.00 8 1021230 92613 0 0
sp_cancel_write_off 10054 10054 0 0.00 1 30242 126 0 0
sp_client_order 21313 21313 0 0.00 3 14331 322 0 0
sp_customer_reserve 456118 456118 0 0.00 0 78489 168 0 0
sp_fill_shopping_cart 32760 32760 0 0.00 1 25661 41 0 0
sp_fill_shopping_cart_clo_res 456112 456112 0 0.00 0 11686 12 0 0
sp_get_clo_for_invoice 5024 5024 0 0.00 0 22383 266 0 0
sp_kill_cost_storno 16990 16990 0 0.00 0 21091 62 0 0
sp_kill_qstorno_ret_qs2qd 1158125 1158125 0 0.00 0 47496 30 0 0
sp_kill_qty_storno 610525 610525 0 0.00 0 47498 66 0 0
sp_lock_selected_doc 21365 21365 0 0.00 0 8318 6 0 0
sp_make_cost_storno 28822 28822 0 0.00 0 71933 62 0 0
sp_multiply_rows_for_pdistr 28822 28822 0 0.00 0 32172 38 0 0
sp_multiply_rows_for_qdistr 21313 21313 0 0.00 0 12745 146 0 0
sp_pay_from_customer 7390 7390 0 0.00 4 33256 133 0 0
sp_pay_to_supplier 6091 6091 0 0.00 4 43611 161 0 0
sp_payment_common 13481 13481 0 0.00 1 33107 46 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 9590 305 0 0
sp_qd_handle_on_invoice_upd_sts 10852 10852 0 0.00 0 36601 359 0 0
sp_qd_handle_on_reserve_upd_sts 10317 10317 0 0.00 0 43448 80 0 0
sp_reserve_write_off 10317 10317 0 0.00 6 43461 213 0 0
sp_supplier_invoice 5743 5743 0 0.00 6 115135 3515 0 0
sp_supplier_order 5706 5706 0 0.00 4 51724 1300 0 0
srv_aggregate_perf_data 112 112 0 0.00 2937 220875 18271 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 954 113768 45521 0 0
srv_find_qd_qs_mism 1067825 1067825 0 0.00 0 14170 5 0 0
srv_make_invnt_saldo 581 581 0 0.00 26 64706 1188 0 0
srv_make_money_saldo 820 820 0 0.00 1 9412 48 0 0
srv_recalc_idx_stat 4 4 0 0.00 211953 508589 333844 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5030 5030 0 0.00 0 6594 165 0 0
t$perf-norm:sp_cancel_adding_invoice 471 471 0 0.00 0 4984 101 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 4818 15 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 2799 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 1627 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 4241 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 1625 14 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 28107 1740 0 0
t$perf-norm:sp_cancel_write_off 1113 1113 0 0.00 0 3909 22 0 0
t$perf-norm:sp_client_order 21313 21313 0 0.00 0 10605 19 0 0
t$perf-norm:sp_customer_reserve 1104 1104 0 0.00 0 4041 8 0 0
t$perf-norm:sp_pay_from_customer 7390 7390 0 0.00 0 6172 13 0 0
t$perf-norm:sp_pay_to_supplier 6091 6091 0 0.00 0 3705 10 0 0
t$perf-norm:sp_reserve_write_off 10317 10317 0 0.00 0 12325 18 0 0
t$perf-norm:sp_supplier_invoice 5743 5743 0 0.00 0 9738 27 0 0
t$perf-norm:sp_supplier_order 5706 5706 0 0.00 0 2783 15 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 353 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 23 1 0 0
t$perf-norm:srv_make_invnt_saldo 581 581 0 0.00 0 2619 26 0 0
t$perf-norm:srv_make_money_saldo 820 820 0 0.00 0 1574 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10224 10048 176 1.72 0 4137 15 0 176
v_all_customers 38492 38492 0 0.00 0 110 1 0 0
v_all_suppliers 31648 31648 0 0.00 0 117 1 0 0
v_all_wares 42626 42626 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 942 942 0 0.00 0 1224 10 0 0
v_cancel_client_order 3588 3588 0 0.00 0 2158 5 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 552 3 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 2001 7 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 3133 7 0 0
v_cancel_supplier_invoice 830 804 26 3.13 0 391 13 0 26
v_cancel_supplier_order 868 868 0 0.00 0 243 3 0 0
v_cancel_write_off 2226 2226 0 0.00 0 4926 16 0 0
v_max_id_clo_ord 5706 5706 0 0.00 0 2892 2 0 0
v_max_id_clo_res 1104 1104 0 0.00 0 2233 12 0 0
v_max_id_ord_sup 5741 5741 0 0.00 0 6204 7 0 0
v_max_non_paid_invoice 1714 1714 0 0.00 0 1078 6 0 0
v_max_non_paid_realizn 4063 4063 0 0.00 0 17514 10 0 0
v_min_id_clo_ord 5706 5706 0 0.00 0 12564 16 0 0
v_min_id_clo_res 1104 1104 0 0.00 0 6654 9 0 0
v_min_id_ord_sup 5741 5741 0 0.00 0 12445 17 0 0
v_min_non_paid_invoice 6091 6091 0 0.00 0 24703 40 0 0
v_min_non_paid_realizn 7390 7390 0 0.00 0 25533 31 0 0
v_our_firm 10788 10788 0 0.00 0 152 1 0 0
v_reserve_write_off 20634 20634 0 0.00 0 4114 5 0 0
x_lock_depdocs_on_canc_invoice 11937 11937 0 0.00 0 14208 125 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 19445 356 0 0
x_make_qstorno_1000_1200 5706 5706 0 0.00 1 51710 1209 0 0
x_make_qstorno_1200_2000 5743 5743 0 0.00 2 109092 3372 0 0
x_make_qstorno_2100_3300 456118 456118 0 0.00 0 78488 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2022 19:39:13 to 05-01-2022 19:39:17.

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
17:08:01 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:02 26132 8192000000 9914082224 10228101120 996802512 10.848 100 89 178 0 1025240784 6022720 22415552
17:10:46 22822 8192000000 9963860704 10286190592 1037127056 11.237 100 89 178 0 1067955568 8378400 22450112
17:12:12 16210 8192000000 9996726736 10333335552 1079532816 11.643 100 84 168 1 1109334496 9226496 20575184
17:13:35 11252 8192000000 10040448624 10393563136 1128749824 12.110 100 74 148 0 1155997104 9810736 17436544
17:14:50 7173 8192000000 10085085216 10446217216 1175250160 12.546 100 75 150 1 1205413168 13078640 17084368
17:16:00 5687 8192000000 10131950864 10501902336 1217650544 12.940 100 83 166 0 1250687344 14795248 18241552
17:17:08 5952 8192000000 10161485456 10545025024 1249630928 13.235 100 77 154 0 1280573952 14102448 16840576
17:18:15 1696 8192000000 10196565504 10582929408 1282870304 13.539 100 89 179 0 1320040752 17192000 19978448
17:19:19 34487 8192000000 10205508160 10605187072 1303386960 13.726 100 59 116 2 1326674768 10237760 13050048
17:20:56 5216 8192000000 10200053232 10611613696 1309878560 13.785 100 33 66 1 1324299792 6945920 7475312
17:22:02 6033 8192000000 10214448832 10629136384 1322829472 13.902 100 35 70 3 1339435792 8594368 8011952
17:23:11 15057 8192000000 10211981216 10634817536 1330455232 13.971 100 32 64 1 1343994256 5970272 7568752
17:24:27 70938 8192000000 10221552048 10639294464 1325334160 13.925 99 36 71 0 1342629648 8910624 8384864
17:26:38 6575 8192000000 10238089536 10660421632 1337631376 14.036 100 27 54 0 1353638000 9968208 6038416
17:27:49 7411 8192000000 10216518960 10637500416 1315957872 13.840 99 26 52 1 1327254624 5728480 5568272
17:28:58 46256 8192000000 10183140992 10594336768 1250242112 13.240 96 31 62 0 1264143664 6318464 7583088
17:30:45 8150 8192000000 10167966592 10567376896 1245869136 13.200 98 33 66 1 1260107776 6932416 7306224
17:31:56 8356 8192000000 10148580864 10548662272 1245251184 13.195 99 26 52 2 1258268992 6318528 6699280
17:33:07 6899 8192000000 10164842752 10562101248 1264593056 13.372 100 28 56 0 1276499648 4833408 7073184
17:34:14 46351 8192000000 10169071616 10583126016 1273049488 13.450 99 31 62 1 1286560848 6396176 7115184
17:36:07 8805 8192000000 10195141136 10606698496 1298625968 13.683 100 29 58 1 1311271936 6215296 6430672
17:37:20 15055 8192000000 10191186272 10613682176 1306709536 13.756 100 19 38 3 1316106304 5100032 4296736
17:38:41 113767 8192000000 10258149360 10676891648 1339133888 14.050 100 36 72 1 1357456496 9649024 8673584
17:41:38 13415 8192000000 10254096048 10679336960 1354416144 14.187 100 32 64 0 1375258448 13490912 7351392
17:42:54 8275 8192000000 10279710608 10702594048 1369250384 14.320 100 43 83 0 1394796896 15960832 9585680
17:44:16 37221 8192000000 10287873200 10716176384 1380006656 14.417 100 40 80 0 1408082704 18397472 9678576
17:45:58 9706 8192000000 10321247264 10743357440 1395119024 14.552 100 51 101 3 1424014752 16811200 12084528
17:47:15 12072 8192000000 10324921216 10746081280 1398803552 14.584 100 58 113 1 1436890384 24051136 14035696
17:48:30 4986 8192000000 10331138064 10751639552 1389010176 14.497 99 70 140 0 1429201888 23038576 17153136
17:49:37 2860 8192000000 10347857520 10766159872 1401663792 14.610 99 66 133 2 1446027840 28160208 16203840
17:50:45 3863 8192000000 10357985856 10766565376 1404419328 14.634 100 82 162 1 1458807072 33976208 20411536
17:51:52 953 8192000000 10335089184 10742128640 1385816096 14.469 100 90 176 2 1436900240 29369600 21714544
17:52:57 3577 8192000000 10302248736 10704814080 1354701088 14.190 99 76 150 4 1401748400 28517216 18530096
17:54:06 8133 8192000000 10320319120 10738171904 1366243872 14.293 99 62 123 3 1416497984 34806816 15447296
17:55:20 6723 8192000000 10315472416 10715078656 1356492304 14.206 100 84 167 1 1416212704 38111648 21608752
17:56:30 4492 8192000000 10287428464 10708451328 1344900160 14.102 98 64 126 2 1390448880 30058432 15490288
17:57:39 10007 8192000000 10281030160 10698403840 1332512304 13.990 98 43 86 1 1369256256 26102688 10641264
17:58:54 11379 8192000000 10320284336 10746966016 1380037232 14.417 100 35 70 1 1420767680 31622032 9108416
18:00:14 12849 8192000000 10301022544 10732179456 1356268880 14.204 99 25 47 1 1395941872 32820464 6852528
18:01:32 18649 8192000000 10313048640 10746892288 1385953632 14.470 100 25 50 1 1423650272 31048352 6648288
18:03:04 20008 8192000000 10317323696 10754052096 1379943712 14.416 100 39 78 1 1424202960 33272320 10986928
18:04:29 28351 8192000000 10316544848 10744803328 1372382720 14.348 99 36 72 2 1410750096 28922912 9444464
18:06:05 35598 8192000000 10313921712 10753998848 1377528864 14.394 100 28 54 3 1421420896 35872800 8019232
18:08:22 40622 8192000000 10299401856 10745729024 1354719984 14.190 99 17 34 0 1397492144 38217360 4554800
18:10:26 81636 8192000000 10295833808 10739937280 1375525152 14.377 99 15 30 0 1393617344 14820560 3271632
18:12:48 39342 8192000000 10341598960 10766815232 1399377280 14.589 100 48 94 1 1420276112 9250400 11648432
18:14:28 109522 8192000000 10310315984 10764328960 1356419168 14.205 97 7 14 0 1371411008 13516464 1475376
18:17:31 58894 8192000000 10312433888 10780835840 1404292480 14.633 100 11 22 0 1420021776 13021712 2707584
18:19:42 108632 8192000000 10314664160 10769903616 1382090704 14.435 98 12 23 1 1392950304 8135712 2723888
18:22:42 62176 8192000000 10306362752 10766700544 1386193904 14.472 99 13 26 0 1399590384 9801728 3594752
18:24:44 106585 8192000000 10315312208 10777329664 1406669760 14.654 100 33 66 0 1427047328 12078704 8298864
18:27:36 29075 8192000000 10308848896 10775818240 1404412768 14.634 100 12 24 0 1418914752 11855104 2646880
18:29:26 34065 8192000000 10321403664 10777124864 1412189184 14.703 100 43 84 1 1434561568 12886688 9485696
18:31:03 64606 8192000000 10309788608 10752921600 1355698512 14.199 96 26 52 0 1375805792 13309440 6797840
18:33:14 38161 8192000000 10278327088 10732822528 1349121104 14.140 97 18 36 0 1363118320 9638592 4358624
18:34:53 57159 8192000000 10276266144 10736443392 1340658992 14.063 97 9 18 1 1353421392 10449536 2312864
18:37:27 85091 8192000000 10269833152 10726195200 1357734016 14.217 98 14 28 0 1373417632 12225712 3457904
18:39:55 38554 8192000000 10269002080 10730962944 1371050736 14.336 100 12 24 1 1383828752 10010528 2767488
18:41:41 58736 8192000000 10272450032 10740658176 1357285008 14.213 98 9 18 0 1367847424 8637200 1925216
18:43:45 26357 8192000000 10281630432 10737913856 1376009792 14.381 100 27 52 3 1390910304 8741328 6159184
18:45:12 70700 8192000000 10284570208 10738184192 1321061920 13.886 95 27 53 0 1346217296 19117856 6037520
18:47:50 11868 8192000000 10295512752 10739122176 1371319088 14.339 99 46 94 1 1388757600 5827104 11611408
18:49:14 38081 8192000000 10289583072 10741837824 1374361488 14.366 100 40 80 2 1399445792 15936816 9147488
18:50:53 65630 8192000000 10273758320 10724343808 1326791920 13.938 97 24 48 2 1350706672 18572896 5341856
18:53:28 86742 8192000000 10291898480 10727739392 1312202384 13.806 95 17 34 0 1326969280 10970544 3796352
18:55:59 75204 8192000000 10271522240 10717929472 1335844592 14.020 97 19 38 1 1350379376 10359744 4175040
18:58:53 56652 8192000000 10273897280 10727034880 1329456688 13.962 97 32 62 3 1350547888 14087696 7003504
19:02:09 64653 8192000000 10282161376 10753556480 1383113600 14.444 100 11 22 1 1400058560 14369888 2575072
19:05:04 62979 8192000000 10303357984 10761478144 1395107744 14.551 100 14 28 0 1408695408 10443712 3143952
19:07:07 55894 8192000000 10300726848 10772852736 1386450480 14.474 99 11 22 1 1407623648 18738432 2434736
19:09:15 67348 8192000000 10309262880 10775486464 1373094928 14.355 97 6 12 0 1383553504 9317872 1140704
19:11:25 75658 8192000000 10333145568 10802147328 1412204848 14.704 100 9 18 1 1433093824 18267424 2621552
19:13:50 43749 8192000000 10315368768 10762633216 1380776624 14.423 98 25 50 0 1398472720 12620240 5075856
19:15:35 18941 8192000000 10319058368 10776371200 1399099200 14.587 100 24 48 1 1416022448 11260176 5663072
19:16:54 45023 8192000000 10318658944 10768310272 1401808608 14.611 100 26 52 0 1422464128 14518464 6137056
19:18:42 62470 8192000000 10314245904 10775605248 1390428720 14.510 99 20 39 2 1405885280 10520224 4936336
19:21:24 86237 8192000000 10322057840 10805465088 1394403088 14.545 99 13 25 3 1421605888 23987696 3215104
19:23:50 82049 8192000000 10294859808 10742042624 1313495216 13.818 93 52 104 1 1333681936 8724992 11461728
19:26:45 50451 8192000000 10266890064 10738253824 1362903920 14.263 99 4 8 0 1372137728 8412480 821328
19:28:42 79337 8192000000 10260821200 10713759744 1286316688 13.571 93 22 44 0 1301711760 10244128 5150944
19:31:01 69455 8192000000 10262249856 10723573760 1348306720 14.132 98 17 34 1 1365300880 12896864 4097296
19:33:26 45504 8192000000 10281116608 10742992896 1378998208 14.408 100 18 36 0 1393753760 10468832 4286720
19:35:12 74109 8192000000 10249209536 10709770240 1271262064 13.433 93 14 28 0 1282975152 8376112 3336976
19:37:35 27437 8192000000 9236743056 9510379520 394000064 4.588 30 14 28 0 401827168 4808768 3018336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-01-2022 19:39:19 to 05-01-2022 19:39:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-01-2022 19:39:35 to 05-01-2022 19:39:37.

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          244417
MON$OLDEST_ACTIVE               246439
MON$OLDEST_SNAPSHOT             246439
MON$NEXT_TRANSACTION            246439
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                       2052704
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:483: 
:484: mscs-test-161.budzdorov.ru    Wed Jan  5 19:38:06 2022
:485:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:486: 
:487: 
:488: mscs-test-161.budzdorov.ru    Wed Jan  5 19:38:09 2022
:489:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:490: 
--- end of diff output ---

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

05.01.22 19:39:38. 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-01-05 19:39:40 No severe PSQL-related problems occured
05.01.22 19:39:41. End of report.