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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-18 19:37:53
DTS_END                         2021-11-18 19:37:53.2280
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/38590
TRN_ID                          244760
ATT_ID                          648
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: 9110.16 21039 1093219
customer order: creation 174.93 312 20991
customer order: refuse 14.43 328 1731
order to supplier: creation 46.37 1213 5564
order to supplier: removal 3.26 81161 391
invoice (draft): creation 46.42 3204 5570
invoice (draft): removal 50.43 685 6051
invoice accept: apply 42.65 16977 5118
invoice accept: cancel 45.58 770 5469
customer reserve: creation 3947.01 169 473641
customer reserve: removal 4447.89 47 533747
realization accept: apply 84.03 183 10084
realization accept: cancel 73.99 100 8879
payment from customer: creation 60.75 140 7290
payment from customer: removal 5.93 94 712
payment to supplier: creation 50.69 132 6083
payment to supplier: removal 3.59 63 431
service: total inventory turnovers 4.65 967 558
service: total monetary turnovers 6.67 34 800
service: aggregate perf. data 0.88 16202 105
service: refresh index statistics 0.03 297993 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-11-2021 19:39:35 to 18-11-2021 19:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3154
TEST_TIME 2 3664
TEST_TIME 3 3770
TEST_TIME 4 4706
TEST_TIME 5 4717
TEST_TIME 6 4775
TEST_TIME 7 5077
TEST_TIME 8 5264
TEST_TIME 9 5517
TEST_TIME 10 5838
TEST_TIME 11 6136
TEST_TIME 12 6102
TEST_TIME 13 6643
TEST_TIME 14 6685
TEST_TIME 15 6706
TEST_TIME 16 6989
TEST_TIME 17 7128
TEST_TIME 18 7858
TEST_TIME 19 8365
TEST_TIME 20 8696
TEST_TIME 21 8952
TEST_TIME 22 9330
TEST_TIME 23 9570
TEST_TIME 24 10039
TEST_TIME 25 10228
TEST_TIME 26 10065
TEST_TIME 27 10312
TEST_TIME 28 10194
TEST_TIME 29 10253
TEST_TIME 30 10179
TEST_TIME 31 10312
TEST_TIME 32 10207
TEST_TIME 33 10401
TEST_TIME 34 10179
TEST_TIME 35 9973
TEST_TIME 36 9805
TEST_TIME 37 9761
TEST_TIME 38 9659
TEST_TIME 39 9685
TEST_TIME 40 9719
TEST_TIME 41 9660
TEST_TIME 42 9564
TEST_TIME 43 9467
TEST_TIME 44 9470
TEST_TIME 45 9438
TEST_TIME 46 9362
TEST_TIME 47 9316
TEST_TIME 48 9313
TEST_TIME 49 9239
TEST_TIME 50 9235
TEST_TIME 51 9202
TEST_TIME 52 9211
TEST_TIME 53 9198
TEST_TIME 54 9283
TEST_TIME 55 9336
TEST_TIME 56 9317
TEST_TIME 57 9445
TEST_TIME 58 9456
TEST_TIME 59 9429
TEST_TIME 60 9564
TEST_TIME 61 9624
TEST_TIME 62 9663
TEST_TIME 63 9552
TEST_TIME 64 9461
TEST_TIME 65 9479
TEST_TIME 66 9395
TEST_TIME 67 9452
TEST_TIME 68 9382
TEST_TIME 69 9369
TEST_TIME 70 9313
TEST_TIME 71 9306
TEST_TIME 72 9394
TEST_TIME 73 9347
TEST_TIME 74 9454
TEST_TIME 75 9476
TEST_TIME 76 9513
TEST_TIME 77 9488
TEST_TIME 78 9506
TEST_TIME 79 9529
TEST_TIME 80 9568
TEST_TIME 81 9645
TEST_TIME 82 9541
TEST_TIME 83 9495
TEST_TIME 84 9421
TEST_TIME 85 9437
TEST_TIME 86 9385
TEST_TIME 87 9322
TEST_TIME 88 9267
TEST_TIME 89 9253
TEST_TIME 90 9240
TEST_TIME 91 9289
TEST_TIME 92 9257
TEST_TIME 93 9299
TEST_TIME 94 9313
TEST_TIME 95 9312
TEST_TIME 96 9333
TEST_TIME 97 9341
TEST_TIME 98 9326
TEST_TIME 99 9408
TEST_TIME 100 9399
TEST_TIME 101 9428
TEST_TIME 102 9392
TEST_TIME 103 9453
TEST_TIME 104 9428
TEST_TIME 105 9468
TEST_TIME 106 9500
TEST_TIME 107 9482
TEST_TIME 108 9404
TEST_TIME 109 9370
TEST_TIME 110 9380
TEST_TIME 111 9330
TEST_TIME 112 9271
TEST_TIME 113 9296
TEST_TIME 114 9265
TEST_TIME 115 9259
TEST_TIME 116 9230
TEST_TIME 117 9208
TEST_TIME 118 9195
TEST_TIME 119 9156
TEST_TIME 120 9114

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-11-2021 19:39:39 to 18-11-2021 19:39:42.

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 1530293 1530293 0 0.00 0 32392 6 0 0
doc_list_biud 1530293 1530293 0 0.00 0 25177 21 0 0
sp_add_invoice_to_stock 5266 5118 148 2.81 18 210332 16978 0 148
sp_cancel_adding_invoice 5469 5469 0 0.00 5 59530 770 0 0
sp_cancel_client_order 1731 1731 0 0.00 4 16868 329 0 0
sp_cancel_customer_reserve 533747 533747 0 0.00 1 21900 47 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 2 7516 94 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 3861 64 0 0
sp_cancel_supplier_invoice 6058 6051 7 0.11 2 14470 685 0 7
sp_cancel_supplier_order 391 391 0 0.00 5 827131 81162 0 0
sp_cancel_write_off 8879 8879 0 0.00 1 23307 101 0 0
sp_client_order 20991 20991 0 0.00 3 20789 313 0 0
sp_customer_reserve 473641 473641 0 0.00 0 32372 169 0 0
sp_fill_shopping_cart 32106 32106 0 0.00 1 10766 35 0 0
sp_fill_shopping_cart_clo_res 473635 473635 0 0.00 0 11009 11 0 0
sp_get_clo_for_invoice 5111 5111 0 0.00 0 19758 264 0 0
sp_kill_cost_storno 15491 15491 0 0.00 0 7512 45 0 0
sp_kill_qstorno_ret_qs2qd 1082815 1082815 0 0.00 0 23253 26 0 0
sp_kill_qty_storno 571463 571463 0 0.00 0 25176 56 0 0
sp_lock_selected_doc 21045 21045 0 0.00 0 7084 7 0 0
sp_make_cost_storno 28568 28568 0 0.00 0 31404 62 0 0
sp_multiply_rows_for_pdistr 28568 28568 0 0.00 0 27344 29 0 0
sp_multiply_rows_for_qdistr 20991 20991 0 0.00 0 15264 137 0 0
sp_pay_from_customer 7290 7290 0 0.00 4 35645 141 0 0
sp_pay_to_supplier 6083 6083 0 0.00 4 29227 133 0 0
sp_payment_common 13373 13373 0 0.00 1 26885 46 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 16857 302 0 0
sp_qd_handle_on_invoice_upd_sts 10580 10580 0 0.00 0 25175 267 0 0
sp_qd_handle_on_reserve_upd_sts 10084 10084 0 0.00 0 8735 54 0 0
sp_reserve_write_off 10084 10084 0 0.00 6 32425 183 0 0
sp_supplier_invoice 5570 5570 0 0.00 5 124478 3205 0 0
sp_supplier_order 5564 5564 0 0.00 5 25684 1213 0 0
srv_aggregate_perf_data 105 105 0 0.00 2859 121270 16203 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 2448 233127 52427 0 0
srv_find_qd_qs_mism 1043631 1043631 0 0.00 0 23241 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 37 27330 968 0 0
srv_make_money_saldo 800 800 0 0.00 1 6266 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 87210 476960 297994 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5118 5118 0 0.00 0 12328 173 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 0 6766 94 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 6705 17 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 6927 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 3869 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 6809 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1537 14 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 24693 1417 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 8117 23 0 0
t$perf-norm:sp_client_order 20991 20991 0 0.00 0 6834 17 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 2268 6 0 0
t$perf-norm:sp_pay_from_customer 7290 7290 0 0.00 0 11493 14 0 0
t$perf-norm:sp_pay_to_supplier 6083 6083 0 0.00 0 6429 11 0 0
t$perf-norm:sp_reserve_write_off 10084 10084 0 0.00 0 15989 12 0 0
t$perf-norm:sp_supplier_invoice 5570 5570 0 0.00 0 10092 22 0 0
t$perf-norm:sp_supplier_order 5564 5564 0 0.00 0 10846 20 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 808 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 130 4 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 1950 16 0 0
t$perf-norm:srv_make_money_saldo 800 800 0 0.00 0 1019 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 44 13 0 0
v_add_invoice_to_stock 10514 10218 296 2.81 0 6721 17 0 296
v_all_customers 38652 38652 0 0.00 0 17 0 0 0
v_all_suppliers 31058 31058 0 0.00 0 15 1 0 0
v_all_wares 41975 41975 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 1669 9 0 0
v_cancel_client_order 3462 3462 0 0.00 0 1531 4 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 4264 14 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 1707 6 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 1190 5 0 0
v_cancel_supplier_invoice 862 848 14 1.62 0 2825 19 0 14
v_cancel_supplier_order 782 782 0 0.00 0 1436 5 0 0
v_cancel_write_off 2066 2066 0 0.00 0 1202 11 0 0
v_max_id_clo_ord 5560 5560 0 0.00 0 9407 3 0 0
v_max_id_clo_res 985 985 0 0.00 0 2205 14 0 0
v_max_id_ord_sup 5557 5557 0 0.00 0 5297 4 0 0
v_max_non_paid_invoice 1671 1671 0 0.00 0 4586 6 0 0
v_max_non_paid_realizn 3814 3814 0 0.00 0 1764 5 0 0
v_min_id_clo_ord 5560 5560 0 0.00 0 6675 10 0 0
v_min_id_clo_res 985 985 0 0.00 0 7676 10 0 0
v_min_id_ord_sup 5557 5557 0 0.00 0 4483 4 0 0
v_min_non_paid_invoice 6083 6083 0 0.00 0 20475 25 0 0
v_min_non_paid_realizn 7290 7290 0 0.00 0 26877 48 0 0
v_our_firm 10274 10274 0 0.00 0 13 1 0 0
v_reserve_write_off 20168 20168 0 0.00 0 3646 4 0 0
x_lock_depdocs_on_canc_invoice 11096 11096 0 0.00 0 23789 117 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 7031 266 0 0
x_make_qstorno_1000_1200 5564 5564 0 0.00 2 25658 1135 0 0
x_make_qstorno_1200_2000 5570 5570 0 0.00 3 124458 3090 0 0
x_make_qstorno_2100_3300 473640 473640 0 0.00 0 32163 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-11-2021 19:39:43 to 18-11-2021 19:39:47.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:57 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:58 35882 8192000000 9896146416 10208772096 976105344 10.646 100 89 178 1 1005354048 6139984 23108720
17:10:44 16804 8192000000 9945169536 10267541504 1028077856 11.150 100 92 184 0 1060258304 8686736 23493712
17:12:07 13491 8192000000 9978644720 10313015296 1059005984 11.447 100 85 170 1 1090478464 10428960 21043520
17:13:27 14825 8192000000 10032898592 10383089664 1124529648 12.070 100 73 146 0 1152349952 11155920 16664384
17:14:46 9604 8192000000 10087241888 10445410304 1165777232 12.457 100 83 166 1 1197860256 13321520 18761504
17:15:59 3092 8192000000 10123302048 10493771776 1215056080 12.916 100 84 168 0 1245555472 12082304 18417088
17:17:04 6791 8192000000 10157183008 10534125568 1245721520 13.199 100 68 136 1 1273116096 12725200 14669376
17:18:12 2270 8192000000 10194355392 10574270464 1275468672 13.472 100 91 182 1 1310970176 15175744 20325760
17:19:17 2196 8192000000 10217536928 10600431616 1297601456 13.673 100 88 176 0 1330554080 13504624 19448000
17:20:33 1619 8192000000 10220888416 10611363840 1311422384 13.799 100 87 174 0 1343919568 12101648 20395536
17:21:36 4747 8192000000 10203689600 10618368000 1317398512 13.853 100 23 45 0 1329803792 7165616 5239664
17:22:42 8407 8192000000 10203340560 10623946752 1325622960 13.928 100 20 41 1 1336463376 5953248 4887168
17:23:55 6578 8192000000 10229274960 10644221952 1337126784 14.031 100 40 79 4 1353203728 6187872 9889072
17:25:01 31286 8192000000 10237269056 10660392960 1338091888 14.040 100 30 61 2 1354889344 9531184 7266272
17:26:34 4262 8192000000 10239797248 10659328000 1343924448 14.093 100 27 54 0 1356135840 6110016 6101376
17:27:40 4014 8192000000 10225175856 10647855104 1336569072 14.026 100 29 58 2 1348206288 4696704 6940512
17:28:46 7946 8192000000 10181594592 10590183424 1250404224 13.242 96 12 24 2 1258803632 5454384 2945024
17:29:56 12123 8192000000 10187750208 10582962176 1242360112 13.168 96 64 126 2 1262412096 4960784 15091200
17:31:14 4342 8192000000 10166588288 10567602176 1261055408 13.340 99 37 74 1 1277068080 7557616 8455056
17:32:22 6577 8192000000 10146642560 10547658752 1237518624 13.123 98 32 64 2 1250969872 5766496 7684752
17:33:32 6926 8192000000 10162874128 10570031104 1272890656 13.448 99 18 36 1 1282021552 5041776 4089120
17:34:40 31652 8192000000 10183916448 10590945280 1276232240 13.479 99 33 65 1 1289864960 4897712 8735008
17:36:13 6577 8192000000 10202233920 10610393088 1299861392 13.694 100 31 60 0 1313149440 6468880 6819168
17:37:20 11112 8192000000 10211007664 10626224128 1318675840 13.865 100 28 56 0 1330424224 5065760 6682624
17:38:34 54756 8192000000 10257852640 10667466752 1341082528 14.067 100 48 93 2 1360851360 9327120 10441712
17:40:34 5606 8192000000 10274086496 10679476224 1351640320 14.162 100 75 147 2 1380977536 11753056 17584160
17:41:47 3021 8192000000 10285923856 10698526720 1365651936 14.288 100 69 133 2 1393918944 12640512 15626496
17:42:53 4714 8192000000 10306020848 10720096256 1378809568 14.406 100 61 124 1 1410123744 17910912 13403264
17:44:02 22780 8192000000 10312640336 10732957696 1392401888 14.527 100 52 104 1 1424323888 20296128 11625872
17:45:28 7845 8192000000 10315733024 10743619584 1399369120 14.589 100 38 74 2 1430027792 22019488 8639184
17:46:40 14459 8192000000 10327861376 10752770048 1401076816 14.605 100 44 88 1 1433430512 21213440 11140256
17:47:57 11410 8192000000 10343558304 10759315456 1403052496 14.622 100 70 138 1 1450416976 30176800 17187680
17:49:13 5670 8192000000 10343250896 10761584640 1402169536 14.614 100 70 140 0 1451277616 31909152 17198928
17:50:26 2448 8192000000 10331232432 10745769984 1391250992 14.517 100 62 124 0 1439771888 33319456 15201440
17:51:32 4714 8192000000 10324486400 10737745920 1372544544 14.350 99 64 127 3 1422568208 34683680 15339984
17:52:45 2704 8192000000 10325179760 10730373120 1378776208 14.406 100 71 140 2 1431011248 34968752 17266288
17:53:51 16133 8192000000 10316139488 10734010368 1363553440 14.269 99 51 99 2 1415809056 39105760 13149856
17:55:24 14148 8192000000 10305072080 10726047744 1353416000 14.178 99 51 102 1 1413244160 45745600 14082560
17:56:47 9800 8192000000 10313480992 10740424704 1377938176 14.398 100 35 68 1 1416775584 29468304 9369104
17:58:04 8597 8192000000 10280992368 10707230720 1338003536 14.039 99 33 66 1 1382055376 35337040 8714800
17:59:27 22989 8192000000 10291926016 10722394112 1357314960 14.213 100 29 57 1 1396492736 31254352 7923424
18:00:51 44933 8192000000 10263061824 10713636864 1343093888 14.085 100 16 32 0 1371846416 24134096 4618432
18:02:39 59170 8192000000 10258788064 10692153344 1320689872 13.883 97 24 47 1 1338777104 12304704 5782528
18:04:51 108150 8192000000 10273548896 10712072192 1330681504 13.973 98 19 37 2 1349980416 15046544 4252368
18:08:14 10860 8192000000 10291777088 10719629312 1371961200 14.345 100 47 94 2 1395272032 12894224 10416608
18:09:37 116838 8192000000 10260036752 10702487552 1296289664 13.661 94 8 16 0 1310895008 12475968 2129376
18:13:08 34798 8192000000 10274982336 10715099136 1363885760 14.272 100 23 45 1 1376000944 6664128 5451056
18:14:44 32974 8192000000 10294887088 10741440512 1382093264 14.435 100 28 56 0 1396451312 7954048 6404000
18:16:20 35814 8192000000 10287648368 10747637760 1388655152 14.494 100 11 22 1 1400654016 9045248 2953616
18:18:31 30803 8192000000 10295523536 10753724416 1398057856 14.578 100 11 22 2 1407672816 7571408 2043552
18:20:19 55287 8192000000 10318513328 10776838144 1410786256 14.691 100 17 34 2 1426855648 11818000 4251392
18:22:26 77617 8192000000 10307864320 10763706368 1377950480 14.398 98 21 41 0 1389296080 6456272 4889328
18:24:53 57486 8192000000 10297176096 10759159808 1388552640 14.493 99 18 36 0 1404638608 11900064 4185904
18:26:51 77644 8192000000 10295418096 10763087872 1393536704 14.537 100 8 16 0 1408497856 12791920 2169232
18:29:09 56860 8192000000 10301478304 10758086656 1359435680 14.232 97 35 70 2 1381476496 14243968 7796848
18:31:06 46405 8192000000 10287299856 10744279040 1354449872 14.187 97 13 26 0 1371385408 14107152 2828384
18:32:58 23209 8192000000 10311869168 10752634880 1361604832 14.252 97 45 90 1 1385478416 13670480 10203104
18:34:28 86054 8192000000 10286703968 10740826112 1327793344 13.947 96 18 36 1 1351857568 19698256 4365968
18:37:30 29657 8192000000 10273069872 10699563008 1325681856 13.928 97 40 79 1 1343282208 9113728 8486624
18:39:02 74391 8192000000 10268521568 10703536128 1323175648 13.905 97 19 38 1 1338881600 11391584 4314368
18:41:22 60615 8192000000 10255418592 10704523264 1340228320 14.059 99 29 58 2 1362505616 15503920 6773376
18:44:39 75475 8192000000 10250236016 10705719296 1299183824 13.688 96 10 20 0 1318599472 17636400 1779248
18:46:55 32961 8192000000 10270094800 10712748032 1344255952 14.096 99 19 38 2 1356159792 7638944 4264896
18:48:29 99148 8192000000 10257116192 10713640960 1293928016 13.640 94 21 42 0 1310172464 10705680 5538768
18:51:08 92470 8192000000 10258843696 10717728768 1317753232 13.856 96 11 22 0 1338118768 17895120 2470416
18:53:41 85461 8192000000 10294750928 10734563328 1359613888 14.234 99 16 31 1 1374961920 12166912 3181120
18:56:08 59011 8192000000 10279807920 10731450368 1339608896 14.054 98 31 60 2 1358670688 11861712 7200080
18:59:37 127121 8192000000 10281463440 10728767488 1355949920 14.201 99 19 38 0 1381917648 21785616 4182112
19:02:45 57544 8192000000 10276839408 10737037312 1376097104 14.382 100 6 12 0 1385268528 7853296 1318128
19:04:49 48081 8192000000 10299666416 10754154496 1398519872 14.582 100 21 42 4 1415363696 11654480 5189344
19:06:37 67544 8192000000 10296160352 10759036928 1391817056 14.522 100 16 30 1 1414119136 18553568 3748512
19:09:24 49944 8192000000 10301180736 10763972608 1393574704 14.538 100 14 28 1 1417726928 21161856 2990368
19:11:21 68708 8192000000 10301777408 10762448896 1386841072 14.478 99 10 20 0 1398517328 9682816 1993440
19:13:31 66462 8192000000 10293797264 10752385024 1373632176 14.360 98 6 12 0 1382408512 7725616 1050720
19:15:37 93640 8192000000 10291421664 10760728576 1320408960 13.880 94 20 40 0 1348418208 23611536 4397712
19:18:11 33398 8192000000 10312594896 10767609856 1392394448 14.527 100 29 58 2 1415620672 16366496 6859728
19:19:58 67542 8192000000 10304088480 10764804096 1383826096 14.451 99 23 46 0 1404483728 15500352 5157280
19:22:11 233127 8192000000 10279092112 10739228672 1285128784 13.560 92 16 32 0 1313829888 25076000 3625104
19:27:05 98729 8192000000 10277181840 10748006400 1346407168 14.115 97 4 8 0 1363059184 15716416 935600
19:29:43 93402 8192000000 10261577904 10727768064 1330205760 13.969 97 13 26 0 1354292032 20969936 3116336
19:32:30 81757 8192000000 10234206512 10692128768 1237075520 13.119 91 18 36 0 1251005280 9888080 4041680
19:34:52 76608 8192000000 10234917712 10692792320 1300935520 13.704 96 15 30 0 1311371216 7123744 3311952
19:37:09 53702 8192000000 9182109888 9444442112 345286256 4.044 26 1 2 0 346751920 1423312 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-11-2021 19:39:49 to 18-11-2021 19:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-11-2021 19:40:05 to 18-11-2021 19:40:07.

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          245051
MON$OLDEST_ACTIVE               245052
MON$OLDEST_SNAPSHOT             245052
MON$NEXT_TRANSACTION            245052
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                       2028000
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33535 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33535 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33535 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33535 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 ---
:55: 
:56: mscs-test-161.budzdorov.ru    Thu Nov 18 19:38:04 2021
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Thu Nov 18 19:38:04 2021
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Thu Nov 18 19:38:04 2021
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Thu Nov 18 19:38:06 2021
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:70: 
--- end of diff output ---

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

18.11.21 19:40:08. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-18 19:40:10 No severe PSQL-related problems occured
18.11.21 19:40:11. End of report.