Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.05.2022 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16635856 kB
MemAvailable 31527836 kB
Buffers 0 kB
Cached 15107396 kB
SwapCached 2540 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-06 22:38:14
DTS_END                         2022-05-06 22:38:14.9210
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/51018
TRN_ID                          252939
ATT_ID                          673
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: 9068.25 21550 1088190
customer order: creation 185.52 330 22262
customer order: refuse 16.57 360 1988
order to supplier: creation 48.54 1175 5825
order to supplier: removal 3.46 88817 415
invoice (draft): creation 50.38 3497 6046
invoice (draft): removal 49.76 742 5971
invoice accept: apply 44.09 17594 5291
invoice accept: cancel 44.57 851 5348
customer reserve: creation 4003.61 177 480433
customer reserve: removal 4316.64 57 517997
realization accept: apply 89.76 180 10771
realization accept: cancel 75.95 99 9114
payment from customer: creation 64.86 116 7783
payment from customer: removal 6.08 103 729
payment to supplier: creation 51.80 131 6216
payment to supplier: removal 3.60 70 432
service: total inventory turnovers 4.87 1128 584
service: total monetary turnovers 7.32 31 878
service: aggregate perf. data 0.86 18930 103
service: refresh index statistics 0.03 296616 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-05-2022 22:39:47 to 06-05-2022 22:39:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5977
TEST_TIME 2 4575
TEST_TIME 3 4112
TEST_TIME 4 4857
TEST_TIME 5 4767
TEST_TIME 6 5183
TEST_TIME 7 5450
TEST_TIME 8 5912
TEST_TIME 9 6578
TEST_TIME 10 6584
TEST_TIME 11 6704
TEST_TIME 12 6733
TEST_TIME 13 6865
TEST_TIME 14 7193
TEST_TIME 15 7282
TEST_TIME 16 7545
TEST_TIME 17 7538
TEST_TIME 18 7808
TEST_TIME 19 7986
TEST_TIME 20 7978
TEST_TIME 21 8317
TEST_TIME 22 8270
TEST_TIME 23 8626
TEST_TIME 24 8966
TEST_TIME 25 9089
TEST_TIME 26 9098
TEST_TIME 27 9314
TEST_TIME 28 9618
TEST_TIME 29 10079
TEST_TIME 30 10358
TEST_TIME 31 10421
TEST_TIME 32 10525
TEST_TIME 33 10497
TEST_TIME 34 10308
TEST_TIME 35 10202
TEST_TIME 36 10110
TEST_TIME 37 10027
TEST_TIME 38 10017
TEST_TIME 39 9827
TEST_TIME 40 9858
TEST_TIME 41 9796
TEST_TIME 42 9797
TEST_TIME 43 9745
TEST_TIME 44 9723
TEST_TIME 45 9611
TEST_TIME 46 9646
TEST_TIME 47 9569
TEST_TIME 48 9429
TEST_TIME 49 9514
TEST_TIME 50 9516
TEST_TIME 51 9401
TEST_TIME 52 9318
TEST_TIME 53 9266
TEST_TIME 54 9266
TEST_TIME 55 9289
TEST_TIME 56 9271
TEST_TIME 57 9372
TEST_TIME 58 9410
TEST_TIME 59 9459
TEST_TIME 60 9480
TEST_TIME 61 9506
TEST_TIME 62 9583
TEST_TIME 63 9589
TEST_TIME 64 9644
TEST_TIME 65 9698
TEST_TIME 66 9659
TEST_TIME 67 9740
TEST_TIME 68 9637
TEST_TIME 69 9566
TEST_TIME 70 9502
TEST_TIME 71 9535
TEST_TIME 72 9476
TEST_TIME 73 9416
TEST_TIME 74 9374
TEST_TIME 75 9349
TEST_TIME 76 9285
TEST_TIME 77 9225
TEST_TIME 78 9228
TEST_TIME 79 9273
TEST_TIME 80 9288
TEST_TIME 81 9268
TEST_TIME 82 9335
TEST_TIME 83 9391
TEST_TIME 84 9331
TEST_TIME 85 9376
TEST_TIME 86 9442
TEST_TIME 87 9442
TEST_TIME 88 9536
TEST_TIME 89 9562
TEST_TIME 90 9516
TEST_TIME 91 9476
TEST_TIME 92 9437
TEST_TIME 93 9380
TEST_TIME 94 9428
TEST_TIME 95 9370
TEST_TIME 96 9327
TEST_TIME 97 9376
TEST_TIME 98 9355
TEST_TIME 99 9314
TEST_TIME 100 9327
TEST_TIME 101 9393
TEST_TIME 102 9400
TEST_TIME 103 9375
TEST_TIME 104 9379
TEST_TIME 105 9402
TEST_TIME 106 9378
TEST_TIME 107 9380
TEST_TIME 108 9421
TEST_TIME 109 9408
TEST_TIME 110 9470
TEST_TIME 111 9404
TEST_TIME 112 9357
TEST_TIME 113 9325
TEST_TIME 114 9281
TEST_TIME 115 9239
TEST_TIME 116 9215
TEST_TIME 117 9191
TEST_TIME 118 9147
TEST_TIME 119 9131
TEST_TIME 120 9069

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-05-2022 22:39:50 to 06-05-2022 22:39:54.

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 1533558 1533558 0 0.00 0 22211 6 0 0
doc_list_biud 1533558 1533558 0 0.00 0 32373 25 0 0
sp_add_invoice_to_stock 5404 5291 113 2.09 38 181559 17595 0 113
sp_cancel_adding_invoice 5348 5348 0 0.00 6 64627 851 0 0
sp_cancel_client_order 1988 1988 0 0.00 4 18229 361 0 0
sp_cancel_customer_reserve 517997 517997 0 0.00 1 22219 57 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 3 12304 104 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 3858 70 0 0
sp_cancel_supplier_invoice 5980 5971 9 0.15 2 16105 743 0 9
sp_cancel_supplier_order 415 415 0 0.00 6 1108852 88817 0 0
sp_cancel_write_off 9114 9114 0 0.00 1 7889 100 0 0
sp_client_order 22262 22262 0 0.00 3 33775 330 0 0
sp_customer_reserve 480433 480433 0 0.00 0 55373 177 0 0
sp_fill_shopping_cart 34120 34120 0 0.00 1 10627 37 0 0
sp_fill_shopping_cart_clo_res 480431 480431 0 0.00 0 24272 12 0 0
sp_get_clo_for_invoice 5288 5288 0 0.00 0 25168 287 0 0
sp_kill_cost_storno 15623 15623 0 0.00 0 15652 55 0 0
sp_kill_qstorno_ret_qs2qd 1051494 1051494 0 0.00 0 22214 31 0 0
sp_kill_qty_storno 556892 556892 0 0.00 0 32372 67 0 0
sp_lock_selected_doc 22285 22285 0 0.00 0 8975 6 0 0
sp_make_cost_storno 30058 30058 0 0.00 0 17320 55 0 0
sp_multiply_rows_for_pdistr 30058 30058 0 0.00 0 7222 30 0 0
sp_multiply_rows_for_qdistr 22262 22262 0 0.00 0 25642 153 0 0
sp_pay_from_customer 7783 7783 0 0.00 4 12608 116 0 0
sp_pay_to_supplier 6216 6216 0 0.00 4 14742 131 0 0
sp_payment_common 13999 13999 0 0.00 1 12008 30 0 0
sp_qd_handle_on_cancel_clo 1988 1988 0 0.00 0 18039 335 0 0
sp_qd_handle_on_invoice_upd_sts 10636 10636 0 0.00 0 32370 314 0 0
sp_qd_handle_on_reserve_upd_sts 10771 10771 0 0.00 0 20564 54 0 0
sp_reserve_write_off 10771 10771 0 0.00 6 20581 181 0 0
sp_supplier_invoice 6046 6046 0 0.00 6 94699 3498 0 0
sp_supplier_order 5825 5825 0 0.00 5 49958 1176 0 0
srv_aggregate_perf_data 103 103 0 0.00 2219 206331 18930 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1588 132358 48416 0 0
srv_find_qd_qs_mism 1037652 1037652 0 0.00 0 22211 5 0 0
srv_make_invnt_saldo 584 584 0 0.00 44 62763 1128 0 0
srv_make_money_saldo 878 878 0 0.00 0 4747 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 162056 400835 296616 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5291 5291 0 0.00 0 16234 175 0 0
t$perf-norm:sp_cancel_adding_invoice 454 454 0 0.00 0 2245 96 0 0
t$perf-norm:sp_cancel_client_order 1988 1988 0 0.00 0 3622 17 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 4687 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 1458 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 15554 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 949 14 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 25635 1578 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 5216 26 0 0
t$perf-norm:sp_client_order 22262 22262 0 0.00 0 7369 18 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 1927 8 0 0
t$perf-norm:sp_pay_from_customer 7784 7784 0 0.00 0 4977 8 0 0
t$perf-norm:sp_pay_to_supplier 6216 6216 0 0.00 0 2969 9 0 0
t$perf-norm:sp_reserve_write_off 10771 10771 0 0.00 0 5684 12 0 0
t$perf-norm:sp_supplier_invoice 6046 6046 0 0.00 0 13012 22 0 0
t$perf-norm:sp_supplier_order 5825 5825 0 0.00 0 8514 22 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 408 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 6254 106 0 0
t$perf-norm:srv_make_invnt_saldo 584 584 0 0.00 0 1974 29 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 2804 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 3 0 0
v_add_invoice_to_stock 10802 10576 226 2.09 0 14107 19 0 226
v_all_customers 40740 40740 0 0.00 0 17 1 0 0
v_all_suppliers 32688 32688 0 0.00 0 23 1 0 0
v_all_wares 44524 44524 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 908 908 0 0.00 0 2934 13 0 0
v_cancel_client_order 3976 3976 0 0.00 0 3027 4 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 12273 19 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 916 4 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 852 5 0 0
v_cancel_supplier_invoice 900 882 18 2.00 0 2143 15 0 18
v_cancel_supplier_order 830 830 0 0.00 0 315 3 0 0
v_cancel_write_off 2084 2084 0 0.00 0 4303 17 0 0
v_max_id_clo_ord 5821 5821 0 0.00 0 1339 2 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 4098 20 0 0
v_max_id_ord_sup 6037 6037 0 0.00 0 5044 4 0 0
v_max_non_paid_invoice 1730 1730 0 0.00 0 496 3 0 0
v_max_non_paid_realizn 4213 4213 0 0.00 0 4096 5 0 0
v_min_id_clo_ord 5821 5821 0 0.00 0 6261 8 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 1594 6 0 0
v_min_id_ord_sup 6037 6037 0 0.00 0 7792 12 0 0
v_min_non_paid_invoice 6216 6216 0 0.00 0 12001 20 0 0
v_min_non_paid_realizn 7783 7783 0 0.00 0 11793 22 0 0
v_our_firm 10924 10924 0 0.00 0 17 1 0 0
v_reserve_write_off 21542 21542 0 0.00 0 9162 8 0 0
x_lock_depdocs_on_canc_invoice 10878 10878 0 0.00 0 17271 111 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 8914 304 0 0
x_make_qstorno_1000_1200 5825 5825 0 0.00 1 49940 1092 0 0
x_make_qstorno_1200_2000 6046 6046 0 0.00 2 94683 3371 0 0
x_make_qstorno_2100_3300 480433 480433 0 0.00 0 52653 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-05-2022 22:39:55 to 06-05-2022 22:39:59.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:18 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:19 8756 8192000000 9876047184 10179878912 964859200 10.537 100 87 174 0 994298256 5638992 23800064
20:10:33 26309 8192000000 9937144192 10256097280 1022176112 11.093 100 90 180 0 1053022368 7453760 23392496
20:12:01 24854 8192000000 9980342784 10306277376 1057169888 11.429 100 91 182 1 1089496928 9423744 22903296
20:13:33 13993 8192000000 10020352928 10366480384 1113230864 11.963 100 71 142 2 1139280656 8941728 17108064
20:14:50 9029 8192000000 10076496000 10432385024 1161514944 12.417 100 75 149 1 1189629536 10755088 17359504
20:16:03 6349 8192000000 10131086080 10496548864 1211960208 12.887 100 85 169 1 1244805856 13530016 19315632
20:17:13 3558 8192000000 10164386032 10535866368 1249157376 13.230 100 85 168 1 1282052944 14079984 18815584
20:18:19 2793 8192000000 10193007456 10571157504 1271825952 13.438 100 88 176 1 1304921408 13847040 19248416
20:19:24 1973 8192000000 10207958192 10591830016 1301399808 13.708 100 92 186 3 1335289776 12792672 21097296
20:20:27 33266 8192000000 10221740272 10618339328 1312045616 13.805 100 73 148 1 1342432848 12348752 18038480
20:22:02 5296 8192000000 10207763008 10617380864 1315131504 13.833 100 34 68 0 1330645904 7367024 8147376
20:23:09 14152 8192000000 10213154992 10630639616 1328159536 13.951 100 18 36 1 1337350080 5446608 3743936
20:24:24 9901 8192000000 10221042736 10643890176 1335593552 14.018 100 16 32 0 1344425904 5361680 3470672
20:25:35 39353 8192000000 10223929760 10651639808 1337757648 14.037 100 39 80 0 1352871856 5398528 9715680
20:27:18 5907 8192000000 10205683216 10633019392 1306405680 13.753 98 16 32 1 1315779984 5673088 3701216
20:28:25 8634 8192000000 10200220576 10618519552 1312733824 13.811 100 22 44 0 1323542640 5893136 4915680
20:29:34 47609 8192000000 10174948576 10573717504 1207029968 12.842 93 19 37 1 1221528640 9053712 5444960
20:31:27 7702 8192000000 10140580672 10537119744 1203052384 12.805 95 32 62 0 1216288912 6307808 6928720
20:32:35 4948 8192000000 10160287584 10563534848 1259033040 13.321 99 26 52 2 1271178464 5373280 6772144
20:33:41 7002 8192000000 10165971376 10566807552 1268984112 13.412 100 32 64 2 1282398384 5736960 7677312
20:34:50 45084 8192000000 10168886384 10581565440 1277775776 13.493 100 33 66 1 1292296016 7071152 7449088
20:36:37 6321 8192000000 10190578992 10606972928 1304048640 13.732 100 23 46 0 1315684480 6294992 5340848
20:37:43 6695 8192000000 10208212416 10623467520 1315788672 13.839 100 21 42 1 1326420704 5826480 4805552
20:38:55 27508 8192000000 10247021440 10660544512 1342556368 14.080 100 38 76 0 1358000896 6910848 8533680
20:40:24 100550 8192000000 10257504976 10680324096 1359722816 14.235 100 30 60 0 1375554368 9226560 6604992
20:43:11 29494 8192000000 10288100672 10705297408 1371652592 14.342 100 45 90 0 1393834800 11329024 10853184
20:44:59 10389 8192000000 10290374224 10714906624 1381187568 14.427 100 44 88 0 1407744960 16091840 10465552
20:46:17 3210 8192000000 10321238144 10736992256 1389993456 14.506 100 70 138 1 1426094336 19148624 16952256
20:47:24 12736 8192000000 10314075648 10738757632 1397325968 14.571 100 58 114 3 1432367648 21633872 13407808
20:48:41 16499 8192000000 10290165904 10717048832 1356629152 14.207 97 44 88 3 1386367792 19309376 10429264
20:50:03 3428 8192000000 10312414112 10729783296 1386564416 14.475 100 66 132 0 1421162256 18661264 15936576
20:51:12 2914 8192000000 10310190672 10727391232 1382609008 14.440 100 61 122 1 1418467168 20568176 15289984
20:52:22 9300 8192000000 10298625984 10717110272 1370225360 14.329 100 59 118 0 1409388832 24346528 14816944
20:53:37 4688 8192000000 10327594928 10730823680 1377856656 14.397 100 83 167 4 1424731824 26616496 20258672
20:54:46 3240 8192000000 10331580304 10744881152 1384975568 14.461 100 86 170 0 1435419680 29459872 20984240
20:55:55 1587 8192000000 10305041152 10704908288 1347078464 14.121 99 89 178 4 1401992800 33326976 21587360
20:57:02 4638 8192000000 10320519216 10724388864 1372609728 14.350 100 75 149 0 1423862368 32398384 18854256
20:58:15 5051 8192000000 10310745600 10719293440 1364595200 14.279 100 58 114 2 1411410944 31775872 15039872
20:59:28 5921 8192000000 10317790112 10730856448 1367731008 14.307 99 50 100 1 1414835888 34314272 12790608
21:00:40 9100 8192000000 10316829632 10735751168 1364669488 14.279 99 40 79 1 1415067344 39324192 11073664
21:01:54 23503 8192000000 10328286896 10752983040 1381464752 14.430 100 37 74 2 1435943200 44266752 10211696
21:03:43 28634 8192000000 10332801440 10772652032 1394944432 14.550 100 28 56 1 1441876160 39149840 7781888
21:05:26 17509 8192000000 10329796064 10769477632 1393985392 14.541 100 26 52 0 1440770384 39345904 7439088
21:06:55 31878 8192000000 10291513392 10740547584 1374859808 14.371 100 13 26 0 1405567200 27330096 3377296
21:08:27 55163 8192000000 10281491072 10730745856 1351542880 14.161 98 22 44 0 1379949392 23353248 5053264
21:10:28 132357 8192000000 10315130800 10751926272 1394584592 14.547 100 21 42 9 1417623712 17801936 5237184
21:13:49 91953 8192000000 10303848784 10762371072 1397085200 14.569 100 23 43 2 1412625680 9897056 5643424
21:16:43 93527 8192000000 10330417808 10784538624 1410778944 14.691 100 33 65 1 1433313120 14717552 7816624
21:19:19 71272 8192000000 10333941984 10786508800 1416536416 14.742 100 25 50 0 1430057376 7572448 5948512
21:21:36 35350 8192000000 10322942192 10770432000 1400642160 14.601 99 22 44 2 1415526544 9736768 5147616
21:23:15 80394 8192000000 10323530448 10784604160 1398235184 14.579 98 15 30 0 1411758768 9765056 3758528
21:25:36 86457 8192000000 10311380048 10778333184 1397957504 14.577 99 16 32 2 1415846640 14034128 3855008
21:28:32 49155 8192000000 10291408448 10753445888 1383235936 14.445 99 25 51 2 1396627104 8039264 5351904
21:31:06 43503 8192000000 10292127072 10766004224 1377419248 14.393 98 5 10 0 1385073248 6721792 932208
21:32:50 64120 8192000000 10298439056 10767818752 1360371504 14.241 97 10 19 0 1379456688 16963552 2121632
21:36:01 44673 8192000000 10285304448 10739642368 1343530976 14.089 97 18 36 0 1359600384 12219072 3850336
21:38:06 19162 8192000000 10274885088 10720116736 1331578400 13.981 97 40 80 0 1355015120 15090384 8346336
21:39:29 70424 8192000000 10296561664 10746519552 1347769648 14.127 97 28 56 1 1372845728 18237632 6838448
21:41:42 84402 8192000000 10267621424 10714247168 1264714928 13.373 92 15 31 1 1278691792 11016400 2960464
21:44:09 54898 8192000000 10266418016 10698756096 1278870512 13.503 94 33 66 0 1297973104 11320768 7781824
21:46:06 94366 8192000000 10250309712 10692239360 1336904608 14.029 99 32 64 1 1352895312 8724800 7265904
21:49:00 45050 8192000000 10250350448 10711683072 1345002672 14.102 99 17 35 3 1359284112 10267088 4014352
21:50:46 60874 8192000000 10264328800 10717581312 1356473184 14.206 99 11 22 0 1364392864 5880560 2039120
21:52:53 73799 8192000000 10256245568 10713628672 1348294288 14.132 99 25 50 0 1362747920 8726272 5727360
21:55:22 65759 8192000000 10248524656 10702827520 1334216256 14.005 98 17 34 1 1352416496 14273744 3926496
21:57:30 30919 8192000000 10278609280 10732601344 1371364128 14.339 100 18 36 0 1391195104 15445248 4385728
21:59:08 48455 8192000000 10280825424 10733854720 1374012704 14.363 100 20 40 0 1392977408 13731744 5232960
22:01:11 118258 8192000000 10300828688 10749292544 1362347216 14.258 98 22 44 1 1379663824 12258992 5057616
22:04:13 24816 8192000000 10297998512 10751758336 1387293360 14.482 100 22 44 0 1402499360 10286688 4919312
22:05:39 60048 8192000000 10297822112 10766778368 1396071552 14.560 100 13 26 3 1412580544 13515088 2993904
22:07:50 97316 8192000000 10292906448 10749784064 1360289296 14.240 97 20 40 1 1376348080 11330864 4727920
22:11:15 30256 8192000000 10313196032 10761142272 1388551200 14.493 99 48 96 4 1415431520 16291248 10589072
22:12:46 59421 8192000000 10293510784 10763464704 1382856016 14.442 99 10 20 0 1395498896 10795712 1847168
22:14:46 67714 8192000000 10287018640 10765631488 1353994368 14.183 97 5 10 1 1372266368 17477376 794624
22:16:57 27577 8192000000 10294555344 10753126400 1380077328 14.417 99 14 28 0 1394067424 10752240 3237856
22:18:25 90879 8192000000 10278184864 10741022720 1316687376 13.847 95 17 34 2 1337569888 16721264 4161248
22:21:09 9652 8192000000 10297972064 10737561600 1375390576 14.375 100 49 96 1 1392130496 5737920 11002000
22:22:21 60612 8192000000 10279074560 10731540480 1330781232 13.974 97 28 56 1 1347667696 10702096 6184368
22:24:25 32816 8192000000 10264048032 10706837504 1324772464 13.920 97 39 78 1 1342699296 8786384 9140448
22:25:59 84716 8192000000 10250428224 10700611584 1268350576 13.407 94 16 32 0 1286802032 15122256 3329200
22:28:25 124982 8192000000 10252327600 10704625664 1321281440 13.888 98 14 28 0 1341401072 16855504 3264128
22:31:47 63717 8192000000 10235592640 10699067392 1308599040 13.773 97 7 14 0 1319829728 9654896 1575792
22:33:52 69244 8192000000 10242276064 10703364096 1349379488 14.142 100 7 14 0 1356408560 5681488 1347584
22:36:01 83453 8192000000 10238861920 10698637312 1313757872 13.820 97 6 14 0 1321167104 5948480 1460752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 06-05-2022 22:40:01 to 06-05-2022 22:40:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-05-2022 22:40:16 to 06-05-2022 22:40:18.

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          252473
MON$OLDEST_ACTIVE               253130
MON$OLDEST_SNAPSHOT             253130
MON$NEXT_TRANSACTION            253130
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                       2026848
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33586 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33586 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33586 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33586 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 ---
:60: 
:61: mscs-test-161.budzdorov.ru    Fri May  6 22:38:16 2022
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Fri May  6 22:38:22 2022
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:67: 
:68: 
:69: mscs-test-161.budzdorov.ru    Fri May  6 22:38:22 2022
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Fri May  6 22:38:22 2022
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Fri May  6 22:38:22 2022
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Fri May  6 22:38:22 2022
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Fri May  6 22:38:22 2022
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Fri May  6 22:38:22 2022
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Fri May  6 22:38:22 2022
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Fri May  6 22:38:22 2022
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Fri May  6 22:38:22 2022
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:103: 
--- end of diff output ---

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

06.05.22 22:40:19. 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-05-06 22:40:21 No severe PSQL-related problems occured
06.05.22 22:40:23. End of report.