Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.12.2021 19: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 16639488 kB
MemAvailable 31480200 kB
Buffers 0 kB
Cached 15061540 kB
SwapCached 0 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 2021-12-11 19:37:58
DTS_END                         2021-12-11 19:37:58.4120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59702
TRN_ID                          231968
ATT_ID                          618
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: 9317.58 19601 1118110
customer order: creation 167.58 352 20110
customer order: refuse 14.13 332 1696
order to supplier: creation 44.26 1273 5311
order to supplier: removal 3.54 99460 425
invoice (draft): creation 44.02 3415 5282
invoice (draft): removal 54.54 795 6545
invoice accept: apply 41.15 18593 4938
invoice accept: cancel 49.05 714 5886
customer reserve: creation 3805.08 184 456610
customer reserve: removal 4794.45 57 575334
realization accept: apply 80.98 190 9718
realization accept: cancel 92.69 112 11123
payment from customer: creation 58.30 113 6996
payment from customer: removal 5.62 68 674
payment to supplier: creation 46.75 150 5610
payment to supplier: removal 2.98 117 357
service: total inventory turnovers 4.98 1026 598
service: total monetary turnovers 6.60 85 792
service: aggregate perf. data 0.84 20019 101
service: refresh index statistics 0.03 244956 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-12-2021 19:40:00 to 11-12-2021 19:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2396
TEST_TIME 2 3060
TEST_TIME 3 3762
TEST_TIME 4 3218
TEST_TIME 5 2971
TEST_TIME 6 3715
TEST_TIME 7 4250
TEST_TIME 8 4643
TEST_TIME 9 4992
TEST_TIME 10 5135
TEST_TIME 11 5251
TEST_TIME 12 5390
TEST_TIME 13 5647
TEST_TIME 14 5866
TEST_TIME 15 6211
TEST_TIME 16 6387
TEST_TIME 17 6476
TEST_TIME 18 6758
TEST_TIME 19 6733
TEST_TIME 20 7324
TEST_TIME 21 7388
TEST_TIME 22 7480
TEST_TIME 23 8136
TEST_TIME 24 8570
TEST_TIME 25 8676
TEST_TIME 26 8940
TEST_TIME 27 9831
TEST_TIME 28 9960
TEST_TIME 29 9997
TEST_TIME 30 10262
TEST_TIME 31 10143
TEST_TIME 32 9894
TEST_TIME 33 9783
TEST_TIME 34 9871
TEST_TIME 35 9853
TEST_TIME 36 9817
TEST_TIME 37 9731
TEST_TIME 38 9683
TEST_TIME 39 9601
TEST_TIME 40 9639
TEST_TIME 41 9663
TEST_TIME 42 9655
TEST_TIME 43 9557
TEST_TIME 44 9503
TEST_TIME 45 9415
TEST_TIME 46 9507
TEST_TIME 47 9476
TEST_TIME 48 9376
TEST_TIME 49 9380
TEST_TIME 50 9260
TEST_TIME 51 9265
TEST_TIME 52 9205
TEST_TIME 53 9293
TEST_TIME 54 9266
TEST_TIME 55 9343
TEST_TIME 56 9298
TEST_TIME 57 9374
TEST_TIME 58 9429
TEST_TIME 59 9368
TEST_TIME 60 9343
TEST_TIME 61 9524
TEST_TIME 62 9619
TEST_TIME 63 9609
TEST_TIME 64 9637
TEST_TIME 65 9652
TEST_TIME 66 9532
TEST_TIME 67 9576
TEST_TIME 68 9564
TEST_TIME 69 9562
TEST_TIME 70 9482
TEST_TIME 71 9388
TEST_TIME 72 9406
TEST_TIME 73 9360
TEST_TIME 74 9358
TEST_TIME 75 9351
TEST_TIME 76 9312
TEST_TIME 77 9271
TEST_TIME 78 9374
TEST_TIME 79 9416
TEST_TIME 80 9424
TEST_TIME 81 9456
TEST_TIME 82 9428
TEST_TIME 83 9506
TEST_TIME 84 9495
TEST_TIME 85 9513
TEST_TIME 86 9517
TEST_TIME 87 9572
TEST_TIME 88 9541
TEST_TIME 89 9458
TEST_TIME 90 9417
TEST_TIME 91 9407
TEST_TIME 92 9392
TEST_TIME 93 9387
TEST_TIME 94 9364
TEST_TIME 95 9319
TEST_TIME 96 9360
TEST_TIME 97 9317
TEST_TIME 98 9353
TEST_TIME 99 9350
TEST_TIME 100 9390
TEST_TIME 101 9364
TEST_TIME 102 9402
TEST_TIME 103 9381
TEST_TIME 104 9403
TEST_TIME 105 9392
TEST_TIME 106 9417
TEST_TIME 107 9480
TEST_TIME 108 9470
TEST_TIME 109 9505
TEST_TIME 110 9515
TEST_TIME 111 9491
TEST_TIME 112 9506
TEST_TIME 113 9498
TEST_TIME 114 9450
TEST_TIME 115 9412
TEST_TIME 116 9388
TEST_TIME 117 9344
TEST_TIME 118 9373
TEST_TIME 119 9373
TEST_TIME 120 9320

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-12-2021 19:40:03 to 11-12-2021 19:40:07.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1540297 1540297 0 0.00 0 36390 6 0 0
doc_list_biud 1540296 1540296 0 0.00 0 48111 27 0 0
sp_add_invoice_to_stock 5048 4938 110 2.17 19 242508 18593 0 110
sp_cancel_adding_invoice 5886 5886 0 0.00 6 65791 714 0 0
sp_cancel_client_order 1696 1696 0 0.00 3 13669 332 0 0
sp_cancel_customer_reserve 575334 575334 0 0.00 1 42712 57 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 2 4109 68 0 0
sp_cancel_pay_to_supplier 357 357 0 0.00 2 18183 118 0 0
sp_cancel_supplier_invoice 6553 6545 8 0.12 2 14893 795 0 8
sp_cancel_supplier_order 425 425 0 0.00 5 1030982 99460 0 0
sp_cancel_write_off 11123 11123 0 0.00 1 22537 113 0 0
sp_client_order 20110 20110 0 0.00 3 42786 352 0 0
sp_customer_reserve 456610 456610 0 0.00 0 140792 185 0 0
sp_fill_shopping_cart 30691 30691 0 0.00 1 12837 38 0 0
sp_fill_shopping_cart_clo_res 456606 456606 0 0.00 0 15301 13 0 0
sp_get_clo_for_invoice 4928 4928 0 0.00 0 26014 287 0 0
sp_kill_cost_storno 18040 18040 0 0.00 0 22364 61 0 0
sp_kill_qstorno_ret_qs2qd 1168761 1168761 0 0.00 0 42708 31 0 0
sp_kill_qty_storno 615655 615655 0 0.00 0 48108 67 0 0
sp_lock_selected_doc 20273 20273 0 0.00 0 8074 6 0 0
sp_make_cost_storno 27252 27252 0 0.00 0 19779 51 0 0
sp_multiply_rows_for_pdistr 27251 27251 0 0.00 0 26471 33 0 0
sp_multiply_rows_for_qdistr 20110 20110 0 0.00 0 42725 158 0 0
sp_pay_from_customer 6996 6996 0 0.00 3 24055 113 0 0
sp_pay_to_supplier 5610 5610 0 0.00 5 26654 151 0 0
sp_payment_common 12605 12605 0 0.00 1 26216 43 0 0
sp_qd_handle_on_cancel_clo 1696 1696 0 0.00 0 13662 306 0 0
sp_qd_handle_on_invoice_upd_sts 10814 10814 0 0.00 0 48104 313 0 0
sp_qd_handle_on_reserve_upd_sts 9718 9718 0 0.00 0 16805 61 0 0
sp_reserve_write_off 9718 9718 0 0.00 6 26944 191 0 0
sp_supplier_invoice 5282 5282 0 0.00 5 90555 3416 0 0
sp_supplier_order 5311 5311 0 0.00 5 36916 1273 0 0
srv_aggregate_perf_data 101 101 0 0.00 2640 362840 20020 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1841 147276 51588 0 0
srv_find_qd_qs_mism 1071164 1071164 0 0.00 0 36388 5 0 0
srv_make_invnt_saldo 598 598 0 0.00 31 69210 1027 0 0
srv_make_money_saldo 792 792 0 0.00 1 43494 86 0 0
srv_recalc_idx_stat 4 4 0 0.00 181440 308364 244957 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 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 4938 4938 0 0.00 0 32468 204 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 22737 195 0 0
t$perf-norm:sp_cancel_client_order 1696 1696 0 0.00 0 1028 9 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 10383 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 4652 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 357 357 0 0.00 0 1549 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 453 453 0 0.00 0 6479 27 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 76663 1830 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 2723 10 0 0
t$perf-norm:sp_client_order 20110 20110 0 0.00 0 23505 21 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 11434 17 0 0
t$perf-norm:sp_pay_from_customer 6996 6996 0 0.00 0 5980 12 0 0
t$perf-norm:sp_pay_to_supplier 5610 5610 0 0.00 0 23527 22 0 0
t$perf-norm:sp_reserve_write_off 9718 9718 0 0.00 0 21483 20 0 0
t$perf-norm:sp_supplier_invoice 5282 5282 0 0.00 0 6836 22 0 0
t$perf-norm:sp_supplier_order 5311 5311 0 0.00 0 6959 18 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 317 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 589 16 0 0
t$perf-norm:srv_make_invnt_saldo 598 598 0 0.00 0 4743 35 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 3068 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10076 9856 220 2.18 0 4871 17 0 220
v_all_customers 37228 37228 0 0.00 0 92 1 0 0
v_all_suppliers 29280 29280 0 0.00 0 25 1 0 0
v_all_wares 40216 40216 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 259 5 0 0
v_cancel_client_order 3392 3392 0 0.00 0 2640 5 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 3073 9 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 3016 10 0 0
v_cancel_payment_to_supplier 714 714 0 0.00 0 3375 8 0 0
v_cancel_supplier_invoice 922 906 16 1.73 0 2348 16 0 16
v_cancel_supplier_order 850 850 0 0.00 0 3950 9 0 0
v_cancel_write_off 1900 1900 0 0.00 0 5128 17 0 0
v_max_id_clo_ord 5309 5309 0 0.00 0 714 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 8733 27 0 0
v_max_id_ord_sup 5274 5274 0 0.00 0 3970 4 0 0
v_max_non_paid_invoice 1551 1551 0 0.00 0 183 2 0 0
v_max_non_paid_realizn 3569 3569 0 0.00 0 641 3 0 0
v_min_id_clo_ord 5309 5309 0 0.00 0 11381 12 0 0
v_min_id_clo_res 983 983 0 0.00 0 3784 10 0 0
v_min_id_ord_sup 5274 5274 0 0.00 0 8865 9 0 0
v_min_non_paid_invoice 5608 5608 0 0.00 0 26211 38 0 0
v_min_non_paid_realizn 6996 6996 0 0.00 0 23641 35 0 0
v_our_firm 9842 9842 0 0.00 0 14 1 0 0
v_reserve_write_off 19434 19434 0 0.00 0 5903 5 0 0
x_lock_depdocs_on_canc_invoice 11978 11978 0 0.00 0 23173 123 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 17349 449 0 0
x_make_qstorno_1000_1200 5311 5311 0 0.00 1 36906 1192 0 0
x_make_qstorno_1200_2000 5282 5282 0 0.00 1 87865 3281 0 0
x_make_qstorno_2100_3300 456610 456610 0 0.00 0 140789 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-12-2021 19:40:08 to 11-12-2021 19:40:12.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:02 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:03 23509 8192000000 9910447728 10223681536 988828672 10.770 100 80 160 0 1015378032 5479488 21069872
17:10:38 17461 8192000000 9957864288 10284720128 1041260864 11.277 100 85 168 1 1070445344 7211056 21973424
17:11:59 14845 8192000000 10006408864 10340589568 1086895440 11.713 100 88 176 1 1118097456 9249056 21952960
17:13:28 14087 8192000000 10055547680 10405232640 1140264608 12.218 100 77 155 0 1169310480 11112672 17933200
17:14:46 7640 8192000000 10093744928 10457137152 1185399216 12.641 100 78 156 1 1215778752 12927392 17452144
17:15:55 4330 8192000000 10136208928 10508238848 1228422416 13.039 100 79 158 1 1258540288 13221744 16896128
17:17:01 2322 8192000000 10171588272 10549780480 1257761536 13.309 100 88 175 1 1290760752 12859872 20139344
17:18:05 2791 8192000000 10200569344 10581397504 1283036976 13.541 100 92 184 3 1317451088 14154352 20259760
17:19:09 24849 8192000000 10199498000 10589827072 1291636016 13.619 100 58 116 0 1314060720 9813872 12610832
17:20:35 5003 8192000000 10198638464 10602831872 1302412416 13.717 100 35 68 2 1317644832 7841072 7391344
17:21:40 5914 8192000000 10188869328 10607439872 1309713664 13.783 100 19 38 0 1319969392 5627280 4628448
17:22:47 5653 8192000000 10200178784 10618396672 1317438752 13.854 100 22 44 0 1329197920 7101760 4657408
17:23:53 31626 8192000000 10214448272 10634252288 1323329184 13.907 100 27 53 3 1336352624 6226384 6797056
17:25:25 11669 8192000000 10213937824 10637492224 1329340080 13.961 100 19 38 0 1338774560 5019072 4415408
17:26:37 6736 8192000000 10223994432 10644373504 1334525200 14.008 100 27 54 0 1346403152 5478464 6399488
17:27:46 13749 8192000000 10212286704 10628698112 1301979552 13.713 98 20 39 1 1312168704 5700400 4488752
17:29:00 8433 8192000000 10194640080 10599497728 1287808928 13.584 99 26 52 1 1299345904 5408256 6128720
17:30:11 30795 8192000000 10118649760 10511876096 1087938416 11.723 84 26 50 1 1103164576 9783280 5442880
17:31:43 7507 8192000000 10149260128 10552029184 1256471616 13.298 100 33 67 0 1271216240 7142176 7602448
17:32:51 5760 8192000000 10146744784 10547089408 1244549952 13.188 99 31 62 0 1258545888 5602928 8393008
17:33:57 7154 8192000000 10175617520 10584096768 1284068384 13.550 100 26 52 2 1296433712 5469120 6896208
17:35:05 26849 8192000000 10167843136 10582949888 1275119776 13.468 99 22 44 0 1285684480 5146768 5417936
17:36:35 8461 8192000000 10189080272 10602426368 1302207808 13.715 100 35 70 0 1316903792 6473344 8222640
17:37:44 6198 8192000000 10191533392 10608803840 1303792128 13.730 100 16 32 0 1313661168 5948368 3920672
17:39:00 17243 8192000000 10239598720 10648797184 1336118480 14.022 100 48 94 2 1354955280 7522928 11313872
17:40:22 63677 8192000000 10253714928 10674061312 1354273472 14.186 100 42 84 0 1373805776 9579648 9952656
17:43:07 8364 8192000000 10275082416 10688344064 1363579104 14.269 100 51 102 0 1389108864 13091360 12438400
17:44:18 12502 8192000000 10287929968 10705821696 1372708448 14.351 100 53 104 2 1399726992 15031152 11987392
17:45:40 16453 8192000000 10299347056 10715877376 1382197936 14.436 100 65 129 2 1415050976 17965072 14887968
17:47:06 8728 8192000000 10311461920 10735722496 1394566176 14.547 100 48 94 0 1421665616 15702848 11396592
17:48:19 4045 8192000000 10336666096 10750861312 1404671440 14.637 100 69 137 0 1441575920 21042240 15862240
17:49:26 3457 8192000000 10345059984 10762301440 1407574640 14.662 100 70 140 2 1450102320 25218560 17309120
17:50:35 17800 8192000000 10350518592 10773872640 1418487728 14.759 100 60 120 1 1463983872 30995488 14500656
17:51:55 6344 8192000000 10357877760 10778660864 1412694656 14.708 100 64 126 1 1462351456 33935216 15721584
17:53:13 1903 8192000000 10371912688 10786893824 1421052960 14.782 100 75 150 0 1474266768 34846240 18367568
17:54:20 2129 8192000000 10376578544 10786250752 1416250768 14.739 100 82 162 0 1476267040 39580064 20436208
17:55:28 1841 8192000000 10387254800 10798088192 1421389024 14.785 100 81 159 0 1490747568 48423056 20935488
17:56:37 8279 8192000000 10378174576 10791395328 1412034048 14.702 100 66 132 1 1479103008 49995456 17073504
17:57:48 8702 8192000000 10363489920 10779009024 1393530000 14.537 99 60 120 1 1457161504 47773696 15857808
17:59:08 14415 8192000000 10355195856 10778746880 1382180528 14.436 99 46 92 1 1450898784 55772624 12945632
18:00:27 12352 8192000000 10341428096 10786041856 1396344784 14.562 100 34 69 1 1452656736 46987248 9324704
18:01:45 29274 8192000000 10324923776 10769555456 1370620096 14.333 99 25 50 0 1420597984 43252368 6725520
18:03:15 34526 8192000000 10294646096 10733334528 1321895120 13.894 97 16 32 0 1367755936 41342528 4518288
18:04:52 9547 8192000000 10292452480 10730758144 1359269520 14.231 99 34 67 0 1397398144 29531568 8597056
18:06:02 43567 8192000000 10277241360 10721361920 1339770624 14.055 98 13 26 2 1359779424 16979136 3029664
18:07:53 119516 8192000000 10280722480 10715418624 1347611712 14.126 99 20 40 0 1360642880 8361456 4669712
18:11:05 98015 8192000000 10269634352 10704531456 1313466192 13.818 96 18 36 2 1326095488 8812784 3816512
18:13:44 124295 8192000000 10301280736 10735083520 1336334432 14.024 97 27 54 0 1353146064 10571712 6239920
18:16:51 35868 8192000000 10299135088 10752786432 1383708672 14.450 100 31 61 1 1402627888 11595664 7323552
18:18:39 147276 8192000000 10304205392 10758279168 1369017600 14.318 98 20 40 0 1390085968 15709888 5358480
18:22:45 64716 8192000000 10309141072 10769747968 1395179504 14.552 100 21 40 0 1418947664 19135328 4632832
18:24:55 36361 8192000000 10289266576 10751787008 1373304416 14.357 98 10 20 0 1383466272 7907872 2253984
18:26:32 86492 8192000000 10296894240 10768900096 1399219200 14.588 100 25 50 1 1417903648 13022896 5661552
18:29:00 64271 8192000000 10311461232 10783797248 1395671600 14.556 99 11 22 0 1415153904 16981120 2501184
18:31:05 69583 8192000000 10312355168 10772271104 1399580368 14.591 99 14 28 0 1413021600 10951680 2489552
18:33:18 11904 8192000000 10319197376 10771681280 1406159056 14.650 100 42 84 1 1426717472 10545824 10012592
18:34:33 16940 8192000000 10304847152 10757996544 1397713264 14.575 100 45 88 2 1417098080 8430896 10953920
18:35:50 37654 8192000000 10312637952 10760380416 1393311120 14.535 100 30 60 0 1414532256 14579600 6641536
18:37:28 52564 8192000000 10306459888 10766520320 1368524912 14.314 98 23 46 0 1397647968 23234320 5888736
18:39:21 64480 8192000000 10292309888 10738769920 1318042848 13.859 94 16 32 0 1332673168 11224208 3406112
18:41:26 84429 8192000000 10286036048 10741010432 1343736272 14.091 97 16 32 0 1361725120 14497712 3491136
18:43:51 77006 8192000000 10290124608 10735919104 1357431360 14.214 98 37 73 0 1383895200 17089024 9374816
18:46:14 69613 8192000000 10260059696 10722439168 1318126944 13.860 96 17 34 0 1330246240 8037552 4081744
18:48:34 59580 8192000000 10272613184 10728734720 1345590528 14.108 98 10 19 0 1366767488 18673664 2503296
18:50:40 84583 8192000000 10271068400 10740367360 1356410640 14.205 99 10 20 1 1372560528 13862768 2287120
18:53:08 67859 8192000000 10272221472 10715951104 1313012800 13.813 96 19 38 0 1332565952 15116352 4436800
18:55:23 63252 8192000000 10258205760 10706903040 1323407504 13.908 98 17 34 0 1338622672 11488288 3726880
18:57:33 86617 8192000000 10272971504 10726862848 1343943200 14.093 99 23 46 1 1361719072 12971056 4804816
19:00:01 74437 8192000000 10281300416 10723254272 1342270640 14.078 98 37 74 0 1365628016 14737392 8619984
19:02:16 40183 8192000000 10281660944 10734374912 1363297552 14.267 99 16 32 1 1379113376 11902640 3913184
19:03:56 102735 8192000000 10278900880 10740428800 1298095840 13.678 94 13 26 0 1316566048 15442352 3027856
19:06:39 58882 8192000000 10259881040 10696232960 1328548160 13.954 97 35 70 0 1343707888 6890496 8269232
19:08:50 51668 8192000000 10287959584 10739732480 1379568144 14.413 100 18 36 0 1398405760 14966144 3871472
19:10:41 45525 8192000000 10276946400 10746789888 1382919120 14.443 100 8 16 0 1395568432 10846416 1802896
19:12:32 106838 8192000000 10285802640 10756280320 1370798480 14.334 99 12 24 1 1383768784 10220848 2749456
19:15:19 98668 8192000000 10298438480 10758103040 1393957296 14.541 100 13 26 0 1415336848 17750288 3629264
19:18:35 39491 8192000000 10293997104 10745843712 1373483728 14.358 99 31 62 2 1390291104 10083040 6724336
19:20:17 22112 8192000000 10306977568 10740387840 1378991792 14.408 100 36 70 1 1396599568 9209136 8398640
19:21:41 43040 8192000000 10295382432 10740596736 1346203136 14.113 97 34 68 0 1365107232 11548976 7355120
19:23:26 91050 8192000000 10280366880 10745843712 1341188512 14.068 98 8 16 0 1360927360 18079472 1659376
19:25:58 48709 8192000000 10303696528 10758729728 1347703344 14.127 98 12 24 0 1363003376 12866576 2433456
19:27:47 140355 8192000000 10293816912 10752835584 1371488992 14.340 99 21 42 1 1402867584 26566000 4812592
19:31:16 57034 8192000000 10273774992 10736484352 1335807200 14.020 96 10 20 0 1347216832 9392208 2017424
19:33:18 93768 8192000000 10282009712 10733490176 1355826352 14.200 98 11 22 0 1368571456 10372544 2372560
19:35:53 84274 8192000000 10278061904 10739929088 1359013296 14.228 98 14 28 0 1376632048 14480640 3138112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-12-2021 19:40:13 to 11-12-2021 19:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 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 11-12-2021 19:40:29 to 11-12-2021 19:40:31.

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          231389
MON$OLDEST_ACTIVE               234789
MON$OLDEST_SNAPSHOT             234789
MON$NEXT_TRANSACTION            234789
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                       2036256
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.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Sat Dec 11 19:38:00 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Sat Dec 11 19:38:14 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Sat Dec 11 19:38:16 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Sat Dec 11 19:38:16 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
--- end of diff output ---

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

11.12.21 19:40:32. 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-12-11 19:40:34 No severe PSQL-related problems occured
11.12.21 19:40:36. End of report.