Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.07.2022 01: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 16238384 kB
MemAvailable 31092376 kB
Buffers 0 kB
Cached 15504096 kB
SwapCached 2492 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.11
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-07-20 01:38:28
DTS_END                         2022-07-20 01:38:28.1020
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/54394
TRN_ID                          241795
ATT_ID                          708
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: 9285.09 20075 1114211
customer order: creation 174.13 367 20896
customer order: refuse 14.94 330 1793
order to supplier: creation 46.64 1238 5597
order to supplier: removal 3.49 96726 419
invoice (draft): creation 46.48 3267 5578
invoice (draft): removal 52.63 842 6316
invoice accept: apply 42.68 16853 5121
invoice accept: cancel 47.93 818 5751
customer reserve: creation 3905.73 169 468687
customer reserve: removal 4651.40 56 558168
realization accept: apply 85.98 179 10317
realization accept: cancel 81.13 106 9735
payment from customer: creation 60.65 106 7278
payment from customer: removal 5.98 76 718
payment to supplier: creation 49.90 119 5988
payment to supplier: removal 3.63 75 435
service: total inventory turnovers 4.73 874 568
service: total monetary turnovers 6.21 38 745
service: aggregate perf. data 0.81 19801 97
service: refresh index statistics 0.03 259459 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-07-2022 01:40:03 to 20-07-2022 01:40:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6809
TEST_TIME 2 7342
TEST_TIME 3 6870
TEST_TIME 4 6106
TEST_TIME 5 6044
TEST_TIME 6 5590
TEST_TIME 7 5862
TEST_TIME 8 5674
TEST_TIME 9 5684
TEST_TIME 10 5566
TEST_TIME 11 5768
TEST_TIME 12 6250
TEST_TIME 13 6576
TEST_TIME 14 7011
TEST_TIME 15 7351
TEST_TIME 16 7365
TEST_TIME 17 7637
TEST_TIME 18 8455
TEST_TIME 19 9000
TEST_TIME 20 9300
TEST_TIME 21 9417
TEST_TIME 22 9431
TEST_TIME 23 9574
TEST_TIME 24 9636
TEST_TIME 25 9419
TEST_TIME 26 10119
TEST_TIME 27 10875
TEST_TIME 28 10934
TEST_TIME 29 10801
TEST_TIME 30 10575
TEST_TIME 31 10448
TEST_TIME 32 10492
TEST_TIME 33 10219
TEST_TIME 34 10281
TEST_TIME 35 10441
TEST_TIME 36 10331
TEST_TIME 37 10231
TEST_TIME 38 10170
TEST_TIME 39 10101
TEST_TIME 40 10029
TEST_TIME 41 9926
TEST_TIME 42 9860
TEST_TIME 43 9858
TEST_TIME 44 9858
TEST_TIME 45 9752
TEST_TIME 46 9715
TEST_TIME 47 9577
TEST_TIME 48 9526
TEST_TIME 49 9451
TEST_TIME 50 9393
TEST_TIME 51 9410
TEST_TIME 52 9474
TEST_TIME 53 9461
TEST_TIME 54 9494
TEST_TIME 55 9541
TEST_TIME 56 9468
TEST_TIME 57 9498
TEST_TIME 58 9514
TEST_TIME 59 9562
TEST_TIME 60 9576
TEST_TIME 61 9566
TEST_TIME 62 9651
TEST_TIME 63 9672
TEST_TIME 64 9704
TEST_TIME 65 9633
TEST_TIME 66 9531
TEST_TIME 67 9500
TEST_TIME 68 9437
TEST_TIME 69 9461
TEST_TIME 70 9408
TEST_TIME 71 9397
TEST_TIME 72 9354
TEST_TIME 73 9367
TEST_TIME 74 9370
TEST_TIME 75 9400
TEST_TIME 76 9414
TEST_TIME 77 9434
TEST_TIME 78 9405
TEST_TIME 79 9387
TEST_TIME 80 9505
TEST_TIME 81 9480
TEST_TIME 82 9569
TEST_TIME 83 9502
TEST_TIME 84 9595
TEST_TIME 85 9573
TEST_TIME 86 9583
TEST_TIME 87 9482
TEST_TIME 88 9438
TEST_TIME 89 9373
TEST_TIME 90 9378
TEST_TIME 91 9376
TEST_TIME 92 9324
TEST_TIME 93 9262
TEST_TIME 94 9286
TEST_TIME 95 9263
TEST_TIME 96 9269
TEST_TIME 97 9273
TEST_TIME 98 9284
TEST_TIME 99 9303
TEST_TIME 100 9339
TEST_TIME 101 9388
TEST_TIME 102 9366
TEST_TIME 103 9344
TEST_TIME 104 9370
TEST_TIME 105 9445
TEST_TIME 106 9483
TEST_TIME 107 9526
TEST_TIME 108 9486
TEST_TIME 109 9430
TEST_TIME 110 9406
TEST_TIME 111 9437
TEST_TIME 112 9404
TEST_TIME 113 9428
TEST_TIME 114 9373
TEST_TIME 115 9364
TEST_TIME 116 9310
TEST_TIME 117 9323
TEST_TIME 118 9293
TEST_TIME 119 9277
TEST_TIME 120 9289

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-07-2022 01:40:06 to 20-07-2022 01:40:09.

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 1548207 1548207 0 0.00 0 23544 6 0 0
doc_list_biud 1548206 1548206 0 0.00 0 49694 26 0 0
sp_add_invoice_to_stock 5267 5121 146 2.77 20 180183 16854 0 146
sp_cancel_adding_invoice 5751 5751 0 0.00 4 62966 818 0 0
sp_cancel_client_order 1793 1793 0 0.00 3 7971 331 0 0
sp_cancel_customer_reserve 558168 558168 0 0.00 0 49698 56 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 2 7806 76 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 3475 76 0 0
sp_cancel_supplier_invoice 6320 6316 4 0.06 1 22391 843 0 4
sp_cancel_supplier_order 419 419 0 0.00 5 941332 96726 0 0
sp_cancel_write_off 9735 9735 0 0.00 1 14836 106 0 0
sp_client_order 20896 20896 0 0.00 3 24976 367 0 0
sp_customer_reserve 468687 468687 0 0.00 0 50354 169 0 0
sp_fill_shopping_cart 32071 32071 0 0.00 1 19421 40 0 0
sp_fill_shopping_cart_clo_res 468683 468683 0 0.00 0 15324 14 0 0
sp_get_clo_for_invoice 5116 5116 0 0.00 0 35634 324 0 0
sp_kill_cost_storno 16639 16639 0 0.00 0 23501 54 0 0
sp_kill_qstorno_ret_qs2qd 1132806 1132806 0 0.00 0 49680 31 0 0
sp_kill_qty_storno 597613 597613 0 0.00 0 49694 67 0 0
sp_lock_selected_doc 21414 21414 0 0.00 0 8375 7 0 0
sp_make_cost_storno 28698 28698 0 0.00 0 23376 51 0 0
sp_multiply_rows_for_pdistr 28697 28697 0 0.00 0 6188 24 0 0
sp_multiply_rows_for_qdistr 20896 20896 0 0.00 0 11975 158 0 0
sp_pay_from_customer 7278 7278 0 0.00 4 13029 106 0 0
sp_pay_to_supplier 5988 5988 0 0.00 4 10828 120 0 0
sp_payment_common 13266 13266 0 0.00 1 7300 25 0 0
sp_qd_handle_on_cancel_clo 1793 1793 0 0.00 0 7963 292 0 0
sp_qd_handle_on_invoice_upd_sts 10866 10866 0 0.00 0 29964 318 0 0
sp_qd_handle_on_reserve_upd_sts 10316 10316 0 0.00 0 14837 59 0 0
sp_reserve_write_off 10317 10317 0 0.00 6 21875 180 0 0
sp_supplier_invoice 5578 5578 0 0.00 6 78873 3267 0 0
sp_supplier_order 5597 5597 0 0.00 4 58914 1239 0 0
srv_aggregate_perf_data 97 97 0 0.00 2088 114484 19801 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 4169 121457 42334 0 0
srv_find_qd_qs_mism 1065702 1065702 0 0.00 0 13648 5 0 0
srv_make_invnt_saldo 568 568 0 0.00 39 37361 875 0 0
srv_make_money_saldo 745 745 0 0.00 1 10772 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 121056 385947 259460 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5121 5121 0 0.00 0 10587 149 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 6712 89 0 0
t$perf-norm:sp_cancel_client_order 1793 1793 0 0.00 0 992 7 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 1032 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 4063 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1275 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 837 8 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 23279 1481 0 0
t$perf-norm:sp_cancel_write_off 1063 1063 0 0.00 0 8147 18 0 0
t$perf-norm:sp_client_order 20896 20896 0 0.00 0 9592 14 0 0
t$perf-norm:sp_customer_reserve 1081 1081 0 0.00 0 6288 10 0 0
t$perf-norm:sp_pay_from_customer 7278 7278 0 0.00 0 9316 10 0 0
t$perf-norm:sp_pay_to_supplier 5988 5988 0 0.00 0 9786 11 0 0
t$perf-norm:sp_reserve_write_off 10317 10317 0 0.00 0 8088 12 0 0
t$perf-norm:sp_supplier_invoice 5578 5578 0 0.00 0 7076 16 0 0
t$perf-norm:sp_supplier_order 5597 5597 0 0.00 0 6904 18 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 436 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 185 6 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 1512 17 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 1719 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10522 10230 292 2.77 0 5824 20 0 292
v_all_customers 37994 37994 0 0.00 0 21 0 0 0
v_all_suppliers 30798 30798 0 0.00 0 26 1 0 0
v_all_wares 41792 41792 0 0.00 0 118 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 8375 16 0 0
v_cancel_client_order 3586 3586 0 0.00 0 7709 10 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 3598 8 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 1285 5 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 1909 9 0 0
v_cancel_supplier_invoice 806 798 8 0.99 0 11193 30 0 8
v_cancel_supplier_order 838 838 0 0.00 0 4470 18 0 0
v_cancel_write_off 2126 2126 0 0.00 0 4286 15 0 0
v_max_id_clo_ord 5597 5597 0 0.00 0 935 1 0 0
v_max_id_clo_res 1081 1081 0 0.00 0 3930 17 0 0
v_max_id_ord_sup 5578 5578 0 0.00 0 2449 3 0 0
v_max_non_paid_invoice 1764 1764 0 0.00 0 3409 5 0 0
v_max_non_paid_realizn 3925 3925 0 0.00 0 2017 4 0 0
v_min_id_clo_ord 5597 5597 0 0.00 0 2093 6 0 0
v_min_id_clo_res 1081 1081 0 0.00 0 2940 8 0 0
v_min_id_ord_sup 5578 5578 0 0.00 0 8334 8 0 0
v_min_non_paid_invoice 5988 5988 0 0.00 0 4910 17 0 0
v_min_non_paid_realizn 7278 7278 0 0.00 0 6351 21 0 0
v_our_firm 10504 10504 0 0.00 0 25 1 0 0
v_reserve_write_off 20632 20632 0 0.00 0 21688 10 0 0
x_lock_depdocs_on_canc_invoice 11668 11668 0 0.00 0 16710 112 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 11194 370 0 0
x_make_qstorno_1000_1200 5597 5597 0 0.00 1 58909 1168 0 0
x_make_qstorno_1200_2000 5578 5578 0 0.00 3 78836 3118 0 0
x_make_qstorno_2100_3300 468687 468687 0 0.00 0 50328 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-07-2022 01:40:10 to 20-07-2022 01:40:14.

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
23:08:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:32 21365 8192000000 9891425328 10201870336 974688400 10.632 100 86 172 0 1003336624 6257296 22390928
23:11:01 17884 8192000000 9938761360 10260385792 1022809456 11.099 100 85 168 0 1053177424 8194032 22173936
23:12:22 11472 8192000000 9983056656 10311684096 1060867216 11.465 100 88 176 0 1092106560 9138096 22101248
23:13:42 9264 8192000000 10010925424 10354597888 1100407520 11.842 100 79 158 0 1129529840 10047120 19075200
23:14:54 5361 8192000000 10062472960 10417897472 1152152544 12.330 100 78 155 1 1181068560 10835424 18080592
23:16:08 4099 8192000000 10112864912 10480295936 1206407888 12.836 100 76 149 1 1234053392 11115136 16530368
23:17:16 1590 8192000000 10158779152 10531393536 1245792192 13.200 100 86 172 1 1278847904 14092224 18963488
23:18:21 1464 8192000000 10190835824 10573725696 1271158624 13.432 100 88 176 1 1304076448 14192864 18724960
23:19:24 1517 8192000000 10211196128 10599542784 1298812672 13.684 100 85 171 0 1329756832 12049104 18895056
23:20:27 25906 8192000000 10196024192 10606395392 1305541232 13.746 100 29 56 0 1318461104 6562912 6356960
23:21:55 9786 8192000000 10194776000 10613796864 1313411440 13.817 100 15 29 1 1322845712 5924496 3509776
23:23:07 11625 8192000000 10202756576 10623520768 1321293424 13.888 100 16 30 0 1329627264 5204768 3129072
23:24:20 57208 8192000000 10233126112 10641686528 1329817840 13.966 100 48 92 1 1346442992 5882368 10742784
23:26:17 2918 8192000000 10228541680 10648485888 1336813840 14.029 100 29 58 1 1349650560 5809120 7027600
23:27:21 5424 8192000000 10231779760 10655666176 1341883216 14.074 100 17 34 0 1351683328 6087024 3713088
23:28:29 49910 8192000000 10197895296 10614099968 1279442064 13.508 97 34 66 1 1292435648 5065552 7928032
23:30:23 4914 8192000000 10138444928 10542104576 1224197536 13.000 97 33 66 1 1239049600 7082656 7769408
23:31:28 11146 8192000000 10140914880 10536775680 1200024016 12.777 96 15 29 0 1212146224 9345584 2776624
23:32:43 54594 8192000000 10155231952 10558222336 1254253440 13.277 99 32 64 1 1266970992 5407376 7310176
23:34:43 6155 8192000000 10182561760 10592854016 1290143984 13.606 100 28 56 0 1303274848 6832160 6298704
23:35:50 9285 8192000000 10189955632 10609926144 1305223776 13.743 100 19 38 1 1314726192 5524400 3978016
23:36:59 34737 8192000000 10194895248 10616369152 1311922288 13.804 100 12 26 0 1319668384 5316400 2429696
23:38:56 6513 8192000000 10243418240 10655793152 1337962432 14.039 100 45 90 0 1359869632 11971632 9935568
23:40:11 6481 8192000000 10263116864 10677690368 1355783664 14.199 100 51 100 0 1377098448 10127104 11187680
23:41:27 9075 8192000000 10279736416 10699149312 1368811360 14.316 100 46 92 0 1396107840 16566720 10729760
23:42:40 18360 8192000000 10296742192 10718187520 1382942192 14.443 100 47 94 1 1411491296 17255632 11293472
23:44:09 13682 8192000000 10308638336 10737909760 1399568240 14.591 100 32 64 0 1426514112 18689344 8256528
23:45:31 4169 8192000000 10333720960 10759524352 1412142704 14.703 100 52 101 2 1448890528 24565408 12182416
23:46:46 7357 8192000000 10348797504 10769965056 1419595904 14.769 100 60 120 0 1461171952 27018784 14557264
23:48:00 5114 8192000000 10349927184 10770554880 1417305088 14.749 100 64 124 4 1462090736 28933184 15852464
23:49:08 9940 8192000000 10353275616 10777571328 1416895120 14.745 100 57 114 1 1467484576 35666656 14922800
23:50:21 9561 8192000000 10355077824 10786676736 1426364016 14.829 100 48 96 2 1468821856 30110112 12347728
23:51:44 4870 8192000000 10348723584 10768982016 1402358928 14.616 100 71 138 0 1458044080 38668816 17016336
23:52:54 8196 8192000000 10335610816 10763448320 1387712208 14.485 99 42 82 0 1432806576 33330992 11763376
23:54:08 9160 8192000000 10329771840 10754785280 1376823840 14.388 99 45 91 1 1425914832 36827232 12263760
23:55:25 5637 8192000000 10337492864 10751520768 1376294640 14.383 100 68 136 0 1440634176 47007488 17332048
23:56:40 6093 8192000000 10329680432 10750611456 1380096240 14.417 100 53 106 1 1437595952 43671088 13828624
23:57:50 23708 8192000000 10287505088 10715369472 1326999648 13.940 96 37 72 0 1375085616 38105600 9980368
23:59:18 7490 8192000000 10297411568 10718113792 1342613904 14.081 97 34 67 0 1380516256 29077568 8824784
00:00:29 17589 8192000000 10306786464 10732351488 1370323744 14.330 100 41 83 0 1410261840 29359616 10578480
00:01:52 9933 8192000000 10305158240 10733240320 1375454944 14.376 100 28 56 0 1412122448 29199968 7467536
00:03:03 22762 8192000000 10307710016 10742173696 1358238864 14.222 99 31 62 0 1404473664 37798976 8435824
00:04:30 12657 8192000000 10319984176 10756554752 1351979216 14.165 99 32 64 0 1415259936 53917440 9363280
00:05:59 50992 8192000000 10271043120 10697498624 1288723856 13.593 95 29 59 0 1317250992 21445712 7081424
00:07:53 96020 8192000000 10273790464 10711183360 1340169632 14.059 99 21 41 0 1366673264 21378464 5125168
00:10:54 55900 8192000000 10287902080 10719129600 1357061648 14.211 99 35 70 0 1377688240 12909680 7716912
00:13:20 44292 8192000000 10278488112 10728198144 1361597936 14.252 99 10 20 0 1373042960 9480432 1964592
00:15:05 58824 8192000000 10277149120 10731012096 1368912768 14.317 99 11 22 0 1379783072 8421584 2448720
00:17:05 50430 8192000000 10298248992 10750316544 1388837568 14.495 100 19 39 0 1406096224 12728464 4530192
00:18:56 65763 8192000000 10302705504 10760572928 1397259568 14.571 100 15 30 0 1416583648 15923424 3400656
00:21:03 112930 8192000000 10266698288 10723110912 1326808400 13.938 95 18 36 1 1342904224 12046896 4048928
00:23:57 39700 8192000000 10277963488 10735403008 1372674032 14.351 99 11 22 0 1386045536 10914080 2457424
00:25:48 62733 8192000000 10278213104 10744549376 1374662256 14.369 99 21 38 0 1392000192 12640864 4697072
00:27:51 79272 8192000000 10271142112 10744397824 1380499712 14.421 99 12 24 0 1392390816 9101744 2789360
00:30:11 48008 8192000000 10292078832 10759172096 1380631776 14.422 99 10 20 1 1396481040 13667936 2181328
00:32:07 38270 8192000000 10302632528 10770255872 1406736736 14.655 100 19 39 1 1420605360 9709328 4159296
00:33:52 51704 8192000000 10325449840 10775699456 1409450208 14.679 100 28 54 0 1423912768 8692912 5769648
00:35:49 49432 8192000000 10304800976 10762063872 1353458288 14.179 96 29 58 0 1372512912 12969760 6084864
00:37:40 54654 8192000000 10287919936 10744614912 1351268224 14.159 97 22 44 0 1366404640 10686528 4449888
00:39:36 38900 8192000000 10292777760 10756050944 1371686624 14.342 98 11 22 0 1386022176 11747856 2587696
00:41:15 53627 8192000000 10291229424 10747101184 1325318880 13.925 95 8 16 0 1336361952 9426224 1616848
00:43:09 98956 8192000000 10276639408 10718101504 1341604448 14.072 97 36 72 2 1357898640 7876816 8417376
00:45:52 50977 8192000000 10271080224 10731749376 1354433296 14.187 98 10 20 0 1368182800 11481440 2268064
00:47:46 56072 8192000000 10239071952 10703400960 1330007952 13.967 98 11 22 0 1347817824 15067936 2741936
00:49:43 41257 8192000000 10251793904 10701180928 1346858048 14.119 100 11 23 0 1361382512 11720000 2804464
00:52:30 44795 8192000000 10251815888 10707845120 1360229424 14.239 100 15 30 2 1376105008 11854256 4021328
00:54:15 76410 8192000000 10260263792 10713710592 1310065184 13.787 96 19 38 1 1323534320 8865712 4603424
00:57:22 22555 8192000000 10274255184 10715033600 1345019776 14.103 98 47 93 0 1366685120 10589456 11075888
00:58:45 74391 8192000000 10246698016 10694529024 1276615984 13.482 94 11 22 0 1290579232 11209984 2753264
01:01:02 48641 8192000000 10275865088 10710380544 1339230016 14.050 99 35 70 0 1361672016 14462544 7979456
01:02:56 121444 8192000000 10261017904 10719526912 1312839872 13.812 97 6 12 1 1332737680 19014096 883712
01:05:59 80742 8192000000 10249912944 10694684672 1321165600 13.887 97 19 38 0 1332204192 7086944 3951648
01:08:38 33585 8192000000 10249290592 10712252416 1363668048 14.270 100 9 16 0 1371656720 6030592 1958080
01:10:15 59792 8192000000 10274538000 10733305856 1378262816 14.401 100 13 26 0 1387977008 6955952 2758240
01:12:15 69030 8192000000 10277496000 10735562752 1372586352 14.350 99 10 20 0 1383586608 9225536 1774720
01:14:30 23000 8192000000 10296817152 10741071872 1387373536 14.482 100 46 90 1 1403665072 6293120 9998416
01:15:57 64653 8192000000 10304239296 10758397952 1383015888 14.444 99 15 30 0 1403630960 16853456 3761616
01:18:16 37899 8192000000 10296823680 10744942592 1375445920 14.376 99 16 32 0 1388111792 9084432 3581440
01:19:56 39404 8192000000 10294067648 10737397760 1351341360 14.160 97 26 53 0 1366088912 8910544 5837008
01:21:41 12530 8192000000 10319573872 10751586304 1386288688 14.473 100 59 118 1 1412125904 13199968 12637248
01:22:58 53585 8192000000 10297100848 10750586880 1385681952 14.467 100 16 32 0 1403575248 14297776 3595520
01:24:55 58403 8192000000 10289486448 10758688768 1344604000 14.099 97 8 16 0 1365564208 18704272 2255936
01:26:53 105104 8192000000 10267406800 10713305088 1332653808 13.991 97 24 46 0 1346442832 8598368 5190656
01:29:58 42184 8192000000 10253890240 10711498752 1331834624 13.984 98 15 30 0 1351841280 16508592 3498064
01:32:34 67013 8192000000 10235518016 10686865408 1308343792 13.771 96 10 19 0 1317712240 7481264 1887184
01:34:47 76313 8192000000 10248832192 10710904832 1316394288 13.844 97 9 18 0 1326867488 8510912 1962288
01:37:09 23058 8192000000 10269763280 10722783232 1356361296 14.205 99 44 88 1 1381237456 14802448 10073712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-07-2022 01:40:16 to 20-07-2022 01:40:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-07-2022 01:40:32 to 20-07-2022 01:40:34.

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          238702
MON$OLDEST_ACTIVE               241919
MON$OLDEST_SNAPSHOT             241919
MON$NEXT_TRANSACTION            241919
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                       2013312
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 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 ---
:221: 
:222: mscs-test-161.budzdorov.ru    Wed Jul 20 01:38:40 2022
:223:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:224: 
:225: 
:226: mscs-test-161.budzdorov.ru    Wed Jul 20 01:38:41 2022
:227:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:228: 
:229: 
:230: mscs-test-161.budzdorov.ru    Wed Jul 20 01:38:46 2022
:231:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:232: 
:233: 
:234: mscs-test-161.budzdorov.ru    Wed Jul 20 01:38:46 2022
:235:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:236: 
--- end of diff output ---

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

20.07.22 01:40:35. 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-07-20 01:40:37 No severe PSQL-related problems occured
20.07.22 01:40:38. End of report.