Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.04.2022 22: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 16696564 kB
MemAvailable 31521364 kB
Buffers 0 kB
Cached 14999292 kB
SwapCached 2360 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-10 22:38:14
DTS_END                         2022-04-10 22:38:14.2660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52160
TRN_ID                          237449
ATT_ID                          671
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: 9293.74 24931 1115249
customer order: creation 161.32 374 19359
customer order: refuse 13.93 354 1672
order to supplier: creation 43.97 1341 5276
order to supplier: removal 3.52 101625 422
invoice (draft): creation 44.01 3260 5281
invoice (draft): removal 54.74 782 6569
invoice accept: apply 40.13 17419 4815
invoice accept: cancel 49.54 705 5945
customer reserve: creation 3742.28 171 449074
customer reserve: removal 4852.13 58 582255
realization accept: apply 79.66 202 9559
realization accept: cancel 85.14 113 10217
payment from customer: creation 56.11 111 6733
payment from customer: removal 5.51 99 661
payment to supplier: creation 47.58 135 5710
payment to supplier: removal 3.18 97 382
service: total inventory turnovers 4.18 1366 501
service: total monetary turnovers 5.96 47 715
service: aggregate perf. data 0.83 17701 99
service: refresh index statistics 0.03 352652 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-04-2022 22:39:27 to 10-04-2022 22:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4681
TEST_TIME 2 3818
TEST_TIME 3 4134
TEST_TIME 4 4111
TEST_TIME 5 3665
TEST_TIME 6 4650
TEST_TIME 7 4810
TEST_TIME 8 5198
TEST_TIME 9 5274
TEST_TIME 10 5932
TEST_TIME 11 6355
TEST_TIME 12 6564
TEST_TIME 13 6525
TEST_TIME 14 6552
TEST_TIME 15 6737
TEST_TIME 16 6734
TEST_TIME 17 7224
TEST_TIME 18 7319
TEST_TIME 19 7985
TEST_TIME 20 8307
TEST_TIME 21 8570
TEST_TIME 22 9217
TEST_TIME 23 8972
TEST_TIME 24 9336
TEST_TIME 25 10179
TEST_TIME 26 10547
TEST_TIME 27 11081
TEST_TIME 28 10852
TEST_TIME 29 10700
TEST_TIME 30 10753
TEST_TIME 31 10894
TEST_TIME 32 10685
TEST_TIME 33 10494
TEST_TIME 34 10465
TEST_TIME 35 10314
TEST_TIME 36 10193
TEST_TIME 37 10167
TEST_TIME 38 10029
TEST_TIME 39 9970
TEST_TIME 40 9827
TEST_TIME 41 9797
TEST_TIME 42 9839
TEST_TIME 43 9854
TEST_TIME 44 9721
TEST_TIME 45 9613
TEST_TIME 46 9527
TEST_TIME 47 9515
TEST_TIME 48 9396
TEST_TIME 49 9435
TEST_TIME 50 9289
TEST_TIME 51 9302
TEST_TIME 52 9390
TEST_TIME 53 9316
TEST_TIME 54 9275
TEST_TIME 55 9443
TEST_TIME 56 9427
TEST_TIME 57 9541
TEST_TIME 58 9651
TEST_TIME 59 9663
TEST_TIME 60 9679
TEST_TIME 61 9647
TEST_TIME 62 9722
TEST_TIME 63 9745
TEST_TIME 64 9749
TEST_TIME 65 9765
TEST_TIME 66 9712
TEST_TIME 67 9633
TEST_TIME 68 9565
TEST_TIME 69 9535
TEST_TIME 70 9474
TEST_TIME 71 9446
TEST_TIME 72 9446
TEST_TIME 73 9405
TEST_TIME 74 9434
TEST_TIME 75 9432
TEST_TIME 76 9468
TEST_TIME 77 9455
TEST_TIME 78 9495
TEST_TIME 79 9445
TEST_TIME 80 9498
TEST_TIME 81 9558
TEST_TIME 82 9617
TEST_TIME 83 9633
TEST_TIME 84 9566
TEST_TIME 85 9614
TEST_TIME 86 9709
TEST_TIME 87 9645
TEST_TIME 88 9567
TEST_TIME 89 9504
TEST_TIME 90 9516
TEST_TIME 91 9512
TEST_TIME 92 9444
TEST_TIME 93 9395
TEST_TIME 94 9422
TEST_TIME 95 9411
TEST_TIME 96 9394
TEST_TIME 97 9442
TEST_TIME 98 9500
TEST_TIME 99 9466
TEST_TIME 100 9504
TEST_TIME 101 9484
TEST_TIME 102 9447
TEST_TIME 103 9530
TEST_TIME 104 9524
TEST_TIME 105 9547
TEST_TIME 106 9574
TEST_TIME 107 9622
TEST_TIME 108 9579
TEST_TIME 109 9564
TEST_TIME 110 9501
TEST_TIME 111 9474
TEST_TIME 112 9445
TEST_TIME 113 9460
TEST_TIME 114 9420
TEST_TIME 115 9405
TEST_TIME 116 9376
TEST_TIME 117 9320
TEST_TIME 118 9357
TEST_TIME 119 9363
TEST_TIME 120 9299

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-04-2022 22:39:30 to 10-04-2022 22:39:34.

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 1531843 1531843 0 0.00 0 18153 6 0 0
doc_list_biud 1531840 1531840 0 0.00 0 29502 28 0 0
sp_add_invoice_to_stock 4942 4815 127 2.56 25 177242 17420 0 127
sp_cancel_adding_invoice 5945 5945 0 0.00 4 55011 706 0 0
sp_cancel_client_order 1672 1672 0 0.00 4 11285 355 0 0
sp_cancel_customer_reserve 582255 582255 0 0.00 1 29508 58 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 11094 100 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 6756 98 0 0
sp_cancel_supplier_invoice 6577 6569 8 0.12 2 19106 782 0 8
sp_cancel_supplier_order 422 422 0 0.00 6 1034632 101625 0 0
sp_cancel_write_off 10217 10217 0 0.00 1 9465 114 0 0
sp_client_order 19359 19359 0 0.00 3 39192 374 0 0
sp_customer_reserve 449074 449074 0 0.00 0 83232 171 0 0
sp_fill_shopping_cart 29913 29913 0 0.00 1 14886 40 0 0
sp_fill_shopping_cart_clo_res 449067 449067 0 0.00 0 20581 15 0 0
sp_get_clo_for_invoice 4808 4808 0 0.00 0 23011 307 0 0
sp_kill_cost_storno 17205 17205 0 0.00 0 7700 52 0 0
sp_kill_qstorno_ret_qs2qd 1181718 1181718 0 0.00 0 29496 32 0 0
sp_kill_qty_storno 621446 621446 0 0.00 0 29502 68 0 0
sp_lock_selected_doc 19904 19904 0 0.00 0 11087 8 0 0
sp_make_cost_storno 26810 26810 0 0.00 0 18074 59 0 0
sp_multiply_rows_for_pdistr 26809 26809 0 0.00 0 9848 30 0 0
sp_multiply_rows_for_qdistr 19359 19359 0 0.00 0 39184 169 0 0
sp_pay_from_customer 6733 6733 0 0.00 4 15547 112 0 0
sp_pay_to_supplier 5710 5710 0 0.00 5 16735 135 0 0
sp_payment_common 12442 12442 0 0.00 1 9824 30 0 0
sp_qd_handle_on_cancel_clo 1672 1672 0 0.00 0 11271 326 0 0
sp_qd_handle_on_invoice_upd_sts 10752 10752 0 0.00 0 24820 316 0 0
sp_qd_handle_on_reserve_upd_sts 9559 9559 0 0.00 0 16300 83 0 0
sp_reserve_write_off 9559 9559 0 0.00 6 16763 203 0 0
sp_supplier_invoice 5281 5281 0 0.00 7 104372 3261 0 0
sp_supplier_order 5276 5276 0 0.00 5 31128 1342 0 0
srv_aggregate_perf_data 100 99 1 1.00 2526 128188 17702 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1606 115830 48795 0 0
srv_find_qd_qs_mism 1069904 1069904 0 0.00 0 15372 5 0 0
srv_make_invnt_saldo 501 501 0 0.00 44 125930 1366 0 0
srv_make_money_saldo 715 715 0 0.00 1 10587 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 207268 500131 352653 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4816 4816 0 0.00 0 15373 166 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 4873 92 0 0
t$perf-norm:sp_cancel_client_order 1672 1672 0 0.00 0 3540 18 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 783 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 3120 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 629 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 685 11 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 30984 1492 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 1694 5 0 0
t$perf-norm:sp_client_order 19359 19359 0 0.00 0 10532 17 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 1705 10 0 0
t$perf-norm:sp_pay_from_customer 6733 6733 0 0.00 0 24519 15 0 0
t$perf-norm:sp_pay_to_supplier 5710 5710 0 0.00 0 6884 10 0 0
t$perf-norm:sp_reserve_write_off 9559 9559 0 0.00 0 14917 13 0 0
t$perf-norm:sp_supplier_invoice 5281 5281 0 0.00 0 14865 23 0 0
t$perf-norm:sp_supplier_order 5276 5276 0 0.00 0 9796 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 773 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 26 1 0 0
t$perf-norm:srv_make_invnt_saldo 501 501 0 0.00 0 6237 31 0 0
t$perf-norm:srv_make_money_saldo 715 715 0 0.00 0 815 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9866 9612 254 2.57 0 5680 16 0 254
v_all_customers 35464 35464 0 0.00 0 20 0 0 0
v_all_suppliers 29534 29534 0 0.00 0 111 1 0 0
v_all_wares 38718 38718 0 0.00 0 93 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 2681 9 0 0
v_cancel_client_order 3344 3344 0 0.00 0 1673 4 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 2191 11 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 2391 5 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 4939 10 0 0
v_cancel_supplier_invoice 814 798 16 1.96 0 661 13 0 16
v_cancel_supplier_order 844 844 0 0.00 0 2413 10 0 0
v_cancel_write_off 1916 1916 0 0.00 0 1008 11 0 0
v_max_id_clo_ord 5276 5276 0 0.00 0 885 1 0 0
v_max_id_clo_res 951 951 0 0.00 0 5868 23 0 0
v_max_id_ord_sup 5278 5278 0 0.00 0 7737 6 0 0
v_max_non_paid_invoice 1496 1496 0 0.00 0 82 2 0 0
v_max_non_paid_realizn 3547 3547 0 0.00 0 1439 4 0 0
v_min_id_clo_ord 5276 5276 0 0.00 0 14077 13 0 0
v_min_id_clo_res 951 951 0 0.00 0 3691 13 0 0
v_min_id_ord_sup 5278 5278 0 0.00 0 6256 9 0 0
v_min_non_paid_invoice 5709 5709 0 0.00 0 9814 21 0 0
v_min_non_paid_realizn 6733 6733 0 0.00 0 9814 25 0 0
v_our_firm 9626 9626 0 0.00 0 14 1 0 0
v_reserve_write_off 19118 19118 0 0.00 0 3239 5 0 0
x_lock_depdocs_on_canc_invoice 12115 12115 0 0.00 0 16335 120 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 18090 413 0 0
x_make_qstorno_1000_1200 5276 5276 0 0.00 2 31092 1253 0 0
x_make_qstorno_1200_2000 5281 5281 0 0.00 4 104334 3124 0 0
x_make_qstorno_2100_3300 449073 449073 0 0.00 0 83231 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-04-2022 22:39:35 to 10-04-2022 22:39:39.

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
20:08:18 12 8192000000 8813911488 8830164992 17688848 0.215 5 4 5 0 17916912 88784 139280
20:09:19 9200 8192000000 9903656688 10212540416 993889744 10.819 100 91 182 0 1023673216 5805360 23978112
20:10:36 19751 8192000000 9951486736 10272153600 1029464688 11.163 100 90 180 0 1060604960 8348768 22791504
20:12:01 14480 8192000000 9981089280 10314989568 1066497408 11.519 100 81 162 0 1096465152 9537616 20430128
20:13:20 14364 8192000000 10030212496 10374094848 1109981664 11.932 100 78 155 1 1139859200 11123712 18753824
20:14:40 11051 8192000000 10077078800 10436153344 1161010720 12.413 100 81 161 1 1192525440 13265152 18249568
20:15:57 4364 8192000000 10123084544 10491527168 1209097696 12.861 100 85 170 2 1241462320 13345440 19019184
20:17:04 2059 8192000000 10151893040 10527170560 1243185232 13.176 100 86 172 1 1275675536 13102784 19387520
20:18:08 2250 8192000000 10197492032 10577207296 1284936640 13.558 100 93 187 3 1319565008 13287472 21340896
20:19:12 1767 8192000000 10220848528 10604883968 1296878928 13.667 100 96 193 2 1329984912 10869264 22236720
20:20:14 7876 8192000000 10198560608 10606915584 1306561600 13.755 100 28 55 0 1319829280 6898688 6368992
20:21:23 17392 8192000000 10232938544 10629001216 1318345440 13.862 100 80 160 1 1342799904 5783696 18670768
20:22:41 6052 8192000000 10218701104 10632052736 1324384672 13.916 100 38 76 0 1340325936 7615104 8326160
20:23:48 6580 8192000000 10219416000 10638942208 1331515312 13.981 100 22 44 0 1343133280 6597664 5020304
20:24:55 16172 8192000000 10224503408 10646085632 1333765840 14.001 100 24 47 0 1348834512 9779616 5289056
20:26:11 8040 8192000000 10228864880 10650152960 1337454960 14.034 100 34 68 0 1352025312 7029440 7540912
20:27:22 6136 8192000000 10229777328 10647461888 1325967728 13.931 99 19 38 2 1335595680 5506736 4121216
20:28:29 6371 8192000000 10174612544 10585231360 1258810864 13.319 97 25 50 0 1278314864 14132032 5371968
20:29:37 62894 8192000000 10144922800 10548666368 1164102864 12.442 91 34 68 1 1178023424 4787744 9132816
20:31:45 5736 8192000000 10155404480 10547748864 1231561840 13.068 98 31 59 2 1243956960 5265488 7129632
20:32:51 8839 8192000000 10173517008 10583240704 1275905136 13.476 99 28 56 1 1288574032 6017664 6651232
20:34:00 15553 8192000000 10194032720 10610065408 1303530880 13.727 100 26 52 3 1316095472 5748912 6815680
20:35:55 8352 8192000000 10207251648 10619092992 1309901248 13.785 100 36 70 3 1324957904 7394464 7662192
20:37:04 7206 8192000000 10210525008 10629427200 1319008864 13.868 100 30 60 0 1332329856 6401600 6919392
20:38:14 47497 8192000000 10242123040 10655940608 1339583984 14.054 100 27 52 1 1350776944 5305152 5887808
20:40:10 20054 8192000000 10288906176 10701443072 1369750656 14.325 100 36 72 0 1387919104 10014960 8153488
20:41:45 82099 8192000000 10281174384 10708774912 1365956576 14.291 99 28 55 1 1388513008 15817888 6738544
20:44:11 43802 8192000000 10301601120 10730840064 1391923184 14.523 100 36 72 0 1418609168 18371984 8314000
20:45:58 20660 8192000000 10330113792 10751016960 1398470112 14.581 100 64 125 1 1436626384 23319264 14837008
20:47:21 4717 8192000000 10332142576 10754875392 1400979056 14.604 100 58 119 3 1440762736 25351472 14432208
20:48:32 5445 8192000000 10340560208 10768314368 1411858544 14.700 100 43 84 1 1452533152 29362608 11312000
20:49:43 4561 8192000000 10313996480 10730475520 1377924832 14.398 97 63 126 1 1420620688 27180640 15515216
20:50:54 5174 8192000000 10363587536 10777886720 1410338656 14.687 100 87 172 1 1461570976 30855616 20376704
20:52:06 7500 8192000000 10371150112 10784215040 1414220544 14.721 100 75 146 1 1471678064 40225616 17231904
20:53:23 9693 8192000000 10374472672 10791854080 1417588704 14.751 100 69 138 1 1479889984 44730400 17570880
20:54:41 1606 8192000000 10373373376 10783449088 1413559968 14.716 100 80 157 0 1477772848 43984448 20228432
20:55:47 2877 8192000000 10356266816 10773823488 1392806800 14.531 99 54 108 1 1457233616 49191216 15235600
20:56:53 13155 8192000000 10346732208 10772742144 1380354496 14.420 98 47 92 2 1448078768 54336496 13387776
20:58:14 10116 8192000000 10329157904 10763493376 1389234720 14.499 100 32 63 2 1441132672 43278768 8619184
20:59:28 6113 8192000000 10336683376 10756218880 1366049408 14.292 99 46 92 0 1431988544 53874704 12064432
21:00:37 23223 8192000000 10295553776 10734874624 1350582336 14.153 99 22 44 1 1402894384 46042480 6269568
21:02:14 28236 8192000000 10280155808 10712870912 1301102144 13.705 97 23 46 2 1360356848 52263984 6990720
21:03:44 24622 8192000000 10275616352 10721017856 1334418224 14.007 99 18 36 0 1382278064 42542720 5317120
21:05:11 16741 8192000000 10258483936 10701799424 1331951776 13.985 99 18 36 1 1356067104 19237520 4877808
21:06:30 77412 8192000000 10243095648 10680909824 1317192272 13.851 98 14 28 1 1329827504 9050976 3584256
21:09:00 93434 8192000000 10258409344 10698780672 1331567744 13.981 98 18 36 2 1348059872 12939328 3552800
21:11:35 111808 8192000000 10253631200 10704584704 1349851232 14.146 99 21 42 2 1367179472 11287824 6040416
21:14:27 22711 8192000000 10283557808 10715738112 1354953248 14.192 99 55 111 1 1377531280 9995680 12582352
21:15:52 12702 8192000000 10258488816 10717024256 1363575568 14.269 100 8 15 1 1374072400 9001632 1495200
21:17:06 37755 8192000000 10284102624 10728312832 1376907456 14.389 100 26 49 0 1392393200 9559600 5926144
21:18:46 53994 8192000000 10295428976 10747305984 1387827280 14.486 100 34 66 3 1410849680 15414624 7607776
21:20:47 111088 8192000000 10284714288 10743615488 1383475568 14.448 100 13 26 0 1400385808 14012848 2897392
21:23:58 45299 8192000000 10287506656 10749149184 1390100560 14.507 100 14 29 2 1406277792 12861840 3315392
21:25:47 53735 8192000000 10295320512 10755690496 1396412048 14.563 100 14 28 0 1410156784 10623120 3121616
21:27:42 57833 8192000000 10297880128 10758000640 1399368736 14.589 100 18 36 1 1416913280 13364144 4180400
21:30:02 87152 8192000000 10306695344 10782371840 1377200656 14.392 98 8 16 0 1396460704 17864512 1395536
21:32:32 35680 8192000000 10322096688 10781962240 1394657456 14.547 99 17 34 0 1412875120 12655552 5562112
21:34:08 78355 8192000000 10320881680 10769588224 1372223280 14.347 97 35 71 1 1397130736 16517408 8390048
21:36:40 101686 8192000000 10294636880 10750038016 1360260544 14.240 97 15 30 0 1378161040 14924624 2975872
21:39:22 46604 8192000000 10294009680 10740150272 1355768800 14.199 97 19 36 2 1368931168 8845008 4317360
21:41:11 60061 8192000000 10303853712 10752008192 1366039216 14.292 98 27 54 0 1383741312 12022656 5679440
21:43:15 71519 8192000000 10290557040 10740539392 1359289856 14.231 98 30 59 1 1378948576 13223952 6434768
21:45:27 59527 8192000000 10266347472 10726707200 1343416784 14.088 97 22 41 2 1355679120 7238896 5023440
21:47:27 47088 8192000000 10237094976 10690048000 1315403120 13.835 97 13 26 2 1327124208 8814672 2906416
21:50:25 55882 8192000000 10258859008 10718154752 1335125328 14.013 98 7 14 0 1347290896 10550496 1615072
21:52:23 81823 8192000000 10263179472 10708766720 1293353296 13.635 95 16 33 2 1320058496 22905792 3799408
21:54:58 50451 8192000000 10277397152 10720657408 1350832608 14.155 99 15 30 0 1369860464 15535504 3492352
21:56:50 57009 8192000000 10286926064 10727727104 1367463856 14.304 100 28 55 1 1393213696 19291600 6458240
21:59:02 105745 8192000000 10259658096 10707890176 1329147456 13.959 97 9 18 0 1344557312 13593552 1816304
22:01:51 71821 8192000000 10291384608 10727522304 1334141168 14.005 97 30 60 0 1349939920 9219376 6579376
22:04:06 79542 8192000000 10277914960 10737348608 1329560384 13.963 97 13 26 1 1350385360 18335216 2489760
22:06:46 74483 8192000000 10287998560 10736394240 1358801456 14.227 98 22 44 0 1376511824 12632272 5078096
22:09:01 32526 8192000000 10270602848 10736840704 1360983056 14.246 99 9 18 0 1373245424 10328144 1934224
22:11:23 70345 8192000000 10275823840 10736955392 1372998320 14.354 99 8 17 1 1384917328 10342816 1576192
22:14:30 71750 8192000000 10298337296 10756800512 1370658160 14.333 98 13 26 2 1389233712 15814128 2761424
22:16:47 36278 8192000000 10316122848 10757685248 1399110000 14.587 100 28 56 1 1412751504 7388512 6252992
22:18:25 39879 8192000000 10304539888 10773766144 1397681024 14.574 99 32 61 1 1420226992 15629200 6916768
22:20:07 65987 8192000000 10302260816 10765578240 1404923952 14.639 100 19 38 0 1420594112 11346608 4323552
22:22:18 22361 8192000000 10309691920 10753179648 1395041392 14.551 100 31 62 2 1415616000 14039168 6535440
22:23:44 115814 8192000000 10297982176 10762518528 1339832224 14.056 96 9 18 0 1356689056 15193552 1663280
22:26:41 18461 8192000000 10312424944 10754043904 1394486880 14.546 100 29 58 1 1409669056 9059264 6122912
22:28:02 101166 8192000000 10295807600 10739310592 1367888736 14.308 98 43 86 0 1385559024 7912496 9757792
22:30:47 64913 8192000000 10274346720 10738077696 1353705632 14.181 97 25 50 0 1366529008 6985824 5837552
22:32:56 33937 8192000000 10288637344 10744631296 1390848672 14.513 100 13 26 0 1401250272 7605584 2796016
22:34:33 51457 8192000000 10292419056 10749087744 1387460096 14.483 99 13 25 1 1397920576 7475200 2985280
22:36:32 80301 8192000000 10259351120 10716397568 1326566448 13.936 96 10 20 0 1337586736 8585232 2435056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-04-2022 22:39:41 to 10-04-2022 22:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 127 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:01, from 10-04-2022 22:39:57 to 10-04-2022 22:39:58.

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          236857
MON$OLDEST_ACTIVE               237610
MON$OLDEST_SNAPSHOT             237610
MON$NEXT_TRANSACTION            237610
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                       2021824
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:184: 
:185: mscs-test-161.budzdorov.ru    Sun Apr 10 22:38:25 2022
:186:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:187: 
--- end of diff output ---

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

10.04.22 22:40:00. 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-10 22:40:02 No severe PSQL-related problems occured
10.04.22 22:40:03. End of report.