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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16776824 kB
MemAvailable 31415968 kB
Buffers 0 kB
Cached 14867444 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.9
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-13 01:38:01
DTS_END                         2021-12-13 01:38:01.6280
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/51148
TRN_ID                          235578
ATT_ID                          614
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: 9192.12 22360 1103054
customer order: creation 170.18 355 20422
customer order: refuse 14.00 371 1680
order to supplier: creation 44.62 1330 5354
order to supplier: removal 3.42 88023 410
invoice (draft): creation 45.59 3494 5471
invoice (draft): removal 52.03 683 6243
invoice accept: apply 41.56 16918 4987
invoice accept: cancel 46.78 747 5614
customer reserve: creation 3877.10 166 465252
customer reserve: removal 4602.11 50 552253
realization accept: apply 82.09 171 9851
realization accept: cancel 85.67 103 10280
payment from customer: creation 58.21 102 6985
payment from customer: removal 5.22 67 626
payment to supplier: creation 47.95 116 5754
payment to supplier: removal 3.26 65 391
service: total inventory turnovers 4.99 1145 599
service: total monetary turnovers 6.48 37 777
service: aggregate perf. data 0.84 17053 101
service: refresh index statistics 0.03 316196 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-12-2021 01:39:18 to 13-12-2021 01:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7908
TEST_TIME 2 6612
TEST_TIME 3 5314
TEST_TIME 4 4656
TEST_TIME 5 5108
TEST_TIME 6 5473
TEST_TIME 7 6146
TEST_TIME 8 5991
TEST_TIME 9 6247
TEST_TIME 10 6552
TEST_TIME 11 6637
TEST_TIME 12 6752
TEST_TIME 13 7279
TEST_TIME 14 7353
TEST_TIME 15 7493
TEST_TIME 16 7603
TEST_TIME 17 7577
TEST_TIME 18 8021
TEST_TIME 19 8529
TEST_TIME 20 8859
TEST_TIME 21 9094
TEST_TIME 22 9191
TEST_TIME 23 9885
TEST_TIME 24 9846
TEST_TIME 25 10341
TEST_TIME 26 10909
TEST_TIME 27 10744
TEST_TIME 28 10586
TEST_TIME 29 10489
TEST_TIME 30 10390
TEST_TIME 31 10224
TEST_TIME 32 10374
TEST_TIME 33 10218
TEST_TIME 34 10083
TEST_TIME 35 9911
TEST_TIME 36 9873
TEST_TIME 37 9750
TEST_TIME 38 9782
TEST_TIME 39 9693
TEST_TIME 40 9836
TEST_TIME 41 9799
TEST_TIME 42 9732
TEST_TIME 43 9630
TEST_TIME 44 9639
TEST_TIME 45 9697
TEST_TIME 46 9706
TEST_TIME 47 9800
TEST_TIME 48 9715
TEST_TIME 49 9701
TEST_TIME 50 9674
TEST_TIME 51 9646
TEST_TIME 52 9584
TEST_TIME 53 9606
TEST_TIME 54 9719
TEST_TIME 55 9662
TEST_TIME 56 9666
TEST_TIME 57 9706
TEST_TIME 58 9848
TEST_TIME 59 9921
TEST_TIME 60 9790
TEST_TIME 61 9698
TEST_TIME 62 9656
TEST_TIME 63 9562
TEST_TIME 64 9560
TEST_TIME 65 9477
TEST_TIME 66 9462
TEST_TIME 67 9428
TEST_TIME 68 9412
TEST_TIME 69 9397
TEST_TIME 70 9468
TEST_TIME 71 9416
TEST_TIME 72 9421
TEST_TIME 73 9420
TEST_TIME 74 9472
TEST_TIME 75 9544
TEST_TIME 76 9526
TEST_TIME 77 9510
TEST_TIME 78 9541
TEST_TIME 79 9552
TEST_TIME 80 9599
TEST_TIME 81 9548
TEST_TIME 82 9442
TEST_TIME 83 9416
TEST_TIME 84 9387
TEST_TIME 85 9351
TEST_TIME 86 9361
TEST_TIME 87 9313
TEST_TIME 88 9261
TEST_TIME 89 9198
TEST_TIME 90 9243
TEST_TIME 91 9218
TEST_TIME 92 9230
TEST_TIME 93 9229
TEST_TIME 94 9232
TEST_TIME 95 9216
TEST_TIME 96 9245
TEST_TIME 97 9271
TEST_TIME 98 9301
TEST_TIME 99 9363
TEST_TIME 100 9418
TEST_TIME 101 9413
TEST_TIME 102 9409
TEST_TIME 103 9342
TEST_TIME 104 9438
TEST_TIME 105 9367
TEST_TIME 106 9312
TEST_TIME 107 9329
TEST_TIME 108 9293
TEST_TIME 109 9263
TEST_TIME 110 9207
TEST_TIME 111 9276
TEST_TIME 112 9234
TEST_TIME 113 9200
TEST_TIME 114 9229
TEST_TIME 115 9221
TEST_TIME 116 9185
TEST_TIME 117 9274
TEST_TIME 118 9282
TEST_TIME 119 9274
TEST_TIME 120 9197

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-12-2021 01:39:21 to 13-12-2021 01:39:25.

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 1531957 1531957 0 0.00 0 13662 6 0 0
doc_list_biud 1531957 1531957 0 0.00 0 36852 24 0 0
sp_add_invoice_to_stock 5152 4987 165 3.20 22 162839 16919 0 165
sp_cancel_adding_invoice 5614 5614 0 0.00 5 52272 747 0 0
sp_cancel_client_order 1680 1680 0 0.00 4 20628 371 0 0
sp_cancel_customer_reserve 552253 552253 0 0.00 1 20446 51 0 0
sp_cancel_pay_from_customer 626 626 0 0.00 2 2908 67 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 2771 65 0 0
sp_cancel_supplier_invoice 6256 6243 13 0.20 1 25846 683 0 13
sp_cancel_supplier_order 410 410 0 0.00 6 1058737 88023 0 0
sp_cancel_write_off 10280 10280 0 0.00 1 9360 103 0 0
sp_client_order 20422 20422 0 0.00 3 17521 355 0 0
sp_customer_reserve 465252 465252 0 0.00 0 127828 166 0 0
sp_fill_shopping_cart 31238 31238 0 0.00 1 16104 42 0 0
sp_fill_shopping_cart_clo_res 465247 465247 0 0.00 0 14019 13 0 0
sp_get_clo_for_invoice 4981 4981 0 0.00 0 25876 290 0 0
sp_kill_cost_storno 16911 16911 0 0.00 0 6137 51 0 0
sp_kill_qstorno_ret_qs2qd 1121439 1121439 0 0.00 0 25836 27 0 0
sp_kill_qty_storno 591312 591312 0 0.00 0 36852 61 0 0
sp_lock_selected_doc 20329 20329 0 0.00 0 6301 3 0 0
sp_make_cost_storno 27571 27571 0 0.00 0 12117 48 0 0
sp_multiply_rows_for_pdistr 27571 27571 0 0.00 0 7880 27 0 0
sp_multiply_rows_for_qdistr 20422 20422 0 0.00 0 16566 162 0 0
sp_pay_from_customer 6985 6985 0 0.00 4 11697 103 0 0
sp_pay_to_supplier 5754 5754 0 0.00 4 16722 116 0 0
sp_payment_common 12739 12739 0 0.00 1 14124 30 0 0
sp_qd_handle_on_cancel_clo 1680 1680 0 0.00 0 20622 340 0 0
sp_qd_handle_on_invoice_upd_sts 10595 10595 0 0.00 0 36851 332 0 0
sp_qd_handle_on_reserve_upd_sts 9851 9851 0 0.00 0 15349 62 0 0
sp_reserve_write_off 9851 9851 0 0.00 6 15416 172 0 0
sp_supplier_invoice 5471 5471 0 0.00 5 94935 3494 0 0
sp_supplier_order 5354 5354 0 0.00 5 43255 1331 0 0
srv_aggregate_perf_data 101 101 0 0.00 2951 179060 17053 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1850 180540 54390 0 0
srv_find_qd_qs_mism 1054762 1054762 0 0.00 0 13662 5 0 0
srv_make_invnt_saldo 599 599 0 0.00 28 79791 1145 0 0
srv_make_money_saldo 777 777 0 0.00 1 4748 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 175125 521908 316197 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4990 4990 0 0.00 0 11898 188 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 3902 85 0 0
t$perf-norm:sp_cancel_client_order 1680 1680 0 0.00 0 4154 17 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 1312 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 626 626 0 0.00 0 2477 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 1344 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 2838 22 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 45268 1622 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 1212 7 0 0
t$perf-norm:sp_client_order 20422 20422 0 0.00 0 14786 20 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 1756 6 0 0
t$perf-norm:sp_pay_from_customer 6985 6985 0 0.00 0 18451 17 0 0
t$perf-norm:sp_pay_to_supplier 5754 5754 0 0.00 0 6898 13 0 0
t$perf-norm:sp_reserve_write_off 9851 9851 0 0.00 0 10965 15 0 0
t$perf-norm:sp_supplier_invoice 5471 5471 0 0.00 0 15917 19 0 0
t$perf-norm:sp_supplier_order 5354 5354 0 0.00 0 6872 20 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 844 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 36 2 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 3696 23 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 3755 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10292 9962 330 3.20 0 3240 16 0 330
v_all_customers 37424 37424 0 0.00 0 81 1 0 0
v_all_suppliers 29886 29886 0 0.00 0 31 1 0 0
v_all_wares 40844 40844 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 2711 9 0 0
v_cancel_client_order 3360 3360 0 0.00 0 1631 5 0 0
v_cancel_customer_prepayment 1252 1252 0 0.00 0 2701 4 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 3397 7 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 2762 6 0 0
v_cancel_supplier_invoice 806 780 26 3.22 0 2633 19 0 26
v_cancel_supplier_order 820 820 0 0.00 0 585 5 0 0
v_cancel_write_off 1950 1950 0 0.00 0 5394 14 0 0
v_max_id_clo_ord 5350 5350 0 0.00 0 1402 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 3375 16 0 0
v_max_id_ord_sup 5466 5466 0 0.00 0 5311 5 0 0
v_max_non_paid_invoice 1627 1627 0 0.00 0 142 2 0 0
v_max_non_paid_realizn 3661 3661 0 0.00 0 1918 4 0 0
v_min_id_clo_ord 5350 5350 0 0.00 0 2072 5 0 0
v_min_id_clo_res 989 989 0 0.00 0 2021 4 0 0
v_min_id_ord_sup 5466 5466 0 0.00 0 5618 10 0 0
v_min_non_paid_invoice 5754 5754 0 0.00 0 14119 23 0 0
v_min_non_paid_realizn 6985 6985 0 0.00 0 11442 25 0 0
v_our_firm 10068 10068 0 0.00 0 15 1 0 0
v_reserve_write_off 19702 19702 0 0.00 0 2995 5 0 0
x_lock_depdocs_on_canc_invoice 11467 11467 0 0.00 0 11003 118 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 16043 377 0 0
x_make_qstorno_1000_1200 5354 5354 0 0.00 1 43248 1251 0 0
x_make_qstorno_1200_2000 5471 5471 0 0.00 2 94881 3338 0 0
x_make_qstorno_2100_3300 465252 465252 0 0.00 0 127827 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-12-2021 01:39:26 to 13-12-2021 01:39:30.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:05 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:06 38602 8192000000 9903985472 10216574976 980503104 10.689 100 90 179 0 1010161824 6099968 23558752
23:10:51 10731 8192000000 9939044128 10260930560 1021963296 11.091 100 90 180 0 1053929456 8715472 23250688
23:12:12 11193 8192000000 9978190032 10312089600 1063576416 11.491 100 85 169 1 1094534624 9619008 21339200
23:13:30 13512 8192000000 10042446640 10394701824 1129232320 12.114 100 75 151 0 1157250288 10572848 17445120
23:14:47 10643 8192000000 10086743344 10450173952 1173090112 12.526 100 75 150 0 1201318304 11116608 17111584
23:16:01 5761 8192000000 10131330784 10500648960 1223720960 12.996 100 87 173 1 1256299584 13058272 19520352
23:17:10 3261 8192000000 10165444160 10543874048 1255365792 13.287 100 89 180 3 1287682976 13098112 19219072
23:18:15 2726 8192000000 10201363136 10582745088 1283420560 13.544 100 92 182 1 1316899824 12842176 20637088
23:19:20 14670 8192000000 10200572160 10592231424 1296516176 13.664 100 73 145 0 1326173024 12857968 16798880
23:20:44 3948 8192000000 10215174720 10611351552 1305684640 13.747 100 48 96 0 1324659136 8310528 10663968
23:21:49 11092 8192000000 10218138144 10620362752 1313790768 13.820 100 69 138 0 1337105184 6366736 16947680
23:23:01 4491 8192000000 10212415952 10628112384 1323546480 13.909 100 36 72 0 1338547216 6520304 8480432
23:24:06 6582 8192000000 10210600432 10636001280 1329316048 13.961 100 16 32 0 1342605600 9873936 3415616
23:25:13 7031 8192000000 10217478976 10640228352 1336004480 14.021 100 21 41 3 1345626384 5156592 4465312
23:26:23 35013 8192000000 10233384512 10648276992 1331238560 13.978 100 47 94 2 1348937440 6976512 10722368
23:27:59 5087 8192000000 10230200880 10646282240 1325431152 13.926 100 32 63 1 1343128928 10618336 7079440
23:29:05 7042 8192000000 10179582832 10595090432 1274738624 13.465 97 13 26 0 1282776160 4948944 3088592
23:30:18 57971 8192000000 10148258576 10542510080 1191336592 12.696 93 22 44 0 1201641712 4807200 5497920
23:32:17 5963 8192000000 10139379968 10538692608 1233212608 13.084 98 18 36 1 1243003808 5945904 3845296
23:33:23 8261 8192000000 10156305808 10555744256 1247415616 13.214 99 32 64 0 1261867648 6267248 8184784
23:34:36 8935 8192000000 10169026512 10579709952 1278972784 13.504 100 20 40 0 1290154768 7068864 4113120
23:35:50 17031 8192000000 10206912448 10597134336 1291065248 13.614 100 75 153 1 1314979632 5479248 18435136
23:37:07 9065 8192000000 10191219664 10602876928 1298277408 13.680 100 32 64 3 1312701008 6565072 7858528
23:38:17 7452 8192000000 10221152128 10626584576 1322286672 13.897 100 46 91 1 1340445776 6967440 11191664
23:39:29 23936 8192000000 10260507008 10670391296 1347778144 14.127 100 54 107 3 1374146192 15036384 11331664
23:41:09 36077 8192000000 10264471488 10687893504 1363939168 14.273 100 42 84 0 1388324928 13508768 10876992
23:43:18 6941 8192000000 10275940960 10701684736 1373302640 14.357 100 33 65 0 1396268208 14791152 8174416
23:44:30 9717 8192000000 10293014976 10718167040 1381862448 14.433 100 30 60 0 1409673296 20054976 7755872
23:45:47 12465 8192000000 10310315344 10736148480 1395521984 14.555 100 44 88 0 1423418784 17577392 10319408
23:47:09 4916 8192000000 10319136496 10743635968 1395080960 14.551 100 50 100 1 1430109200 22681216 12347024
23:48:22 5550 8192000000 10325041008 10750435328 1403150576 14.623 100 47 93 0 1438840400 24265040 11424784
23:49:35 5085 8192000000 10348079360 10771660800 1411596576 14.698 100 60 120 1 1458624080 31758016 15269488
23:50:45 5320 8192000000 10361227744 10774417408 1415117392 14.729 100 87 174 1 1466533424 30719856 20696176
23:51:55 1847 8192000000 10352165024 10760826880 1401055472 14.604 100 94 188 2 1455932368 32196432 22680464
23:53:03 18456 8192000000 10335385232 10751156224 1391416848 14.519 100 73 146 0 1446418528 36648304 18353376
23:54:35 14495 8192000000 10334736240 10757619712 1389382496 14.500 100 53 106 0 1442477344 39045024 14049824
23:55:57 1956 8192000000 10346550672 10750205952 1364040864 14.274 98 78 155 0 1433828640 49185312 20602464
23:57:02 13636 8192000000 10317537024 10750296064 1375453392 14.376 100 31 62 0 1430739184 46999856 8285936
23:58:20 6980 8192000000 10314560528 10736058368 1367515040 14.305 100 41 82 3 1420499216 42108160 10876016
23:59:30 11805 8192000000 10271198112 10699124736 1315259200 13.834 98 28 56 0 1373477264 50015120 8202944
00:00:45 24041 8192000000 10295842592 10731220992 1356023520 14.202 100 22 44 1 1406449488 44228352 6197616
00:02:11 36769 8192000000 10281177920 10718416896 1340307104 14.060 99 14 28 2 1376518880 32432096 3779680
00:03:49 49704 8192000000 10262160832 10706001920 1332906960 13.993 98 21 42 0 1372079568 34264752 4907856
00:05:49 61856 8192000000 10264279152 10697641984 1314694992 13.829 97 22 44 1 1334263104 14330704 5237408
00:07:52 122527 8192000000 10245371088 10682458112 1274418352 13.462 94 21 42 1 1288431744 9824656 4188736
00:10:56 180540 8192000000 10286522416 10724872192 1346321648 14.114 98 16 32 2 1361681264 11921056 3438560
00:14:57 45970 8192000000 10288764368 10730221568 1362326080 14.258 99 15 31 1 1380966736 15006720 3633936
00:16:44 65140 8192000000 10292931760 10747236352 1378335584 14.402 100 20 40 0 1402859376 19733600 4790192
00:18:53 103010 8192000000 10288475472 10739269632 1385472864 14.465 100 23 44 1 1412279952 21410160 5396928
00:21:41 71472 8192000000 10283189184 10744012800 1369792560 14.325 99 23 45 1 1390438336 15157360 5488416
00:24:11 92250 8192000000 10297314464 10765422592 1384941104 14.461 100 10 20 0 1400488672 13446032 2101536
00:27:39 54079 8192000000 10321971296 10759720960 1393472608 14.537 100 32 64 0 1409063920 8605152 6986160
00:29:44 47846 8192000000 10293264160 10737221632 1353471888 14.179 97 25 50 0 1368188976 9332800 5384288
00:31:35 62509 8192000000 10286102720 10746376192 1359082768 14.229 98 24 48 0 1376682704 12295872 5304064
00:33:42 16917 8192000000 10291614656 10733416448 1369986816 14.327 100 28 56 0 1387279136 11027424 6264896
00:35:08 71023 8192000000 10295253712 10761846784 1365865376 14.290 99 19 38 1 1393317008 23006800 4444832
00:37:27 133219 8192000000 10289620784 10742738944 1359631088 14.234 98 13 26 0 1383659712 21153712 2874912
00:40:41 43918 8192000000 10279827088 10718466048 1344426128 14.097 98 20 38 3 1358509040 10499984 3582928
00:42:25 33108 8192000000 10265825568 10716160000 1324888496 13.921 97 21 42 0 1335841200 6289120 4663584
00:43:59 53279 8192000000 10277773312 10738458624 1375481872 14.376 100 9 18 0 1388836112 11705360 1648880
00:45:55 55041 8192000000 10256178608 10708992000 1321888320 13.894 96 10 20 0 1340529536 16501856 2139360
00:48:20 86591 8192000000 10252516960 10702073856 1288112576 13.587 94 12 24 1 1310130000 19787280 2230144
00:50:53 63188 8192000000 10273620304 10718007296 1339642576 14.054 98 23 46 0 1363259312 18551072 5065664
00:52:58 125769 8192000000 10266907280 10711621632 1284844976 13.557 94 12 24 0 1298797008 11503248 2448784
00:56:13 47971 8192000000 10291442992 10736734208 1356510992 14.206 99 36 72 1 1381902064 17421376 7969696
00:58:02 116962 8192000000 10266457408 10722447360 1338568992 14.045 98 9 18 0 1355942272 15318880 2054400
01:01:11 65307 8192000000 10285299552 10728976384 1341993872 14.075 97 27 54 0 1358274112 10394112 5886128
01:03:42 54289 8192000000 10273168720 10731073536 1363461408 14.268 99 24 50 1 1378176768 9039552 5675808
01:05:55 35799 8192000000 10266114656 10718486528 1336633504 14.027 97 13 26 0 1349967856 10189648 3144704
01:07:31 78848 8192000000 10279369552 10751643648 1381781536 14.432 100 16 32 0 1402935536 16814000 4340000
01:09:50 86987 8192000000 10278126080 10737307648 1361580432 14.252 98 7 14 0 1371099424 8255616 1263376
01:12:20 82111 8192000000 10296706480 10748706816 1335077504 14.013 96 17 34 0 1349165728 10396624 3691600
01:14:45 42787 8192000000 10309441392 10755133440 1390117344 14.507 100 27 54 0 1413018688 16535072 6366272
01:16:48 74690 8192000000 10317396560 10782830592 1381348880 14.429 99 9 18 1 1412003552 28551968 2102704
01:19:14 44459 8192000000 10297518304 10750164992 1357358048 14.214 97 17 34 1 1374492544 13728784 3405712
01:21:00 33552 8192000000 10313653456 10747846656 1372254032 14.347 98 52 105 2 1392496000 8698496 11543472
01:22:35 174013 8192000000 10285089232 10747506688 1329796320 13.965 96 20 41 0 1356357280 21388720 5172240
01:26:33 60315 8192000000 10286667712 10744897536 1371297664 14.339 99 25 50 0 1388400016 11869888 5232464
01:28:43 91255 8192000000 10303845280 10760036352 1378559024 14.404 99 18 36 1 1400391792 17620640 4212128
01:31:15 69278 8192000000 10265847792 10718887936 1322613424 13.900 96 15 30 0 1340910032 14700336 3596272
01:33:41 115662 8192000000 10274000064 10739429376 1331860272 13.984 97 14 28 0 1359971216 24820688 3290256
01:36:37 48275 8192000000 10270806864 10713817088 1327997632 13.949 97 38 76 0 1346490048 9993120 8499296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-12-2021 01:39:32 to 13-12-2021 01:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 165 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-12-2021 01:39:47 to 13-12-2021 01:39:49.

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          233962
MON$OLDEST_ACTIVE               235925
MON$OLDEST_SNAPSHOT             235925
MON$NEXT_TRANSACTION            235925
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                       2013248
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 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 ---
:85: 
:86: mscs-test-161.budzdorov.ru    Mon Dec 13 01:38:07 2021
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Mon Dec 13 01:38:11 2021
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Mon Dec 13 01:38:11 2021
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:96: 
--- end of diff output ---

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

13.12.21 01:39:50. 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-12-13 01:39:52 No severe PSQL-related problems occured
13.12.21 01:39:54. End of report.