Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16072764 kB
MemAvailable 30805536 kB
Buffers 0 kB
Cached 15650944 kB
SwapCached 2456 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-10 16:37:56
DTS_END                         2021-11-10 16:37:56.0310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37700
TRN_ID                          244514
ATT_ID                          706
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: 9204.35 19052 1104522
customer order: creation 177.32 368 21278
customer order: refuse 15.82 323 1898
order to supplier: creation 47.14 1274 5657
order to supplier: removal 3.69 89190 443
invoice (draft): creation 47.43 3362 5691
invoice (draft): removal 51.38 771 6165
invoice accept: apply 43.13 17643 5176
invoice accept: cancel 46.78 798 5613
customer reserve: creation 3904.78 179 468574
customer reserve: removal 4562.00 57 547440
realization accept: apply 85.38 168 10246
realization accept: cancel 86.30 111 10356
payment from customer: creation 60.80 99 7296
payment from customer: removal 5.52 91 662
payment to supplier: creation 50.62 116 6074
payment to supplier: removal 3.69 73 443
service: total inventory turnovers 4.99 1109 599
service: total monetary turnovers 6.65 24 798
service: aggregate perf. data 0.91 19442 109
service: refresh index statistics 0.03 245846 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-11-2021 16:39:48 to 10-11-2021 16:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4252
TEST_TIME 2 4903
TEST_TIME 3 4746
TEST_TIME 4 4971
TEST_TIME 5 4770
TEST_TIME 6 4787
TEST_TIME 7 5213
TEST_TIME 8 4859
TEST_TIME 9 4839
TEST_TIME 10 4890
TEST_TIME 11 4928
TEST_TIME 12 5573
TEST_TIME 13 5700
TEST_TIME 14 6059
TEST_TIME 15 6255
TEST_TIME 16 6247
TEST_TIME 17 6494
TEST_TIME 18 6710
TEST_TIME 19 7294
TEST_TIME 20 7636
TEST_TIME 21 7819
TEST_TIME 22 8431
TEST_TIME 23 8570
TEST_TIME 24 8698
TEST_TIME 25 8691
TEST_TIME 26 8955
TEST_TIME 27 9279
TEST_TIME 28 10250
TEST_TIME 29 10620
TEST_TIME 30 10595
TEST_TIME 31 10441
TEST_TIME 32 10376
TEST_TIME 33 10144
TEST_TIME 34 10268
TEST_TIME 35 10091
TEST_TIME 36 10030
TEST_TIME 37 10032
TEST_TIME 38 9920
TEST_TIME 39 9949
TEST_TIME 40 9837
TEST_TIME 41 9784
TEST_TIME 42 9707
TEST_TIME 43 9644
TEST_TIME 44 9589
TEST_TIME 45 9532
TEST_TIME 46 9661
TEST_TIME 47 9591
TEST_TIME 48 9514
TEST_TIME 49 9490
TEST_TIME 50 9385
TEST_TIME 51 9440
TEST_TIME 52 9419
TEST_TIME 53 9376
TEST_TIME 54 9416
TEST_TIME 55 9596
TEST_TIME 56 9575
TEST_TIME 57 9605
TEST_TIME 58 9591
TEST_TIME 59 9633
TEST_TIME 60 9654
TEST_TIME 61 9682
TEST_TIME 62 9738
TEST_TIME 63 9670
TEST_TIME 64 9745
TEST_TIME 65 9728
TEST_TIME 66 9688
TEST_TIME 67 9628
TEST_TIME 68 9620
TEST_TIME 69 9541
TEST_TIME 70 9514
TEST_TIME 71 9441
TEST_TIME 72 9431
TEST_TIME 73 9403
TEST_TIME 74 9418
TEST_TIME 75 9343
TEST_TIME 76 9338
TEST_TIME 77 9427
TEST_TIME 78 9475
TEST_TIME 79 9483
TEST_TIME 80 9482
TEST_TIME 81 9586
TEST_TIME 82 9593
TEST_TIME 83 9544
TEST_TIME 84 9607
TEST_TIME 85 9609
TEST_TIME 86 9643
TEST_TIME 87 9563
TEST_TIME 88 9544
TEST_TIME 89 9487
TEST_TIME 90 9468
TEST_TIME 91 9399
TEST_TIME 92 9371
TEST_TIME 93 9346
TEST_TIME 94 9340
TEST_TIME 95 9317
TEST_TIME 96 9301
TEST_TIME 97 9278
TEST_TIME 98 9366
TEST_TIME 99 9380
TEST_TIME 100 9421
TEST_TIME 101 9430
TEST_TIME 102 9436
TEST_TIME 103 9397
TEST_TIME 104 9497
TEST_TIME 105 9465
TEST_TIME 106 9480
TEST_TIME 107 9500
TEST_TIME 108 9459
TEST_TIME 109 9374
TEST_TIME 110 9387
TEST_TIME 111 9395
TEST_TIME 112 9384
TEST_TIME 113 9352
TEST_TIME 114 9350
TEST_TIME 115 9327
TEST_TIME 116 9306
TEST_TIME 117 9246
TEST_TIME 118 9219
TEST_TIME 119 9233
TEST_TIME 120 9207

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-11-2021 16:39:51 to 10-11-2021 16:39:55.

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 1539549 1539549 0 0.00 0 15761 6 0 0
doc_list_biud 1539549 1539549 0 0.00 0 36866 26 0 0
sp_add_invoice_to_stock 5335 5176 159 2.98 38 187532 17643 0 159
sp_cancel_adding_invoice 5613 5613 0 0.00 5 73966 798 0 0
sp_cancel_client_order 1898 1898 0 0.00 4 12351 323 0 0
sp_cancel_customer_reserve 547440 547440 0 0.00 1 36868 57 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 8540 92 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 2565 74 0 0
sp_cancel_supplier_invoice 6182 6165 17 0.27 2 23763 772 0 17
sp_cancel_supplier_order 443 443 0 0.00 7 1132728 89190 0 0
sp_cancel_write_off 10356 10356 0 0.00 1 18427 111 0 0
sp_client_order 21278 21278 0 0.00 3 40212 368 0 0
sp_customer_reserve 468574 468574 0 0.00 0 149305 179 0 0
sp_fill_shopping_cart 32614 32614 0 0.00 1 14604 38 0 0
sp_fill_shopping_cart_clo_res 468569 468569 0 0.00 0 12480 14 0 0
sp_get_clo_for_invoice 5163 5163 0 0.00 0 21856 308 0 0
sp_kill_cost_storno 17074 17074 0 0.00 0 10973 56 0 0
sp_kill_qstorno_ret_qs2qd 1111844 1111844 0 0.00 0 36863 31 0 0
sp_kill_qty_storno 587321 587321 0 0.00 0 36865 67 0 0
sp_lock_selected_doc 21476 21476 0 0.00 0 7576 6 0 0
sp_make_cost_storno 28776 28776 0 0.00 0 15589 49 0 0
sp_multiply_rows_for_pdistr 28776 28776 0 0.00 0 9637 27 0 0
sp_multiply_rows_for_qdistr 21278 21278 0 0.00 0 38015 159 0 0
sp_pay_from_customer 7296 7296 0 0.00 4 10844 99 0 0
sp_pay_to_supplier 6074 6074 0 0.00 4 10221 117 0 0
sp_payment_common 13370 13370 0 0.00 1 9532 21 0 0
sp_qd_handle_on_cancel_clo 1898 1898 0 0.00 0 12344 297 0 0
sp_qd_handle_on_invoice_upd_sts 10773 10773 0 0.00 0 16810 322 0 0
sp_qd_handle_on_reserve_upd_sts 10246 10246 0 0.00 0 20452 56 0 0
sp_reserve_write_off 10246 10246 0 0.00 6 20478 169 0 0
sp_supplier_invoice 5691 5691 0 0.00 6 92406 3363 0 0
sp_supplier_order 5657 5657 0 0.00 6 30258 1275 0 0
srv_aggregate_perf_data 109 109 0 0.00 3013 231811 19442 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1854 144110 45763 0 0
srv_find_qd_qs_mism 1056206 1056206 0 0.00 0 15457 5 0 0
srv_make_invnt_saldo 599 599 0 0.00 39 50498 1109 0 0
srv_make_money_saldo 798 798 0 0.00 1 2783 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 128275 356443 245847 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5176 5176 0 0.00 0 11626 150 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 1 6918 86 0 0
t$perf-norm:sp_cancel_client_order 1898 1898 0 0.00 0 5247 13 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 6385 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 706 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 1981 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 1555 19 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 22931 1429 0 0
t$perf-norm:sp_cancel_write_off 1070 1070 0 0.00 0 687 5 0 0
t$perf-norm:sp_client_order 21278 21278 0 0.00 0 8519 16 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 2553 9 0 0
t$perf-norm:sp_pay_from_customer 7296 7296 0 0.00 0 13545 11 0 0
t$perf-norm:sp_pay_to_supplier 6074 6074 0 0.00 0 13505 14 0 0
t$perf-norm:sp_reserve_write_off 10246 10246 0 0.00 0 13604 13 0 0
t$perf-norm:sp_supplier_invoice 5691 5691 0 0.00 0 10050 16 0 0
t$perf-norm:sp_supplier_order 5657 5657 0 0.00 0 8169 17 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 700 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 2077 21 0 0
t$perf-norm:srv_make_money_saldo 798 798 0 0.00 0 1241 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 72 20 0 0
v_add_invoice_to_stock 10638 10320 318 2.98 0 8646 19 0 318
v_all_customers 38748 38748 0 0.00 0 23 0 0 0
v_all_suppliers 31104 31104 0 0.00 0 17 1 0 0
v_all_wares 42554 42554 0 0.00 0 95 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 2688 12 0 0
v_cancel_client_order 3796 3796 0 0.00 0 4764 5 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 8534 18 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 429 3 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 2463 12 0 0
v_cancel_supplier_invoice 846 812 34 4.01 0 1142 13 0 34
v_cancel_supplier_order 886 886 0 0.00 0 611 5 0 0
v_cancel_write_off 2140 2140 0 0.00 0 8674 19 0 0
v_max_id_clo_ord 5657 5657 0 0.00 0 585 1 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 2627 14 0 0
v_max_id_ord_sup 5680 5680 0 0.00 0 5286 5 0 0
v_max_non_paid_invoice 1859 1859 0 0.00 0 336 2 0 0
v_max_non_paid_realizn 3848 3848 0 0.00 0 3414 4 0 0
v_min_id_clo_ord 5657 5657 0 0.00 0 2547 6 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 3168 5 0 0
v_min_id_ord_sup 5680 5680 0 0.00 0 7628 8 0 0
v_min_non_paid_invoice 6074 6074 0 0.00 0 4610 13 0 0
v_min_non_paid_realizn 7296 7296 0 0.00 0 9528 17 0 0
v_our_firm 10702 10702 0 0.00 0 14 1 0 0
v_reserve_write_off 20492 20492 0 0.00 0 8657 7 0 0
x_lock_depdocs_on_canc_invoice 11372 11372 0 0.00 0 22371 108 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 7810 286 0 0
x_make_qstorno_1000_1200 5657 5657 0 0.00 2 30232 1194 0 0
x_make_qstorno_1200_2000 5691 5691 0 0.00 3 92396 3223 0 0
x_make_qstorno_2100_3300 468574 468574 0 0.00 0 149301 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-11-2021 16:39:56 to 10-11-2021 16:40:00.

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:59 7 8192000000 8800046736 8811548672 6309952 0.076 2 1 2 0 6409584 56848 42784
14:09:00 31476 8192000000 9904678896 10217336832 986042432 10.743 100 90 180 1 1016292624 6383696 23866496
14:10:38 23638 8192000000 9953636016 10276421632 1033567424 11.203 100 88 176 0 1064385216 8378304 22439488
14:12:07 12034 8192000000 9989767552 10324291584 1075582224 11.605 100 88 176 0 1106550416 9351200 21616992
14:13:21 14084 8192000000 10031575888 10381225984 1122207600 12.048 100 74 148 1 1149980336 9690672 18082064
14:14:38 8000 8192000000 10096576320 10459336704 1181368704 12.603 100 77 154 1 1211432688 12216480 17847504
14:15:48 5653 8192000000 10134464640 10507128832 1228108368 13.037 100 79 158 1 1259567264 14239040 17219856
14:16:55 5110 8192000000 10161765456 10546032640 1256508560 13.298 100 66 129 1 1281125136 10159600 14456976
14:18:02 1954 8192000000 10194744864 10578321408 1283782448 13.548 100 77 154 1 1314519536 12993024 17744064
14:19:05 2184 8192000000 10221402688 10606206976 1302440592 13.717 100 83 166 1 1334400528 13223968 18735968
14:20:08 1386 8192000000 10227744992 10615816192 1309557456 13.782 100 94 187 2 1341542432 11282592 20702384
14:21:10 7632 8192000000 10204816512 10615820288 1316060816 13.841 100 25 50 1 1328372000 6271968 6039216
14:22:18 7460 8192000000 10212934784 10626314240 1320798944 13.884 100 25 50 2 1332777792 6675472 5303376
14:23:26 9953 8192000000 10211177920 10625875968 1325518032 13.927 100 18 36 0 1335568288 5763040 4287216
14:24:37 27920 8192000000 10228319968 10649751552 1330716832 13.974 100 33 66 1 1345481872 7379904 7385136
14:26:05 6319 8192000000 10242267904 10656837632 1337388016 14.034 100 26 52 0 1349354016 5633520 6332480
14:27:13 9224 8192000000 10216557696 10637271040 1318472944 13.863 99 23 44 3 1329725120 5413248 5838928
14:28:25 64439 8192000000 10199097168 10615631872 1291774720 13.620 98 32 64 1 1304577968 5298032 7505216
14:30:30 3673 8192000000 10170743072 10569875456 1267628432 13.400 100 42 83 0 1284535632 7784944 9122256
14:31:36 7864 8192000000 10137639872 10536083456 1218351120 12.946 97 17 34 0 1227046864 4759776 3935968
14:32:50 32006 8192000000 10162501696 10569297920 1268474352 13.408 100 22 45 0 1278121520 4840912 4806256
14:34:31 6672 8192000000 10190253904 10599972864 1292240960 13.625 100 30 60 2 1306000752 5961648 7798144
14:35:39 6199 8192000000 10199080448 10612195328 1306557616 13.755 100 36 70 1 1322656320 6392064 9706640
14:36:46 10240 8192000000 10208464720 10624061440 1314394016 13.826 100 24 47 1 1326268000 6828896 5045088
14:37:58 48396 8192000000 10249054544 10659569664 1338316928 14.042 100 41 81 1 1352966976 4942416 9707632
14:39:54 13895 8192000000 10266842768 10685693952 1361212576 14.248 100 51 102 0 1382181856 9790576 11178704
14:41:14 20340 8192000000 10283016272 10705702912 1370408176 14.331 100 44 88 1 1396604528 16226400 9969952
14:42:41 10851 8192000000 10305450656 10728148992 1389690192 14.503 100 50 100 1 1416001856 14351024 11960640
14:43:57 17524 8192000000 10312222096 10740146176 1398174752 14.579 100 38 76 0 1424627584 17401440 9051392
14:45:19 9950 8192000000 10330766704 10756890624 1409633008 14.681 100 51 103 3 1441114080 19370592 12110480
14:46:38 15290 8192000000 10340264752 10760077312 1409576048 14.680 100 65 128 2 1451885712 26654592 15655072
14:47:59 11926 8192000000 10338082848 10774065152 1417398672 14.750 100 35 70 1 1454285472 27302352 9584448
14:49:13 12297 8192000000 10353255664 10786709504 1418701008 14.761 100 49 98 0 1458530304 28072608 11756688
14:50:30 9476 8192000000 10335696624 10766135296 1404205072 14.632 99 45 89 2 1444846240 29002176 11638992
14:51:46 3219 8192000000 10354427808 10780323840 1409697568 14.681 99 60 120 1 1459674160 35523888 14452704
14:52:55 3594 8192000000 10336495072 10758475776 1381824320 14.433 98 58 116 0 1431764880 34471792 15468768
14:54:05 3738 8192000000 10353098304 10767556608 1383692816 14.450 98 70 139 0 1443661776 41149408 18819552
14:55:11 3278 8192000000 10350536624 10757582848 1379113808 14.409 99 86 171 0 1443415776 42384176 21917792
14:56:24 1851 8192000000 10349151488 10758017024 1386248608 14.472 100 81 162 0 1452968144 46449360 20270176
14:57:31 12497 8192000000 10315191440 10731286528 1361802688 14.254 100 43 85 3 1415216336 42078368 11335280
14:58:47 3867 8192000000 10323197584 10727395328 1347066960 14.121 99 72 144 1 1413399152 47149392 19182800
14:59:53 15668 8192000000 10328429600 10763247616 1382817008 14.442 100 34 68 1 1434996384 42874416 9304960
15:01:18 30658 8192000000 10320535200 10750558208 1375955744 14.380 100 39 75 1 1431661872 45190496 10515632
15:02:55 36293 8192000000 10296831888 10737377280 1342974752 14.084 98 20 40 2 1395613120 46916320 5722048
15:04:32 32758 8192000000 10301065968 10746064896 1329082624 13.959 99 16 32 2 1395260128 61094320 5083184
15:06:15 110521 8192000000 10277531424 10727133184 1309289600 13.780 97 12 24 1 1329933312 17934080 2709632
15:09:09 20309 8192000000 10301185200 10732175360 1378568112 14.404 100 51 102 1 1402690128 12117248 12004768
15:10:31 144110 8192000000 10286952224 10725965824 1340091744 14.058 97 27 52 1 1363238672 17232400 5914528
15:14:18 57804 8192000000 10290043616 10738671616 1389239168 14.499 100 23 45 2 1403657984 8897920 5520896
15:16:17 24327 8192000000 10274365120 10730438656 1375835504 14.379 99 24 48 0 1390114368 8916080 5362784
15:17:51 133475 8192000000 10323438368 10774069248 1387844992 14.487 99 13 26 0 1399325040 8455712 3024336
15:21:05 32394 8192000000 10327174880 10790907904 1416501328 14.742 100 11 22 0 1434613712 15399728 2712656
15:23:08 72703 8192000000 10321460032 10782187520 1416409680 14.741 100 28 56 2 1439475280 16843040 6222560
15:25:22 26233 8192000000 10312166432 10777858048 1392345440 14.527 98 14 28 2 1411818880 16275424 3198016
15:26:50 48263 8192000000 10303192384 10772963328 1405531552 14.644 99 20 40 0 1423986784 13823056 4632176
15:29:06 64660 8192000000 10305237152 10766913536 1392462928 14.528 99 8 15 0 1403965280 9668704 1833648
15:31:11 30535 8192000000 10323526720 10783711232 1414354544 14.723 100 24 48 0 1443668384 23624720 5689120
15:32:54 40156 8192000000 10325439824 10768961536 1384945312 14.461 98 25 52 0 1400685920 9933648 5806960
15:34:36 34272 8192000000 10324829888 10789163008 1415932928 14.737 100 28 56 0 1437660736 15678752 6049056
15:36:14 45701 8192000000 10317300080 10768580608 1381037776 14.426 97 33 66 1 1396923872 8662432 7223664
15:38:06 62161 8192000000 10266685568 10711719936 1237628928 13.124 89 17 34 0 1250049616 9057952 3362736
15:40:17 38717 8192000000 10264511808 10691796992 1277974816 13.495 95 27 54 0 1296039728 12310048 5754864
15:41:57 45016 8192000000 10264553936 10704355328 1327588496 13.945 98 22 43 2 1345706256 13293376 4824384
15:43:46 94781 8192000000 10264007840 10704187392 1339603552 14.054 98 51 101 2 1363866448 12876704 11386192
15:46:42 34908 8192000000 10261758576 10708340736 1360120768 14.238 100 38 76 1 1379205328 11005776 8078784
15:50:14 59176 8192000000 10277392880 10731229184 1375373648 14.375 100 9 18 0 1392574496 15241696 1959152
15:54:00 82672 8192000000 10263764128 10700427264 1280367536 13.516 94 12 24 0 1295220416 12250752 2602128
15:56:27 67955 8192000000 10265204784 10709184512 1327602448 13.945 97 27 51 0 1354890016 21454544 5833024
15:58:50 101248 8192000000 10293226864 10729975808 1318943872 13.867 96 12 22 1 1339340080 18020240 2375968
16:01:32 48016 8192000000 10295087600 10757484544 1376304640 14.383 100 33 66 0 1401995008 18726032 6964336
16:03:21 118801 8192000000 10296517728 10759192576 1373283728 14.356 99 19 38 0 1401574400 23781008 4509664
16:06:28 46930 8192000000 10281830720 10751856640 1387679776 14.485 100 17 34 0 1404750720 12983600 4087344
16:08:33 64683 8192000000 10290474256 10760159232 1402444272 14.617 100 9 18 0 1413562848 9131088 1987488
16:10:42 64501 8192000000 10297428080 10760523776 1375295984 14.374 98 8 16 0 1387199120 10274480 1628656
16:12:52 34184 8192000000 10312832864 10763563008 1378435456 14.403 98 20 40 1 1397203568 13778096 4990016
16:14:29 45182 8192000000 10322327504 10774585344 1393693760 14.539 99 19 38 0 1415080976 16867360 4519856
16:16:17 55111 8192000000 10315433136 10782945280 1410626992 14.690 100 11 22 0 1427924368 14842032 2455344
16:18:22 89379 8192000000 10311839472 10769387520 1376455504 14.385 98 15 31 0 1395378480 15239680 3683296
16:20:53 43371 8192000000 10305000368 10773360640 1390022224 14.506 99 21 42 4 1416544160 21384720 5137216
16:22:36 18260 8192000000 10293245680 10738229248 1343644080 14.090 97 25 50 1 1356389728 7536256 5209392
16:23:59 102955 8192000000 10259389728 10715205632 1263020384 13.358 92 13 26 0 1275406672 9898416 2487872
16:28:01 52528 8192000000 10276077680 10735165440 1365183984 14.284 100 22 44 0 1386605296 16816128 4605184
16:29:54 53678 8192000000 10228752240 10685497344 1311053824 13.796 96 12 23 1 1327220656 13312112 2854720
16:32:03 39831 8192000000 10256950800 10713595904 1361537040 14.251 100 17 34 0 1371804000 6894720 3372240
16:33:47 120453 8192000000 10250067664 10704613376 1325161456 13.923 98 21 42 0 1342449328 11673824 5614048
16:36:51 49875 8192000000 10261502624 10715443200 1362133216 14.257 100 30 60 0 1377711744 8831872 6746656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 10-11-2021 16:40:02 to 10-11-2021 16:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-11-2021 16:40:17 to 10-11-2021 16:40:19.

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          243967
MON$OLDEST_ACTIVE               245301
MON$OLDEST_SNAPSHOT             245301
MON$NEXT_TRANSACTION            245301
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                       2023424
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 ---
:70: 
:71: mscs-test-161.budzdorov.ru    Wed Nov 10 16:38:09 2021
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Wed Nov 10 16:38:14 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Wed Nov 10 16:38:18 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:81: 
:82: 
:83: mscs-test-161.budzdorov.ru    Wed Nov 10 16:38:19 2021
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:85: 
:86: 
:87: mscs-test-161.budzdorov.ru    Wed Nov 10 16:38:19 2021
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:89: 
--- end of diff output ---

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

10.11.21 16:40:20. 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-10 16:40:23 No severe PSQL-related problems occured
10.11.21 16:40:24. End of report.