Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.2022 16: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 16651612 kB
MemAvailable 31252868 kB
Buffers 0 kB
Cached 14999896 kB
SwapCached 2292 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-11 16:38:02
DTS_END                         2022-01-11 16:38:02.7870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60380
TRN_ID                          242980
ATT_ID                          643
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: 9206.63 21359 1104795
customer order: creation 176.94 371 21233
customer order: refuse 14.97 322 1796
order to supplier: creation 47.37 1257 5684
order to supplier: removal 3.36 101325 403
invoice (draft): creation 47.55 3488 5706
invoice (draft): removal 51.07 792 6128
invoice accept: apply 42.52 17993 5102
invoice accept: cancel 46.98 783 5637
customer reserve: creation 3926.09 178 471131
customer reserve: removal 4541.26 59 544951
realization accept: apply 86.49 173 10379
realization accept: cancel 87.83 107 10539
payment from customer: creation 60.98 108 7317
payment from customer: removal 6.16 82 739
payment to supplier: creation 50.89 116 6107
payment to supplier: removal 3.68 46 441
service: total inventory turnovers 4.67 1066 560
service: total monetary turnovers 6.92 31 830
service: aggregate perf. data 0.90 19483 108
service: refresh index statistics 0.03 279403 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2022 16:38:52 to 11-01-2022 16:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6260
TEST_TIME 2 5848
TEST_TIME 3 5261
TEST_TIME 4 5051
TEST_TIME 5 5075
TEST_TIME 6 5010
TEST_TIME 7 4981
TEST_TIME 8 5245
TEST_TIME 9 5353
TEST_TIME 10 5895
TEST_TIME 11 5858
TEST_TIME 12 6219
TEST_TIME 13 6316
TEST_TIME 14 6799
TEST_TIME 15 7152
TEST_TIME 16 7250
TEST_TIME 17 7435
TEST_TIME 18 7708
TEST_TIME 19 8301
TEST_TIME 20 8330
TEST_TIME 21 9160
TEST_TIME 22 9167
TEST_TIME 23 9524
TEST_TIME 24 9355
TEST_TIME 25 10304
TEST_TIME 26 11290
TEST_TIME 27 10940
TEST_TIME 28 10872
TEST_TIME 29 10760
TEST_TIME 30 10576
TEST_TIME 31 10371
TEST_TIME 32 10311
TEST_TIME 33 10162
TEST_TIME 34 9994
TEST_TIME 35 9826
TEST_TIME 36 9856
TEST_TIME 37 9749
TEST_TIME 38 9750
TEST_TIME 39 9603
TEST_TIME 40 9771
TEST_TIME 41 9776
TEST_TIME 42 9663
TEST_TIME 43 9629
TEST_TIME 44 9719
TEST_TIME 45 9653
TEST_TIME 46 9648
TEST_TIME 47 9719
TEST_TIME 48 9671
TEST_TIME 49 9682
TEST_TIME 50 9723
TEST_TIME 51 9696
TEST_TIME 52 9672
TEST_TIME 53 9836
TEST_TIME 54 9817
TEST_TIME 55 9665
TEST_TIME 56 9652
TEST_TIME 57 9535
TEST_TIME 58 9487
TEST_TIME 59 9453
TEST_TIME 60 9364
TEST_TIME 61 9397
TEST_TIME 62 9356
TEST_TIME 63 9321
TEST_TIME 64 9322
TEST_TIME 65 9424
TEST_TIME 66 9416
TEST_TIME 67 9379
TEST_TIME 68 9392
TEST_TIME 69 9430
TEST_TIME 70 9566
TEST_TIME 71 9541
TEST_TIME 72 9541
TEST_TIME 73 9551
TEST_TIME 74 9527
TEST_TIME 75 9596
TEST_TIME 76 9645
TEST_TIME 77 9607
TEST_TIME 78 9538
TEST_TIME 79 9476
TEST_TIME 80 9418
TEST_TIME 81 9401
TEST_TIME 82 9368
TEST_TIME 83 9363
TEST_TIME 84 9306
TEST_TIME 85 9248
TEST_TIME 86 9306
TEST_TIME 87 9295
TEST_TIME 88 9322
TEST_TIME 89 9301
TEST_TIME 90 9317
TEST_TIME 91 9317
TEST_TIME 92 9376
TEST_TIME 93 9370
TEST_TIME 94 9401
TEST_TIME 95 9447
TEST_TIME 96 9481
TEST_TIME 97 9470
TEST_TIME 98 9491
TEST_TIME 99 9560
TEST_TIME 100 9570
TEST_TIME 101 9479
TEST_TIME 102 9411
TEST_TIME 103 9388
TEST_TIME 104 9343
TEST_TIME 105 9309
TEST_TIME 106 9265
TEST_TIME 107 9232
TEST_TIME 108 9249
TEST_TIME 109 9230
TEST_TIME 110 9223
TEST_TIME 111 9215
TEST_TIME 112 9210
TEST_TIME 113 9238
TEST_TIME 114 9258
TEST_TIME 115 9268
TEST_TIME 116 9274
TEST_TIME 117 9256
TEST_TIME 118 9222
TEST_TIME 119 9284
TEST_TIME 120 9207

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2022 16:38:56 to 11-01-2022 16:38:59.

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 1541217 1541217 0 0.00 0 16603 6 0 0
doc_list_biud 1541210 1541210 0 0.00 0 54536 27 0 0
sp_add_invoice_to_stock 5225 5102 123 2.35 36 166441 17993 0 123
sp_cancel_adding_invoice 5637 5637 0 0.00 5 58801 784 0 0
sp_cancel_client_order 1796 1796 0 0.00 4 11718 322 0 0
sp_cancel_customer_reserve 544951 544951 0 0.00 1 54539 59 0 0
sp_cancel_pay_from_customer 739 739 0 0.00 2 5464 82 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 2 754 47 0 0
sp_cancel_supplier_invoice 6142 6128 14 0.22 1 14529 792 0 14
sp_cancel_supplier_order 403 403 0 0.00 5 1081977 101326 0 0
sp_cancel_write_off 10539 10539 0 0.00 1 19001 107 0 0
sp_client_order 21233 21233 0 0.00 3 28981 371 0 0
sp_customer_reserve 471131 471131 0 0.00 0 108930 178 0 0
sp_fill_shopping_cart 32601 32601 0 0.00 1 17230 39 0 0
sp_fill_shopping_cart_clo_res 471125 471125 0 0.00 0 18914 15 0 0
sp_get_clo_for_invoice 5096 5096 0 0.00 0 33683 401 0 0
sp_kill_cost_storno 17356 17356 0 0.00 0 6947 47 0 0
sp_kill_qstorno_ret_qs2qd 1106972 1106972 0 0.00 0 54530 32 0 0
sp_kill_qty_storno 584926 584926 0 0.00 0 54536 70 0 0
sp_lock_selected_doc 21418 21418 0 0.00 0 8688 5 0 0
sp_make_cost_storno 28899 28899 0 0.00 0 13345 50 0 0
sp_multiply_rows_for_pdistr 28898 28898 0 0.00 0 15020 30 0 0
sp_multiply_rows_for_qdistr 21233 21233 0 0.00 0 16712 164 0 0
sp_pay_from_customer 7317 7317 0 0.00 4 16078 109 0 0
sp_pay_to_supplier 6107 6107 0 0.00 4 15031 116 0 0
sp_payment_common 13421 13421 0 0.00 2 9557 23 0 0
sp_qd_handle_on_cancel_clo 1796 1796 0 0.00 0 11710 294 0 0
sp_qd_handle_on_invoice_upd_sts 10733 10733 0 0.00 0 23304 323 0 0
sp_qd_handle_on_reserve_upd_sts 10376 10376 0 0.00 0 17902 63 0 0
sp_reserve_write_off 10379 10379 0 0.00 6 20295 173 0 0
sp_supplier_invoice 5706 5706 0 0.00 6 90807 3488 0 0
sp_supplier_order 5684 5684 0 0.00 6 35637 1257 0 0
srv_aggregate_perf_data 108 108 0 0.00 1884 308173 19483 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2269 130727 46677 0 0
srv_find_qd_qs_mism 1055781 1055781 0 0.00 0 11430 6 0 0
srv_make_invnt_saldo 560 560 0 0.00 7 106588 1067 0 0
srv_make_money_saldo 830 830 0 0.00 0 4084 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 177628 473883 279404 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5102 5102 0 0.00 0 13481 166 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 2739 56 0 0
t$perf-norm:sp_cancel_client_order 1796 1796 0 0.00 0 14808 28 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 6224 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 739 739 0 0.00 0 2758 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 2670 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1848 11 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 29665 1390 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 1498 5 0 0
t$perf-norm:sp_client_order 21233 21233 0 0.00 0 9788 17 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 6317 13 0 0
t$perf-norm:sp_pay_from_customer 7317 7317 0 0.00 0 6300 9 0 0
t$perf-norm:sp_pay_to_supplier 6107 6107 0 0.00 0 6404 10 0 0
t$perf-norm:sp_reserve_write_off 10379 10379 0 0.00 0 16300 12 0 0
t$perf-norm:sp_supplier_invoice 5706 5706 0 0.00 0 8839 22 0 0
t$perf-norm:sp_supplier_order 5684 5684 0 0.00 0 4921 13 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 241 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 23 1 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 755 19 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 518 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10436 10190 246 2.35 0 3181 15 0 246
v_all_customers 38484 38484 0 0.00 0 16 0 0 0
v_all_suppliers 31368 31368 0 0.00 0 15 1 0 0
v_all_wares 42464 42464 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 302 6 0 0
v_cancel_client_order 3592 3592 0 0.00 0 2036 5 0 0
v_cancel_customer_prepayment 1478 1478 0 0.00 0 3527 9 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 3077 6 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 660 3 0 0
v_cancel_supplier_invoice 850 822 28 3.29 0 2456 16 0 28
v_cancel_supplier_order 806 806 0 0.00 0 492 4 0 0
v_cancel_write_off 2006 2006 0 0.00 0 5976 14 0 0
v_max_id_clo_ord 5675 5675 0 0.00 0 1662 1 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 1976 13 0 0
v_max_id_ord_sup 5692 5692 0 0.00 0 5971 6 0 0
v_max_non_paid_invoice 1788 1788 0 0.00 0 2091 5 0 0
v_max_non_paid_realizn 3994 3994 0 0.00 0 4630 4 0 0
v_min_id_clo_ord 5675 5675 0 0.00 0 2512 5 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 2695 13 0 0
v_min_id_ord_sup 5692 5692 0 0.00 0 6645 8 0 0
v_min_non_paid_invoice 6106 6106 0 0.00 0 9548 14 0 0
v_min_non_paid_realizn 7315 7315 0 0.00 0 9351 20 0 0
v_our_firm 10622 10622 0 0.00 0 116 1 0 0
v_reserve_write_off 20752 20752 0 0.00 0 3681 5 0 0
x_lock_depdocs_on_canc_invoice 11354 11354 0 0.00 0 29498 115 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 6103 310 0 0
x_make_qstorno_1000_1200 5684 5684 0 0.00 2 35625 1187 0 0
x_make_qstorno_1200_2000 5706 5706 0 0.00 2 90777 3338 0 0
x_make_qstorno_2100_3300 471131 471131 0 0.00 0 108929 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-01-2022 16:39:00 to 11-01-2022 16:39:04.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:06 18 8192000000 8809561440 8824459264 14104512 0.171 5 2 4 0 14260224 70752 84960
14:09:07 18406 8192000000 9908117152 10222145536 991122992 10.792 100 89 178 0 1021135456 6753328 23259136
14:10:31 25542 8192000000 9946858224 10272858112 1028245200 11.152 100 92 184 1 1061247296 9474000 23528096
14:12:03 19880 8192000000 9986168304 10321956864 1064925360 11.504 100 81 162 0 1095286416 10062032 20299024
14:13:28 11053 8192000000 10026502368 10374852608 1111526608 11.947 100 79 158 1 1141500864 11095648 18878608
14:14:40 9493 8192000000 10083888400 10443542528 1165467408 12.454 100 86 172 0 1198056368 12027616 20561344
14:15:52 5213 8192000000 10127916208 10497523712 1213262160 12.899 100 77 155 0 1242758368 12773632 16722576
14:17:00 2608 8192000000 10167390864 10541457408 1247288544 13.213 100 92 185 1 1283056416 14739552 21028320
14:18:04 2248 8192000000 10198611568 10582335488 1281136528 13.523 100 93 187 1 1316705152 14822032 20746592
14:19:08 6220 8192000000 10197225632 10598432768 1295826032 13.657 100 66 132 1 1322682720 12189536 14667152
14:20:36 11935 8192000000 10192360048 10607681536 1309808320 13.784 100 23 46 0 1320364944 5960432 4596192
14:21:48 9869 8192000000 10203005312 10619273216 1315071600 13.832 100 24 48 0 1327202944 6865712 5265632
14:23:01 63988 8192000000 10217588624 10640474112 1325027552 13.922 100 41 82 0 1340798352 6316752 9454048
14:25:13 6698 8192000000 10224660208 10649227264 1334239168 14.005 100 17 34 1 1348291216 10448000 3604048
14:26:25 10451 8192000000 10223834112 10645233664 1319843632 13.875 99 25 50 1 1332105168 6317232 5944304
14:27:36 56792 8192000000 10219695568 10634129408 1297218464 13.670 97 37 74 1 1312921856 7017296 8686096
14:29:34 4963 8192000000 10189119680 10603122688 1300005648 13.695 100 34 68 0 1315047280 7001616 8040016
14:30:39 9034 8192000000 10106717920 10502746112 1169236016 12.490 94 18 36 1 1179339648 5562752 4540880
14:31:50 4836 8192000000 10139609952 10544287744 1248069040 13.220 100 20 40 0 1259624128 6702448 4852640
14:33:00 39742 8192000000 10156764384 10552016896 1241502688 13.160 98 47 94 2 1259502176 5445904 12553584
14:34:40 6281 8192000000 10166159056 10578182144 1281058432 13.523 100 23 42 1 1291974320 5742736 5173152
14:35:49 14648 8192000000 10184900400 10598379520 1294238512 13.643 100 15 30 2 1302859504 5304656 3316336
14:37:09 27296 8192000000 10189823856 10613420032 1305266384 13.743 100 17 32 0 1314701120 5018016 4416720
14:38:47 31659 8192000000 10234333120 10643238912 1326729120 13.938 100 36 72 0 1340852480 6519808 7603552
14:40:26 13309 8192000000 10265457392 10675474432 1351138720 14.158 100 61 120 2 1376544320 11656000 13749600
14:41:44 33322 8192000000 10288678352 10708967424 1374779504 14.370 100 44 88 1 1401588208 16195936 10612768
14:43:23 14218 8192000000 10295981680 10712510464 1375700624 14.378 99 43 86 0 1404633344 18735728 10196992
14:44:53 4297 8192000000 10322380816 10744954880 1404786704 14.638 100 47 92 2 1439072192 23506144 10779344
14:46:02 10281 8192000000 10339982848 10761076736 1409029984 14.675 100 55 110 1 1449425760 26804784 13590992
14:47:22 17143 8192000000 10349598848 10774208512 1411099056 14.694 100 45 90 0 1452961984 30860112 11002816
14:48:44 5156 8192000000 10372698640 10793734144 1423995984 14.808 100 66 132 0 1475146832 35439776 15711072
14:49:54 5293 8192000000 10368492448 10782519296 1399170192 14.588 98 81 161 1 1456230976 38049120 19011664
14:51:03 2257 8192000000 10367920320 10777403392 1411363584 14.696 100 88 176 2 1474892736 42227600 21301552
14:52:11 2977 8192000000 10375628368 10791612416 1412404400 14.705 100 79 157 4 1484134800 52024352 19706048
14:53:20 4633 8192000000 10356308032 10771677184 1393048432 14.533 100 62 123 2 1456423344 48205856 15169056
14:54:32 4856 8192000000 10368172080 10775285760 1391942944 14.523 100 86 170 0 1465684752 52875408 20866400
14:55:42 5130 8192000000 10368267296 10783281152 1394705472 14.548 100 64 128 1 1471223136 59842976 16674688
14:56:56 14193 8192000000 10335507088 10753585152 1362085264 14.256 100 44 87 2 1441734640 66957184 12692192
14:58:16 14690 8192000000 10344686128 10765565952 1377901248 14.398 100 45 90 0 1450340224 59697840 12741136
14:59:48 13933 8192000000 10332864368 10768306176 1377534720 14.395 99 36 70 1 1433404784 46691728 9178336
15:01:10 14228 8192000000 10332702832 10763747328 1370625440 14.333 99 24 48 2 1424930832 47481504 6823888
15:02:39 21118 8192000000 10330452528 10758664192 1348170192 14.131 99 28 56 2 1415367856 58873568 8324096
15:04:02 27841 8192000000 10276225712 10728845312 1344186640 14.095 98 12 24 1 1385247760 37554512 3506608
15:05:35 43993 8192000000 10277337056 10710405120 1336696816 14.028 98 36 69 3 1356819584 11424496 8698272
15:07:41 130726 8192000000 10288465536 10742235136 1360293168 14.240 98 11 22 0 1372215840 9688576 2234096
15:10:52 63977 8192000000 10295408752 10753347584 1383204624 14.445 99 10 21 0 1395954608 10138496 2611488
15:13:06 79813 8192000000 10317600368 10779627520 1409807296 14.682 100 21 42 0 1435361456 20545248 5008912
15:15:27 76388 8192000000 10301626928 10766135296 1378185504 14.400 98 11 22 0 1391377488 10553968 2638016
15:17:45 61396 8192000000 10294901616 10763427840 1381753216 14.432 99 17 32 1 1402672416 16891536 4027664
15:19:50 26920 8192000000 10303027888 10754879488 1391967152 14.523 100 33 63 1 1412257936 13065616 7225168
15:21:27 113348 8192000000 10296352304 10760065024 1358625120 14.225 97 22 44 1 1380174752 16124544 5425088
15:24:21 55736 8192000000 10311836016 10765860864 1395928864 14.559 100 24 48 2 1415907088 14770976 5207248
15:26:17 32526 8192000000 10312756368 10765103104 1406515584 14.653 100 30 61 1 1426969008 13260656 7192768
15:27:52 41630 8192000000 10307256288 10763558912 1391001904 14.515 99 25 50 0 1409541648 12916608 5623136
15:29:37 36788 8192000000 10325983216 10776612864 1406579488 14.654 100 27 54 0 1426848960 14096336 6173136
15:31:27 85910 8192000000 10298748912 10765074432 1358823200 14.227 97 9 17 1 1382603808 21981488 1799120
15:34:47 49164 8192000000 10288917248 10745323520 1381656864 14.431 100 29 58 0 1400608240 12413824 6537552
15:36:38 37449 8192000000 10280770528 10732609536 1364649968 14.279 99 22 44 1 1381673328 12230432 4792928
15:39:07 68484 8192000000 10276062208 10746961920 1375462752 14.376 99 18 36 0 1394998640 15282240 4253648
15:41:40 61163 8192000000 10264941712 10724397056 1330779264 13.974 97 11 23 0 1341881280 8679344 2422672
15:43:44 62783 8192000000 10246319376 10702290944 1295819312 13.657 95 13 25 1 1308248544 9370128 3059104
15:45:48 31024 8192000000 10266035088 10705006592 1325213488 13.924 97 27 54 1 1344982096 13251552 6517056
15:47:19 68078 8192000000 10271867232 10726047744 1333293024 13.997 98 14 27 0 1361459056 24779360 3386672
15:49:28 59583 8192000000 10264365088 10712899584 1351932176 14.165 99 20 40 0 1371613648 15313584 4367888
15:51:27 36937 8192000000 10267446768 10702999552 1311046352 13.796 96 22 44 1 1324199408 7846400 5306656
15:53:06 101831 8192000000 10276621552 10731266048 1335744384 14.019 98 13 26 0 1350547296 12226608 2576304
15:55:49 112725 8192000000 10257521856 10712293376 1364124576 14.274 100 34 68 0 1380747904 9089056 7534272
15:58:43 6515 8192000000 10279417040 10721157120 1367330480 14.303 100 46 92 2 1387063360 9340576 10392304
15:59:50 60842 8192000000 10272905568 10733178880 1362850608 14.263 99 14 28 1 1379226768 13338400 3037760
16:01:51 28049 8192000000 10274840336 10741682176 1376648928 14.387 100 5 10 0 1393152640 15769936 733776
16:03:20 70009 8192000000 10270695200 10732552192 1370102144 14.328 99 18 36 1 1389505168 14638192 4764832
16:05:30 100703 8192000000 10283380416 10743808000 1354651760 14.189 98 12 24 1 1366645136 9433840 2559536
16:08:11 41690 8192000000 10291129520 10739429376 1373944368 14.362 99 22 45 0 1386446432 7601328 4900736
16:09:54 61485 8192000000 10295700512 10757038080 1389762176 14.504 100 18 36 1 1409169040 15462704 3944160
16:11:58 71963 8192000000 10309153344 10761555968 1384593600 14.458 99 26 52 1 1405871216 15371360 5906256
16:14:13 56521 8192000000 10296323648 10744160256 1380049824 14.417 99 22 44 0 1394716592 9398256 5268512
16:16:16 19085 8192000000 10317675584 10761793536 1396739008 14.566 100 22 44 1 1410147328 8538912 4869408
16:17:40 129114 8192000000 10286973056 10740355072 1355819456 14.200 97 11 22 0 1377117024 18661184 2636384
16:20:49 27263 8192000000 10286377680 10741448704 1376760816 14.388 99 15 30 1 1387233152 7352048 3120288
16:22:16 56737 8192000000 10272388800 10733010944 1349468240 14.143 97 18 36 0 1359968544 6690064 3810240
16:24:17 66040 8192000000 10264234240 10724966400 1358195904 14.221 98 9 18 0 1371220240 10943616 2080720
16:26:23 53987 8192000000 10228248624 10682159104 1299052272 13.687 95 4 8 0 1307755024 8006576 696176
16:28:18 48999 8192000000 10269242512 10729357312 1375700080 14.378 100 18 36 0 1388721856 9100544 3921232
16:30:15 42763 8192000000 10260551504 10707095552 1309735824 13.784 95 19 38 0 1325239520 11203696 4300000
16:32:00 68454 8192000000 10261069664 10706575360 1329643200 13.964 98 19 38 0 1350021808 15790432 4588176
16:34:09 91340 8192000000 10273859680 10718035968 1314422240 13.826 96 22 44 0 1327139984 8052464 4665280
16:36:42 86818 8192000000 9730606128 10103803904 842663488 9.327 61 16 32 0 861037168 14477728 3895952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-01-2022 16:39:06 to 11-01-2022 16:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-01-2022 16:39:21 to 11-01-2022 16:39:23.

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          241151
MON$OLDEST_ACTIVE               243214
MON$OLDEST_SNAPSHOT             243214
MON$NEXT_TRANSACTION            243214
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                       2005312
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 ---
:794: 
:795: mscs-test-161.budzdorov.ru    Tue Jan 11 16:38:19 2022
:796:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:797: 
:798: 
:799: mscs-test-161.budzdorov.ru    Tue Jan 11 16:38:21 2022
:800:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:801: 
:802: 
:803: mscs-test-161.budzdorov.ru    Tue Jan 11 16:38:21 2022
:804:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:805: 
--- end of diff output ---

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

11.01.22 16:39:24. 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-11 16:39:27 No severe PSQL-related problems occured
11.01.22 16:39:28. End of report.