Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.11.2021 16: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 16816708 kB
MemAvailable 31582956 kB
Buffers 0 kB
Cached 14891828 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-25 16:37:58
DTS_END                         2021-11-25 16:37:58.6080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33186
TRN_ID                          233302
ATT_ID                          615
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: 9143.69 24268 1097243
customer order: creation 166.93 372 20032
customer order: refuse 14.29 405 1715
order to supplier: creation 44.77 1277 5372
order to supplier: removal 3.70 105261 444
invoice (draft): creation 43.62 3708 5234
invoice (draft): removal 51.53 863 6183
invoice accept: apply 41.34 18431 4961
invoice accept: cancel 46.60 896 5592
customer reserve: creation 3856.56 179 462787
customer reserve: removal 4586.18 67 550342
realization accept: apply 81.30 180 9756
realization accept: cancel 81.64 113 9797
payment from customer: creation 56.59 118 6791
payment from customer: removal 5.33 74 640
payment to supplier: creation 48.42 127 5810
payment to supplier: removal 3.13 66 376
service: total inventory turnovers 4.86 1285 583
service: total monetary turnovers 6.11 64 733
service: aggregate perf. data 0.76 27800 91
service: refresh index statistics 0.03 324068 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3250
TEST_TIME 2 2454
TEST_TIME 3 3174
TEST_TIME 4 4514
TEST_TIME 5 5075
TEST_TIME 6 5029
TEST_TIME 7 5032
TEST_TIME 8 5223
TEST_TIME 9 5640
TEST_TIME 10 5819
TEST_TIME 11 5775
TEST_TIME 12 5784
TEST_TIME 13 6659
TEST_TIME 14 7169
TEST_TIME 15 7091
TEST_TIME 16 7047
TEST_TIME 17 7065
TEST_TIME 18 7119
TEST_TIME 19 7323
TEST_TIME 20 7356
TEST_TIME 21 7789
TEST_TIME 22 8021
TEST_TIME 23 8506
TEST_TIME 24 8868
TEST_TIME 25 9253
TEST_TIME 26 9428
TEST_TIME 27 9288
TEST_TIME 28 10187
TEST_TIME 29 10947
TEST_TIME 30 11116
TEST_TIME 31 10990
TEST_TIME 32 10993
TEST_TIME 33 10753
TEST_TIME 34 10551
TEST_TIME 35 10544
TEST_TIME 36 10361
TEST_TIME 37 10298
TEST_TIME 38 10302
TEST_TIME 39 10256
TEST_TIME 40 10100
TEST_TIME 41 9953
TEST_TIME 42 9858
TEST_TIME 43 9810
TEST_TIME 44 9669
TEST_TIME 45 9575
TEST_TIME 46 9757
TEST_TIME 47 9677
TEST_TIME 48 9579
TEST_TIME 49 9496
TEST_TIME 50 9664
TEST_TIME 51 9573
TEST_TIME 52 9567
TEST_TIME 53 9491
TEST_TIME 54 9460
TEST_TIME 55 9375
TEST_TIME 56 9383
TEST_TIME 57 9361
TEST_TIME 58 9476
TEST_TIME 59 9438
TEST_TIME 60 9436
TEST_TIME 61 9433
TEST_TIME 62 9459
TEST_TIME 63 9539
TEST_TIME 64 9530
TEST_TIME 65 9538
TEST_TIME 66 9555
TEST_TIME 67 9475
TEST_TIME 68 9417
TEST_TIME 69 9380
TEST_TIME 70 9333
TEST_TIME 71 9305
TEST_TIME 72 9295
TEST_TIME 73 9254
TEST_TIME 74 9212
TEST_TIME 75 9173
TEST_TIME 76 9098
TEST_TIME 77 9183
TEST_TIME 78 9142
TEST_TIME 79 9160
TEST_TIME 80 9136
TEST_TIME 81 9138
TEST_TIME 82 9194
TEST_TIME 83 9184
TEST_TIME 84 9200
TEST_TIME 85 9194
TEST_TIME 86 9187
TEST_TIME 87 9221
TEST_TIME 88 9291
TEST_TIME 89 9299
TEST_TIME 90 9327
TEST_TIME 91 9391
TEST_TIME 92 9356
TEST_TIME 93 9270
TEST_TIME 94 9218
TEST_TIME 95 9191
TEST_TIME 96 9158
TEST_TIME 97 9131
TEST_TIME 98 9139
TEST_TIME 99 9188
TEST_TIME 100 9160
TEST_TIME 101 9150
TEST_TIME 102 9150
TEST_TIME 103 9203
TEST_TIME 104 9220
TEST_TIME 105 9191
TEST_TIME 106 9230
TEST_TIME 107 9265
TEST_TIME 108 9316
TEST_TIME 109 9268
TEST_TIME 110 9318
TEST_TIME 111 9357
TEST_TIME 112 9330
TEST_TIME 113 9275
TEST_TIME 114 9259
TEST_TIME 115 9234
TEST_TIME 116 9219
TEST_TIME 117 9207
TEST_TIME 118 9179
TEST_TIME 119 9158
TEST_TIME 120 9150

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-11-2021 16:40:23 to 25-11-2021 16:40:26.

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 1523589 1523589 0 0.00 0 14309 6 0 0
doc_list_biud 1523589 1523589 0 0.00 0 28682 30 0 0
sp_add_invoice_to_stock 5055 4961 94 1.85 21 156860 18431 0 94
sp_cancel_adding_invoice 5592 5592 0 0.00 5 70879 897 0 0
sp_cancel_client_order 1715 1715 0 0.00 3 15220 405 0 0
sp_cancel_customer_reserve 550342 550342 0 0.00 1 28696 67 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 2 12106 74 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 3587 66 0 0
sp_cancel_supplier_invoice 6189 6183 6 0.09 1 13807 863 0 6
sp_cancel_supplier_order 444 444 0 0.00 14 1109967 105261 0 0
sp_cancel_write_off 9797 9797 0 0.00 1 11353 114 0 0
sp_client_order 20032 20032 0 0.00 3 49985 373 0 0
sp_customer_reserve 462787 462787 0 0.00 0 34300 179 0 0
sp_fill_shopping_cart 30629 30629 0 0.00 1 24075 48 0 0
sp_fill_shopping_cart_clo_res 462767 462767 0 0.00 0 23116 14 0 0
sp_get_clo_for_invoice 4941 4941 0 0.00 0 31144 392 0 0
sp_kill_cost_storno 16405 16405 0 0.00 0 13112 62 0 0
sp_kill_qstorno_ret_qs2qd 1117108 1117108 0 0.00 0 27656 36 0 0
sp_kill_qty_storno 588770 588770 0 0.00 0 28682 78 0 0
sp_lock_selected_doc 20301 20301 0 0.00 0 7968 5 0 0
sp_make_cost_storno 27298 27298 0 0.00 0 14302 58 0 0
sp_multiply_rows_for_pdistr 27298 27298 0 0.00 0 7375 31 0 0
sp_multiply_rows_for_qdistr 20032 20032 0 0.00 0 49979 176 0 0
sp_pay_from_customer 6791 6791 0 0.00 4 18307 118 0 0
sp_pay_to_supplier 5810 5810 0 0.00 4 15480 127 0 0
sp_payment_common 12601 12601 0 0.00 1 18296 28 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 15215 382 0 0
sp_qd_handle_on_invoice_upd_sts 10533 10533 0 0.00 0 26636 361 0 0
sp_qd_handle_on_reserve_upd_sts 9756 9756 0 0.00 0 14247 56 0 0
sp_reserve_write_off 9756 9756 0 0.00 6 14258 180 0 0
sp_supplier_invoice 5234 5234 0 0.00 6 82701 3708 0 0
sp_supplier_order 5372 5372 0 0.00 5 33997 1278 0 0
srv_aggregate_perf_data 91 91 0 0.00 3431 831651 27801 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2220 138521 47094 0 0
srv_find_qd_qs_mism 1049376 1049376 0 0.00 0 14158 5 0 0
srv_make_invnt_saldo 583 583 0 0.00 32 149819 1286 0 0
srv_make_money_saldo 733 733 0 0.00 1 17022 65 0 0
srv_recalc_idx_stat 4 4 0 0.00 179972 484082 324068 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4961 4961 0 0.00 0 12500 189 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 4240 107 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 9847 20 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 8587 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 2488 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 541 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 2561 21 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 22148 1579 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 5929 27 0 0
t$perf-norm:sp_client_order 20032 20032 0 0.00 0 12719 23 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 985 5 0 0
t$perf-norm:sp_pay_from_customer 6791 6791 0 0.00 0 8639 13 0 0
t$perf-norm:sp_pay_to_supplier 5810 5810 0 0.00 0 6194 11 0 0
t$perf-norm:sp_reserve_write_off 9756 9756 0 0.00 0 9380 14 0 0
t$perf-norm:sp_supplier_invoice 5234 5234 0 0.00 0 5245 17 0 0
t$perf-norm:sp_supplier_order 5372 5372 0 0.00 0 6576 18 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 188 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 103 2 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 1449 28 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 2789 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 5 0 0
v_add_invoice_to_stock 10066 9878 188 1.86 0 9008 18 0 188
v_all_customers 36238 36238 0 0.00 0 179 1 0 0
v_all_suppliers 29602 29602 0 0.00 0 13 1 0 0
v_all_wares 40064 40064 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1979 9 0 0
v_cancel_client_order 3430 3430 0 0.00 0 2374 3 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 12079 12 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 1391 6 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 749 6 0 0
v_cancel_supplier_invoice 806 794 12 1.48 0 6548 22 0 12
v_cancel_supplier_order 888 888 0 0.00 0 1411 6 0 0
v_cancel_write_off 1946 1946 0 0.00 0 4733 16 0 0
v_max_id_clo_ord 5370 5370 0 0.00 0 896 1 0 0
v_max_id_clo_res 950 950 0 0.00 0 1539 6 0 0
v_max_id_ord_sup 5226 5226 0 0.00 0 5639 6 0 0
v_max_non_paid_invoice 1598 1598 0 0.00 0 5248 7 0 0
v_max_non_paid_realizn 3666 3666 0 0.00 0 1963 4 0 0
v_min_id_clo_ord 5370 5370 0 0.00 0 2271 6 0 0
v_min_id_clo_res 950 950 0 0.00 0 4737 15 0 0
v_min_id_ord_sup 5226 5226 0 0.00 0 6462 15 0 0
v_min_non_paid_invoice 5810 5810 0 0.00 0 15369 21 0 0
v_min_non_paid_realizn 6791 6791 0 0.00 0 18289 19 0 0
v_our_firm 10076 10076 0 0.00 0 21 1 0 0
v_reserve_write_off 19512 19512 0 0.00 0 10074 6 0 0
x_lock_depdocs_on_canc_invoice 11378 11378 0 0.00 0 22317 124 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 3674 282 0 0
x_make_qstorno_1000_1200 5372 5372 0 0.00 2 33977 1194 0 0
x_make_qstorno_1200_2000 5234 5234 0 0.00 2 82584 3516 0 0
x_make_qstorno_2100_3300 462787 462787 0 0.00 0 34299 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-11-2021 16:40:27 to 25-11-2021 16:40:31.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:02 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:03 10798 8192000000 9897020096 10209841152 984134064 10.724 100 85 168 1 1011521552 5860448 21527040
14:10:19 24336 8192000000 9949280848 10273546240 1026266096 11.132 100 84 168 0 1056065920 8132128 21667696
14:11:50 17662 8192000000 9980513472 10311352320 1059573168 11.452 100 90 180 1 1092632016 9882080 23176768
14:13:15 9622 8192000000 10011554528 10358423552 1103820784 11.874 100 79 158 0 1133235504 10228384 19186336
14:14:27 10760 8192000000 10063054096 10417590272 1148866256 12.299 100 83 166 2 1180914464 12988288 19059920
14:15:42 7701 8192000000 10107810944 10477096960 1199797648 12.774 100 68 137 0 1227256848 11435824 16023376
14:16:52 3033 8192000000 10155648560 10529996800 1238420800 13.132 100 82 164 2 1274021856 17383520 18217536
14:17:56 8693 8192000000 10167140144 10545397760 1254573616 13.280 100 84 169 1 1286801680 14178208 18049856
14:19:05 1497 8192000000 10207149744 10588733440 1287541376 13.582 100 95 190 0 1321478240 13459056 20477808
14:20:08 51351 8192000000 10181657696 10593718272 1297818128 13.675 100 47 94 0 1318540752 9531856 11190768
14:22:03 9061 8192000000 10205052912 10610384896 1307496368 13.763 100 22 44 1 1318835888 6018640 5320880
14:23:12 5430 8192000000 10199643264 10620325888 1318542976 13.864 100 22 44 0 1331071376 7287504 5240896
14:24:19 8803 8192000000 10210906912 10630230016 1325154512 13.923 100 21 41 0 1336045152 6343520 4547120
14:25:28 24599 8192000000 10253603856 10652483584 1332501312 13.990 100 78 155 2 1357846368 5484064 19860992
14:26:53 5286 8192000000 10234080480 10655154176 1338969344 14.048 100 39 79 1 1355214144 6166816 10077984
14:28:00 6970 8192000000 10207566320 10624057344 1305929712 13.749 99 20 41 1 1316662704 5582752 5150240
14:29:08 38278 8192000000 10182639232 10598240256 1233497504 13.086 94 23 46 0 1244131120 5181040 5452576
14:30:50 7667 8192000000 10175789152 10580963328 1247856304 13.219 97 26 52 0 1263334960 9127648 6351008
14:31:58 6854 8192000000 10142774000 10543976448 1239512304 13.142 99 24 48 0 1251263200 5941376 5809520
14:33:10 58552 8192000000 10134367312 10541785088 1217863184 12.942 96 21 42 0 1227602256 4655584 5083488
14:35:09 6740 8192000000 10171281056 10570973184 1273890368 13.457 100 40 80 3 1288552448 5175424 9486656
14:36:17 7081 8192000000 10186422224 10597490688 1292923808 13.631 100 16 32 0 1301759440 5051472 3784160
14:37:24 12026 8192000000 10190564448 10607026176 1302054448 13.714 100 10 20 0 1309740576 5349904 2336224
14:38:38 74580 8192000000 10242922896 10651906048 1322558784 13.900 100 54 107 0 1339860368 4974864 12326720
14:40:59 6055 8192000000 10271805152 10678480896 1351936896 14.165 100 75 150 3 1383985232 15541088 16507248
14:42:11 9219 8192000000 10287617456 10697105408 1365463520 14.286 100 59 118 1 1393373648 14395776 13514352
14:43:25 28710 8192000000 10300949376 10720821248 1380752816 14.423 100 44 88 1 1406841456 16017248 10071392
14:45:03 10897 8192000000 10297813520 10720280576 1382957008 14.443 100 38 76 1 1412564000 19712768 9894224
14:46:19 6058 8192000000 10314350592 10735964160 1390356448 14.509 100 62 123 1 1427854752 23645072 13853232
14:47:30 6455 8192000000 10318540064 10739716096 1394925472 14.550 100 48 96 0 1430572272 23981392 11665408
14:48:44 9076 8192000000 10329017120 10751168512 1393427616 14.536 100 50 100 1 1435693744 29728512 12537616
14:49:59 4468 8192000000 10351831872 10772295680 1395004480 14.550 99 65 130 1 1454915456 43517440 16393536
14:51:13 5026 8192000000 10361252448 10787303424 1413795968 14.718 100 47 94 1 1469179024 43355232 12027824
14:52:22 2386 8192000000 10369853728 10786697216 1417341312 14.749 100 80 159 1 1473053712 36834272 18878128
14:53:28 3546 8192000000 10380945760 10795745280 1416653648 14.743 100 77 155 0 1478146128 42317872 19174608
14:54:39 3543 8192000000 10393916464 10799767552 1413804416 14.718 99 95 189 2 1486202384 49166784 23231184
14:55:49 5931 8192000000 10385001184 10796052480 1414677360 14.725 100 76 151 2 1487837696 53927568 19232768
14:56:58 3139 8192000000 10408213088 10817990656 1426110336 14.827 100 89 178 3 1511603424 62248720 23244368
14:58:08 2219 8192000000 10399894272 10807402496 1409652400 14.681 100 86 172 2 1503833744 71293632 22887712
14:59:16 8296 8192000000 10367474528 10784903168 1389278832 14.499 100 60 120 2 1473762320 68583824 15899664
15:00:45 4274 8192000000 10369255616 10789318656 1389254688 14.499 100 60 120 2 1471861024 66738224 15868112
15:01:53 15573 8192000000 10357898816 10792980480 1390343392 14.509 100 30 60 0 1467915216 69126912 8444912
15:03:10 27238 8192000000 10346460992 10782158848 1375577504 14.377 100 25 50 5 1441350832 58818800 6954528
15:05:17 23896 8192000000 10302521568 10751627264 1348041056 14.130 100 23 46 0 1429464048 74363216 7059776
15:06:43 32514 8192000000 10277654368 10703745024 1335031888 14.013 99 26 51 1 1370850432 28818256 7000288
15:08:17 31192 8192000000 10262270016 10704007168 1346535632 14.116 99 25 48 2 1365830272 13860896 5433744
15:09:59 50932 8192000000 10262149936 10707156992 1355109568 14.193 100 7 14 0 1367702320 11091952 1500800
15:11:54 91398 8192000000 10251307888 10691592192 1322717712 13.901 97 20 40 2 1335770720 9191584 3861424
15:14:26 41336 8192000000 10276969152 10725224448 1369949824 14.327 100 17 34 0 1380749568 7392112 3407632
15:16:08 85863 8192000000 10292726096 10748579840 1387709328 14.485 100 10 20 1 1401819024 12263808 1845888
15:18:40 45269 8192000000 10290313008 10735661056 1371460256 14.340 99 20 40 2 1390533712 14279968 4793488
15:20:40 16854 8192000000 10316182320 10767065088 1403051152 14.622 100 25 50 1 1428991472 20007440 5932880
15:22:15 45185 8192000000 10324298960 10775191552 1398637680 14.583 100 21 42 1 1429306032 25534048 5134304
15:24:01 73231 8192000000 10308803520 10762776576 1382816240 14.442 99 15 30 1 1414219584 27559360 3843984
15:26:55 23737 8192000000 10302682960 10754244608 1390153504 14.507 100 23 46 2 1415868800 20554192 5161104
15:28:23 88845 8192000000 10276167968 10740105216 1350947936 14.156 97 14 28 1 1361106144 7236608 2921600
15:30:56 37315 8192000000 10289283456 10742255616 1378945152 14.407 99 25 50 0 1393150848 8309216 5896480
15:32:39 25975 8192000000 10304739552 10752274432 1395388352 14.554 100 39 78 0 1412390480 8229008 8773120
15:34:06 50002 8192000000 10309876816 10766680064 1388319280 14.491 99 19 38 0 1406172320 13498416 4354624
15:36:27 111545 8192000000 10295723360 10753093632 1368782288 14.316 98 13 26 1 1386545472 14782336 2980848
15:39:39 88981 8192000000 10296930976 10753462272 1360698096 14.244 98 11 22 0 1375520896 12510976 2311824
15:42:09 107494 8192000000 10297302192 10743230464 1350077712 14.148 97 14 28 0 1370412816 17003424 3331680
15:45:04 113615 8192000000 10264615616 10719940608 1297656112 13.674 94 25 50 0 1312378864 9292816 5429936
15:48:22 63192 8192000000 10243894544 10699485184 1310923168 13.794 96 16 32 0 1324571552 9047424 4600960
15:50:26 69001 8192000000 10264136528 10711396352 1334678320 14.009 98 22 44 1 1348382464 8192176 5511968
15:52:39 76996 8192000000 10272178976 10729848832 1375441520 14.376 100 10 21 0 1389138000 11169296 2527184
15:55:31 30559 8192000000 10248125136 10695786496 1317602912 13.855 96 20 40 0 1328960176 6992784 4364480
15:57:12 72256 8192000000 10265541392 10710761472 1320825424 13.884 97 17 34 0 1335479232 10530496 4123312
15:59:25 72391 8192000000 10268852160 10710253568 1338867040 14.047 98 28 57 1 1354513184 9585712 6060432
16:01:38 31965 8192000000 10283029328 10713903104 1346313536 14.114 99 27 55 0 1360753008 8457056 5982416
16:03:13 54868 8192000000 10284122928 10727612416 1344997984 14.102 98 24 48 2 1368517376 18048368 5471024
16:05:12 60927 8192000000 10291543504 10730369024 1338169392 14.041 97 16 32 1 1357937504 15840544 3927568
16:07:22 47091 8192000000 10285713632 10735951872 1369948336 14.327 100 30 61 0 1389789584 13346272 6494976
16:09:11 138520 8192000000 10291949920 10739761152 1383222064 14.445 100 27 54 0 1398388208 9070320 6095824
16:13:29 89509 8192000000 10323448224 10781376512 1391840272 14.522 99 8 16 1 1410345504 17100128 1405104
16:15:59 74672 8192000000 10296486000 10767167488 1374119840 14.364 98 19 38 1 1395008576 16304832 4583904
16:18:39 49681 8192000000 10301036720 10755981312 1379618640 14.413 99 33 67 0 1399746352 12294672 7833040
16:20:37 113836 8192000000 10321098240 10774614016 1399114336 14.587 100 17 35 0 1417540608 14920800 3505472
16:23:32 89733 8192000000 10296188864 10742087680 1321446928 13.890 94 11 22 0 1337073008 13418176 2207904
16:26:05 75429 8192000000 10304204528 10761048064 1382129584 14.436 99 11 22 0 1398363056 14099312 2134160
16:28:23 88099 8192000000 10299361264 10775588864 1367917312 14.308 98 4 8 1 1390351328 21593440 840576
16:31:27 25616 8192000000 10286538688 10730172416 1342998880 14.084 97 42 83 0 1360192656 7905680 9288096
16:33:13 58725 8192000000 10274650496 10729857024 1348252512 14.132 97 25 50 0 1362255664 8457200 5545952
16:35:13 53613 8192000000 10286030352 10743300096 1374414768 14.367 99 17 34 0 1394548768 16195024 3938976
16:37:07 61141 8192000000 9687035200 10051567616 815540336 9.053 59 10 20 0 831214016 13386288 2287392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-11-2021 16:40:33 to 25-11-2021 16:40:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-11-2021 16:40:49 to 25-11-2021 16:40:51.

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          230949
MON$OLDEST_ACTIVE               234317
MON$OLDEST_SNAPSHOT             234317
MON$NEXT_TRANSACTION            234317
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                       2028384
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:334: 
:335: mscs-test-161.budzdorov.ru    Thu Nov 25 16:38:17 2021
:336:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:337: 
:338: 
:339: mscs-test-161.budzdorov.ru    Thu Nov 25 16:38:18 2021
:340:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:341: 
:342: 
:343: mscs-test-161.budzdorov.ru    Thu Nov 25 16:38:26 2021
:344:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:345: 
--- end of diff output ---

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

25.11.21 16:40:52. 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-25 16:40:54 No severe PSQL-related problems occured
25.11.21 16:40:55. End of report.