Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.11.2021 16: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 16631820 kB
MemAvailable 31576704 kB
Buffers 0 kB
Cached 15015724 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-16 16:37:54
DTS_END                         2021-11-16 16:37:54.6790
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/33734
TRN_ID                          249060
ATT_ID                          700
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: 9590.42 18599 1150850
customer order: creation 177.18 336 21261
customer order: refuse 14.90 321 1788
order to supplier: creation 47.18 1232 5662
order to supplier: removal 3.78 93021 454
invoice (draft): creation 47.18 3411 5662
invoice (draft): removal 55.56 775 6667
invoice accept: apply 41.80 17554 5016
invoice accept: cancel 50.85 747 6102
customer reserve: creation 3887.07 173 466448
customer reserve: removal 4961.18 55 595341
realization accept: apply 85.65 168 10278
realization accept: cancel 84.35 109 10122
payment from customer: creation 61.81 96 7417
payment from customer: removal 5.81 66 697
payment to supplier: creation 50.41 122 6049
payment to supplier: removal 3.42 134 410
service: total inventory turnovers 5.28 956 633
service: total monetary turnovers 6.08 26 729
service: aggregate perf. data 0.92 16945 110
service: refresh index statistics 0.03 235725 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1764
TEST_TIME 2 2732
TEST_TIME 3 3767
TEST_TIME 4 3521
TEST_TIME 5 3627
TEST_TIME 6 4734
TEST_TIME 7 5058
TEST_TIME 8 5091
TEST_TIME 9 5316
TEST_TIME 10 5556
TEST_TIME 11 5465
TEST_TIME 12 5380
TEST_TIME 13 5476
TEST_TIME 14 5620
TEST_TIME 15 5922
TEST_TIME 16 6182
TEST_TIME 17 6466
TEST_TIME 18 7063
TEST_TIME 19 7481
TEST_TIME 20 7741
TEST_TIME 21 8351
TEST_TIME 22 8428
TEST_TIME 23 8554
TEST_TIME 24 8805
TEST_TIME 25 8872
TEST_TIME 26 9075
TEST_TIME 27 9769
TEST_TIME 28 9905
TEST_TIME 29 10304
TEST_TIME 30 10308
TEST_TIME 31 10315
TEST_TIME 32 10382
TEST_TIME 33 10375
TEST_TIME 34 10230
TEST_TIME 35 10349
TEST_TIME 36 10255
TEST_TIME 37 10227
TEST_TIME 38 10194
TEST_TIME 39 10110
TEST_TIME 40 9917
TEST_TIME 41 9854
TEST_TIME 42 9763
TEST_TIME 43 9822
TEST_TIME 44 9975
TEST_TIME 45 9941
TEST_TIME 46 9870
TEST_TIME 47 10058
TEST_TIME 48 10002
TEST_TIME 49 9912
TEST_TIME 50 9870
TEST_TIME 51 9740
TEST_TIME 52 9629
TEST_TIME 53 9589
TEST_TIME 54 9617
TEST_TIME 55 9556
TEST_TIME 56 9471
TEST_TIME 57 9579
TEST_TIME 58 9539
TEST_TIME 59 9525
TEST_TIME 60 9564
TEST_TIME 61 9636
TEST_TIME 62 9656
TEST_TIME 63 9719
TEST_TIME 64 9848
TEST_TIME 65 9835
TEST_TIME 66 9934
TEST_TIME 67 9893
TEST_TIME 68 9919
TEST_TIME 69 10005
TEST_TIME 70 9936
TEST_TIME 71 9975
TEST_TIME 72 9846
TEST_TIME 73 9820
TEST_TIME 74 9789
TEST_TIME 75 9700
TEST_TIME 76 9729
TEST_TIME 77 9684
TEST_TIME 78 9608
TEST_TIME 79 9612
TEST_TIME 80 9638
TEST_TIME 81 9575
TEST_TIME 82 9530
TEST_TIME 83 9502
TEST_TIME 84 9428
TEST_TIME 85 9479
TEST_TIME 86 9440
TEST_TIME 87 9433
TEST_TIME 88 9509
TEST_TIME 89 9498
TEST_TIME 90 9492
TEST_TIME 91 9479
TEST_TIME 92 9479
TEST_TIME 93 9588
TEST_TIME 94 9618
TEST_TIME 95 9595
TEST_TIME 96 9686
TEST_TIME 97 9684
TEST_TIME 98 9744
TEST_TIME 99 9771
TEST_TIME 100 9768
TEST_TIME 101 9686
TEST_TIME 102 9620
TEST_TIME 103 9615
TEST_TIME 104 9585
TEST_TIME 105 9526
TEST_TIME 106 9486
TEST_TIME 107 9437
TEST_TIME 108 9475
TEST_TIME 109 9472
TEST_TIME 110 9446
TEST_TIME 111 9508
TEST_TIME 112 9474
TEST_TIME 113 9490
TEST_TIME 114 9534
TEST_TIME 115 9574
TEST_TIME 116 9553
TEST_TIME 117 9563
TEST_TIME 118 9579
TEST_TIME 119 9588
TEST_TIME 120 9594

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-11-2021 16:38:42 to 16-11-2021 16:38:45.

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 1584506 1584506 0 0.00 0 31307 6 0 0
doc_list_biud 1584506 1584506 0 0.00 0 24234 27 0 0
sp_add_invoice_to_stock 5118 5016 102 1.99 45 191628 17555 0 102
sp_cancel_adding_invoice 6102 6102 0 0.00 5 58411 748 0 0
sp_cancel_client_order 1788 1788 0 0.00 3 14385 321 0 0
sp_cancel_customer_reserve 595341 595341 0 0.00 1 24236 55 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 2 2402 66 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 2 8638 135 0 0
sp_cancel_supplier_invoice 6674 6667 7 0.10 2 17611 776 0 7
sp_cancel_supplier_order 454 454 0 0.00 5 1012184 93022 0 0
sp_cancel_write_off 10122 10122 0 0.00 1 11030 109 0 0
sp_client_order 21261 21261 0 0.00 3 17187 336 0 0
sp_customer_reserve 466448 466448 0 0.00 0 35190 174 0 0
sp_fill_shopping_cart 32580 32580 0 0.00 1 13528 37 0 0
sp_fill_shopping_cart_clo_res 466435 466435 0 0.00 0 15861 12 0 0
sp_get_clo_for_invoice 5003 5003 0 0.00 0 20061 271 0 0
sp_kill_cost_storno 17331 17331 0 0.00 0 15295 54 0 0
sp_kill_qstorno_ret_qs2qd 1207925 1207925 0 0.00 0 24230 30 0 0
sp_kill_qty_storno 635755 635755 0 0.00 0 24234 66 0 0
sp_lock_selected_doc 21137 21137 0 0.00 0 8100 5 0 0
sp_make_cost_storno 28747 28747 0 0.00 0 15759 55 0 0
sp_multiply_rows_for_pdistr 28747 28747 0 0.00 0 8047 27 0 0
sp_multiply_rows_for_qdistr 21261 21261 0 0.00 0 16329 158 0 0
sp_pay_from_customer 7417 7417 0 0.00 4 13422 96 0 0
sp_pay_to_supplier 6049 6049 0 0.00 4 19356 122 0 0
sp_payment_common 13466 13466 0 0.00 1 18828 26 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 14357 298 0 0
sp_qd_handle_on_invoice_upd_sts 11105 11105 0 0.00 0 23122 329 0 0
sp_qd_handle_on_reserve_upd_sts 10278 10278 0 0.00 0 14504 55 0 0
sp_reserve_write_off 10278 10278 0 0.00 6 19315 169 0 0
sp_supplier_invoice 5662 5662 0 0.00 7 96735 3411 0 0
sp_supplier_order 5662 5662 0 0.00 5 29375 1232 0 0
srv_aggregate_perf_data 110 110 0 0.00 3199 132147 16945 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1524 138563 45610 0 0
srv_find_qd_qs_mism 1102848 1102848 0 0.00 0 31307 5 0 0
srv_make_invnt_saldo 633 633 0 0.00 35 25388 956 0 0
srv_make_money_saldo 729 729 0 0.00 1 2529 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 98964 359959 235725 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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 5017 5017 0 0.00 0 20653 168 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 3435 83 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 2969 12 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 1512 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 360 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 594 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 5305 33 0 0
t$perf-norm:sp_cancel_supplier_order 454 454 0 0.00 0 22235 1612 0 0
t$perf-norm:sp_cancel_write_off 1067 1067 0 0.00 0 234 2 0 0
t$perf-norm:sp_client_order 21261 21261 0 0.00 0 9064 17 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 1569 4 0 0
t$perf-norm:sp_pay_from_customer 7417 7417 0 0.00 0 5503 12 0 0
t$perf-norm:sp_pay_to_supplier 6049 6049 0 0.00 0 6350 10 0 0
t$perf-norm:sp_reserve_write_off 10278 10278 0 0.00 0 13693 9 0 0
t$perf-norm:sp_supplier_invoice 5662 5662 0 0.00 0 8058 19 0 0
t$perf-norm:sp_supplier_order 5662 5662 0 0.00 0 9009 17 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 435 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 633 633 0 0.00 0 1651 19 0 0
t$perf-norm:srv_make_money_saldo 729 729 0 0.00 0 1203 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10210 10006 204 1.99 0 7837 19 0 204
v_all_customers 38716 38716 0 0.00 0 67 1 0 0
v_all_suppliers 31548 31548 0 0.00 0 91 1 0 0
v_all_wares 42520 42520 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 5554 15 0 0
v_cancel_client_order 3576 3576 0 0.00 0 5548 6 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 290 3 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 2311 5 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 7636 27 0 0
v_cancel_supplier_invoice 736 722 14 1.90 0 7676 30 0 14
v_cancel_supplier_order 908 908 0 0.00 0 1411 4 0 0
v_cancel_write_off 2134 2134 0 0.00 0 6869 19 0 0
v_max_id_clo_ord 5662 5662 0 0.00 0 309 1 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 4400 26 0 0
v_max_id_ord_sup 5658 5658 0 0.00 0 7325 6 0 0
v_max_non_paid_invoice 1595 1595 0 0.00 0 137 2 0 0
v_max_non_paid_realizn 3952 3952 0 0.00 0 769 3 0 0
v_min_id_clo_ord 5662 5662 0 0.00 0 8590 10 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 1604 4 0 0
v_min_id_ord_sup 5658 5658 0 0.00 0 4364 9 0 0
v_min_non_paid_invoice 6049 6049 0 0.00 0 18823 22 0 0
v_min_non_paid_realizn 7417 7417 0 0.00 0 7998 20 0 0
v_our_firm 10734 10734 0 0.00 0 13 1 0 0
v_reserve_write_off 20556 20556 0 0.00 0 4963 6 0 0
x_lock_depdocs_on_canc_invoice 12408 12408 0 0.00 0 18889 104 0 0
x_lock_depdocs_on_canc_sup_ord 454 454 0 0.00 0 7080 320 0 0
x_make_qstorno_1000_1200 5662 5662 0 0.00 1 29362 1156 0 0
x_make_qstorno_1200_2000 5662 5662 0 0.00 3 96660 3276 0 0
x_make_qstorno_2100_3300 466448 466448 0 0.00 0 35189 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-11-2021 16:38:46 to 16-11-2021 16:38:50.

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:07:58 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:08:59 33342 8192000000 9927897744 10243854336 1001180128 10.890 100 84 168 0 1029440848 6187248 22073472
14:10:38 25909 8192000000 9959585424 10285674496 1041683280 11.281 100 91 182 0 1073446832 8840416 22923136
14:12:08 14909 8192000000 9995749824 10332635136 1077641088 11.625 100 86 172 1 1109253840 10254976 21357776
14:13:29 14262 8192000000 10040574656 10396151808 1133923120 12.158 100 78 156 0 1162757728 10731536 18103072
14:14:48 7758 8192000000 10098583120 10462408704 1184451744 12.632 100 77 154 0 1214153680 11897472 17804464
14:15:59 3449 8192000000 10139826384 10512400384 1229085296 13.046 100 79 158 1 1260635680 14492528 17057856
14:17:04 2812 8192000000 10163860624 10550472704 1261470672 13.343 100 64 128 1 1286562256 10310064 14781520
14:18:07 1818 8192000000 10205308912 10587783168 1287274048 13.579 100 88 176 1 1320475440 13570656 19630736
14:19:10 3791 8192000000 10186942224 10588139520 1295787872 13.657 100 39 78 2 1312160496 7986704 8385920
14:20:15 50538 8192000000 10200837136 10617860096 1310534608 13.791 100 25 50 3 1326188336 9814576 5839152
14:22:06 8502 8192000000 10209822640 10629992448 1319474416 13.872 100 33 67 1 1334227392 7188736 7564240
14:23:16 5777 8192000000 10208041248 10631364608 1323633376 13.910 100 18 36 2 1333486480 6128480 3724624
14:24:22 50370 8192000000 10250594048 10649808896 1329695680 13.964 100 59 118 1 1350552448 6105600 14751168
14:26:12 6251 8192000000 10221905936 10641551360 1318072880 13.859 99 36 72 4 1331883392 6032720 7777792
14:27:19 8637 8192000000 10201276752 10618126336 1273351424 13.452 96 26 50 1 1285378544 6179696 5847424
14:28:28 10293 8192000000 10190313632 10605457408 1289112048 13.596 99 19 38 0 1298777312 5516864 4148400
14:29:39 23988 8192000000 10160853536 10540572672 1202748432 12.802 94 50 99 0 1219150288 4869088 11532768
14:31:04 3482 8192000000 10146014624 10537033728 1218983712 12.952 97 33 66 1 1233638656 7244656 7410288
14:32:08 6693 8192000000 10151285536 10554376192 1238934816 13.136 98 24 48 1 1250399120 6144272 5320032
14:33:17 7443 8192000000 10190403200 10597531648 1292605936 13.628 100 30 60 0 1305861344 6363104 6892304
14:34:25 30387 8192000000 10192518848 10608427008 1300074704 13.696 100 30 60 3 1321087936 14611824 6401408
14:35:56 7554 8192000000 10202111504 10622283776 1313542992 13.818 100 20 40 0 1323676976 6249584 3884400
14:37:05 7475 8192000000 10225823776 10642227200 1329498224 13.963 100 16 32 0 1338225248 5352880 3374144
14:38:16 16977 8192000000 10253048432 10664775680 1348987824 14.138 100 36 72 0 1363927280 7014736 7924720
14:39:40 43273 8192000000 10262002672 10691842048 1368587120 14.314 100 33 66 0 1385768192 8970352 8210720
14:41:55 33320 8192000000 10287400928 10706804736 1376502512 14.385 100 43 86 2 1399924352 13505680 9916160
14:43:30 10401 8192000000 10304387872 10719399936 1384251552 14.455 100 59 113 0 1410825520 13549632 13024336
14:44:45 15223 8192000000 10306845104 10731913216 1392729504 14.530 100 53 106 2 1423474528 18176016 12569008
14:46:15 8981 8192000000 10331667584 10751164416 1406118272 14.649 100 66 128 1 1440839808 20060752 14660784
14:47:28 3763 8192000000 10339890080 10759577600 1410284496 14.686 100 57 114 1 1448126032 23977424 13864112
14:48:38 11412 8192000000 10341177504 10768744448 1417345520 14.749 100 51 102 2 1453131840 23594656 12191664
14:50:12 4295 8192000000 10338470992 10762575872 1400271200 14.597 98 44 88 2 1439866016 28157184 11437632
14:51:32 8720 8192000000 10331134336 10749579264 1393604736 14.538 100 60 120 1 1442932064 34155984 15171344
14:52:48 3822 8192000000 10328959648 10736648192 1378562608 14.404 100 85 172 1 1436220080 36693520 20963952
14:53:59 4077 8192000000 10306300160 10703069184 1348061664 14.130 98 78 155 2 1407794496 39595392 20137440
14:55:08 3014 8192000000 10336041008 10746171392 1374248352 14.365 100 73 144 2 1438285072 45602928 18433792
14:56:14 8607 8192000000 10329734368 10746417152 1375148960 14.373 100 56 112 0 1437192848 47138448 14905440
14:57:31 1523 8192000000 10338576544 10741497856 1367098048 14.301 100 96 193 2 1438847072 46869168 24879856
14:58:36 17478 8192000000 10323647104 10751778816 1372304912 14.348 99 42 83 1 1422379376 38991824 11082640
15:00:06 20119 8192000000 10337884000 10766184448 1388096464 14.489 100 33 66 0 1437869248 40913472 8859312
15:01:29 10235 8192000000 10319739744 10747633664 1374960720 14.371 100 36 72 2 1424259264 39225696 10072848
15:02:42 25338 8192000000 10323538976 10757611520 1370912288 14.335 99 29 58 0 1425092128 46028128 8151712
15:04:14 33970 8192000000 10296339040 10746593280 1377570720 14.395 100 17 34 0 1415790080 33319216 4900144
15:06:05 42191 8192000000 10283338320 10749882368 1352138576 14.167 99 15 30 1 1385602960 29435312 4029072
15:07:49 58166 8192000000 10314084240 10750353408 1371270304 14.338 99 27 54 0 1392512016 14952080 6289632
15:09:51 103379 8192000000 10291216864 10736672768 1340846608 14.065 97 7 14 0 1351452192 9516768 1088816
15:12:37 40832 8192000000 10304325360 10748547072 1392010976 14.524 100 28 56 2 1409602400 10616480 6974944
15:14:19 86477 8192000000 10299499648 10735480832 1362790192 14.262 98 26 52 2 1384883744 15596144 6497408
15:16:51 60193 8192000000 10319188912 10756214784 1395079552 14.551 100 50 100 2 1417574016 10440432 12054032
15:18:54 72977 8192000000 10319522272 10783510528 1400291536 14.598 100 33 66 1 1433815472 25715472 7808464
15:23:03 17342 8192000000 10322208112 10765209600 1397300736 14.571 99 43 86 0 1417571120 10469552 9800832
15:24:23 33680 8192000000 10292484096 10757787648 1393087664 14.533 100 21 42 1 1412020464 13342416 5590384
15:26:38 20947 8192000000 10291027504 10752405504 1391071776 14.515 99 19 40 0 1402472352 6658672 4741904
15:28:00 62370 8192000000 10305923424 10766852096 1391871776 14.523 99 17 34 0 1408318848 12235184 4211888
15:30:16 47053 8192000000 10299356784 10763128832 1388109568 14.489 99 39 74 1 1407736912 10905664 8721680
15:32:09 62694 8192000000 10292598672 10756640768 1361227632 14.248 97 17 34 0 1377936336 12118608 4590096
15:35:44 18176 8192000000 10286154464 10742906880 1380046608 14.417 100 42 84 3 1399269776 9586192 9636976
15:37:11 41468 8192000000 10290515392 10743652352 1356347280 14.205 97 23 46 0 1374191184 11997872 5846032
15:38:57 59325 8192000000 10273736928 10735955968 1321184896 13.887 95 13 26 2 1338037520 13994784 2857840
15:41:02 116769 8192000000 10297897456 10758344704 1290721360 13.611 93 10 20 0 1307419568 14719728 1978480
15:43:59 55318 8192000000 10287973312 10738044928 1331411824 13.980 96 18 36 2 1351716992 16107856 4197312
15:46:01 35477 8192000000 10270136816 10717319168 1334644736 14.009 98 24 48 0 1352594480 12569824 5379920
15:47:36 31860 8192000000 10274255488 10715262976 1317149088 13.851 96 26 52 0 1331258080 7947024 6161968
15:49:09 103746 8192000000 10275687328 10724208640 1352133904 14.167 99 28 56 0 1367563760 9226976 6202880
15:51:54 22844 8192000000 10295013232 10738794496 1377243872 14.392 100 51 102 2 1402090304 13507184 11339248
15:53:21 63165 8192000000 10272799696 10727796736 1362043568 14.256 99 23 46 2 1378321072 11437440 4840064
15:55:41 91567 8192000000 10270266320 10727419904 1337399168 14.034 97 12 24 0 1352823328 12368816 3055344
15:58:17 79046 8192000000 10269885888 10730795008 1362306656 14.258 99 20 40 0 1374594016 6849728 5437632
16:00:41 47193 8192000000 10276464384 10735906816 1369296400 14.321 99 15 30 0 1383249216 10665936 3286880
16:02:29 68789 8192000000 10268899888 10726801408 1363625680 14.270 99 10 20 0 1376712608 10834224 2252704
16:04:43 70821 8192000000 10289916208 10754269184 1377685136 14.396 99 17 34 0 1395068144 13735216 3647792
16:07:02 35515 8192000000 10295859376 10746212352 1380211008 14.418 99 43 85 1 1398366400 8719104 9436288
16:08:39 19717 8192000000 10319347264 10770202624 1391826320 14.522 99 38 77 0 1410724128 10369104 8528704
16:10:03 102385 8192000000 10281545536 10750091264 1330162672 13.969 95 14 28 1 1350999744 17866128 2970944
16:12:48 81129 8192000000 10273747616 10719907840 1319064176 13.868 96 11 22 0 1331675392 10208880 2402336
16:15:15 64295 8192000000 10277959552 10744864768 1352620816 14.171 98 8 16 1 1368933040 14475920 1836304
16:17:26 138562 8192000000 10280445104 10738417664 1367290752 14.303 99 17 34 1 1380850848 9210080 4350016
16:20:53 84573 8192000000 10223593440 10678411264 1249381504 13.233 91 17 34 1 1260401120 6516464 4503152
16:23:17 73304 8192000000 10244092432 10701877248 1305664784 13.747 96 14 28 0 1320147888 10872912 3610192
16:25:44 70078 8192000000 10259806176 10714169344 1333801280 14.001 98 19 38 2 1346639264 8106720 4731264
16:28:01 58639 8192000000 10258331248 10712690688 1346283824 14.114 99 21 40 1 1364463360 12846704 5332832
16:30:05 38866 8192000000 10254390592 10700083200 1319847312 13.875 97 34 68 1 1339894512 12328016 7719184
16:31:53 85243 8192000000 10266249696 10722078720 1346047120 14.112 99 19 38 0 1362022608 11583008 4392480
16:34:24 27204 8192000000 10266058976 10705080320 1342695360 14.082 99 24 48 0 1355740736 7452064 5593312
16:36:02 49709 8192000000 10280193456 10734018560 1367115296 14.301 100 22 44 1 1384920368 12748528 5056544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-11-2021 16:38:52 to 16-11-2021 16:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 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 16-11-2021 16:39:07 to 16-11-2021 16:39:09.

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          247577
MON$OLDEST_ACTIVE               249429
MON$OLDEST_SNAPSHOT             249429
MON$NEXT_TRANSACTION            249429
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                       2050016
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:422: 
:423: mscs-test-161.budzdorov.ru    Tue Nov 16 16:37:57 2021
:424:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:425: 
:426: 
:427: mscs-test-161.budzdorov.ru    Tue Nov 16 16:37:57 2021
:428:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:429: 
:430: 
:431: mscs-test-161.budzdorov.ru    Tue Nov 16 16:37:57 2021
:432:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:433: 
:434: 
:435: mscs-test-161.budzdorov.ru    Tue Nov 16 16:38:08 2021
:436:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:437: 
:438: 
:439: mscs-test-161.budzdorov.ru    Tue Nov 16 16:38:09 2021
:440:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:441: 
--- end of diff output ---

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

16.11.21 16:39:10. 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-16 16:39:13 No severe PSQL-related problems occured
16.11.21 16:39:14. End of report.