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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16762724 kB
MemAvailable 31571876 kB
Buffers 0 kB
Cached 14940396 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-27 01:37:56
DTS_END                         2021-11-27 01:37:56.5970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54844
TRN_ID                          234451
ATT_ID                          619
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: 9435.64 24522 1132277
customer order: creation 167.98 372 20158
customer order: refuse 14.07 340 1688
order to supplier: creation 45.62 1276 5474
order to supplier: removal 3.50 99335 420
invoice (draft): creation 45.08 3229 5410
invoice (draft): removal 56.38 730 6766
invoice accept: apply 40.03 17054 4804
invoice accept: cancel 50.82 738 6098
customer reserve: creation 3762.06 164 451447
customer reserve: removal 4944.23 55 593307
realization accept: apply 82.05 199 9846
realization accept: cancel 95.68 119 11482
payment from customer: creation 59.03 124 7083
payment from customer: removal 5.88 82 705
payment to supplier: creation 48.70 155 5844
payment to supplier: removal 3.28 108 393
service: total inventory turnovers 4.46 1076 535
service: total monetary turnovers 5.98 55 717
service: aggregate perf. data 0.80 24545 96
service: refresh index statistics 0.03 340686 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1220
TEST_TIME 2 2525
TEST_TIME 3 2757
TEST_TIME 4 3434
TEST_TIME 5 3535
TEST_TIME 6 5112
TEST_TIME 7 5610
TEST_TIME 8 5614
TEST_TIME 9 5533
TEST_TIME 10 5731
TEST_TIME 11 5919
TEST_TIME 12 5771
TEST_TIME 13 6342
TEST_TIME 14 6579
TEST_TIME 15 6717
TEST_TIME 16 6791
TEST_TIME 17 6881
TEST_TIME 18 7080
TEST_TIME 19 7393
TEST_TIME 20 7653
TEST_TIME 21 7785
TEST_TIME 22 7987
TEST_TIME 23 8149
TEST_TIME 24 8521
TEST_TIME 25 8573
TEST_TIME 26 8850
TEST_TIME 27 9393
TEST_TIME 28 9665
TEST_TIME 29 10428
TEST_TIME 30 10656
TEST_TIME 31 10501
TEST_TIME 32 10370
TEST_TIME 33 10260
TEST_TIME 34 10175
TEST_TIME 35 9998
TEST_TIME 36 10035
TEST_TIME 37 9962
TEST_TIME 38 9804
TEST_TIME 39 9714
TEST_TIME 40 9679
TEST_TIME 41 9856
TEST_TIME 42 9878
TEST_TIME 43 9771
TEST_TIME 44 9705
TEST_TIME 45 9657
TEST_TIME 46 9595
TEST_TIME 47 9490
TEST_TIME 48 9421
TEST_TIME 49 9431
TEST_TIME 50 9332
TEST_TIME 51 9330
TEST_TIME 52 9254
TEST_TIME 53 9275
TEST_TIME 54 9266
TEST_TIME 55 9328
TEST_TIME 56 9366
TEST_TIME 57 9322
TEST_TIME 58 9395
TEST_TIME 59 9472
TEST_TIME 60 9516
TEST_TIME 61 9543
TEST_TIME 62 9554
TEST_TIME 63 9609
TEST_TIME 64 9580
TEST_TIME 65 9598
TEST_TIME 66 9663
TEST_TIME 67 9680
TEST_TIME 68 9570
TEST_TIME 69 9574
TEST_TIME 70 9503
TEST_TIME 71 9521
TEST_TIME 72 9429
TEST_TIME 73 9437
TEST_TIME 74 9394
TEST_TIME 75 9327
TEST_TIME 76 9306
TEST_TIME 77 9363
TEST_TIME 78 9381
TEST_TIME 79 9314
TEST_TIME 80 9345
TEST_TIME 81 9434
TEST_TIME 82 9432
TEST_TIME 83 9501
TEST_TIME 84 9506
TEST_TIME 85 9517
TEST_TIME 86 9455
TEST_TIME 87 9651
TEST_TIME 88 9660
TEST_TIME 89 9614
TEST_TIME 90 9538
TEST_TIME 91 9577
TEST_TIME 92 9540
TEST_TIME 93 9557
TEST_TIME 94 9487
TEST_TIME 95 9452
TEST_TIME 96 9461
TEST_TIME 97 9499
TEST_TIME 98 9487
TEST_TIME 99 9488
TEST_TIME 100 9507
TEST_TIME 101 9530
TEST_TIME 102 9526
TEST_TIME 103 9625
TEST_TIME 104 9576
TEST_TIME 105 9560
TEST_TIME 106 9654
TEST_TIME 107 9648
TEST_TIME 108 9673
TEST_TIME 109 9659
TEST_TIME 110 9705
TEST_TIME 111 9626
TEST_TIME 112 9593
TEST_TIME 113 9590
TEST_TIME 114 9597
TEST_TIME 115 9549
TEST_TIME 116 9513
TEST_TIME 117 9477
TEST_TIME 118 9437
TEST_TIME 119 9423
TEST_TIME 120 9438

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-11-2021 01:40:20 to 27-11-2021 01:40:23.

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 1550147 1550147 0 0.00 0 34982 6 0 0
doc_list_biud 1550146 1550146 0 0.00 0 61492 27 0 0
sp_add_invoice_to_stock 4919 4804 115 2.33 36 163211 17055 0 115
sp_cancel_adding_invoice 6098 6098 0 0.00 4 82499 738 0 0
sp_cancel_client_order 1688 1688 0 0.00 4 10752 340 0 0
sp_cancel_customer_reserve 593307 593307 0 0.00 1 61499 56 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 8674 83 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 13214 109 0 0
sp_cancel_supplier_invoice 6771 6766 5 0.07 1 22759 731 0 5
sp_cancel_supplier_order 420 420 0 0.00 9 1075230 99336 0 0
sp_cancel_write_off 11482 11482 0 0.00 1 18682 119 0 0
sp_client_order 20158 20158 0 0.00 3 54708 372 0 0
sp_customer_reserve 451447 451447 0 0.00 0 77042 165 0 0
sp_fill_shopping_cart 31035 31035 0 0.00 1 14151 35 0 0
sp_fill_shopping_cart_clo_res 451441 451441 0 0.00 0 25473 14 0 0
sp_get_clo_for_invoice 4797 4797 0 0.00 0 27972 311 0 0
sp_kill_cost_storno 18678 18678 0 0.00 0 9679 57 0 0
sp_kill_qstorno_ret_qs2qd 1205282 1205282 0 0.00 0 61486 30 0 0
sp_kill_qty_storno 634404 634404 0 0.00 0 61491 65 0 0
sp_lock_selected_doc 20365 20365 0 0.00 0 10371 10 0 0
sp_make_cost_storno 27570 27570 0 0.00 0 34934 64 0 0
sp_multiply_rows_for_pdistr 27570 27570 0 0.00 0 11086 31 0 0
sp_multiply_rows_for_qdistr 20158 20158 0 0.00 0 54696 181 0 0
sp_pay_from_customer 7083 7083 0 0.00 4 25514 125 0 0
sp_pay_to_supplier 5844 5844 0 0.00 4 33494 155 0 0
sp_payment_common 12927 12927 0 0.00 2 25237 36 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 10746 298 0 0
sp_qd_handle_on_invoice_upd_sts 10895 10895 0 0.00 0 15964 314 0 0
sp_qd_handle_on_reserve_upd_sts 9846 9846 0 0.00 0 12438 55 0 0
sp_reserve_write_off 9846 9846 0 0.00 6 25649 200 0 0
sp_supplier_invoice 5410 5410 0 0.00 6 78371 3229 0 0
sp_supplier_order 5474 5474 0 0.00 5 28412 1276 0 0
srv_aggregate_perf_data 96 96 0 0.00 2359 496264 24545 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1932 150763 51018 0 0
srv_find_qd_qs_mism 1085268 1085268 0 0.00 0 15807 5 0 0
srv_make_invnt_saldo 535 535 0 0.00 32 42695 1077 0 0
srv_make_money_saldo 717 717 0 0.00 0 8382 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 184555 526641 340686 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4804 4804 0 0.00 0 10656 169 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 1 9528 103 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 3990 15 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 1973 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 9538 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 801 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 2872 24 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 35012 1744 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 3504 13 0 0
t$perf-norm:sp_client_order 20158 20158 0 0.00 0 8803 17 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 5971 8 0 0
t$perf-norm:sp_pay_from_customer 7083 7083 0 0.00 0 5104 11 0 0
t$perf-norm:sp_pay_to_supplier 5844 5844 0 0.00 0 3250 10 0 0
t$perf-norm:sp_reserve_write_off 9846 9846 0 0.00 0 5136 12 0 0
t$perf-norm:sp_supplier_invoice 5410 5410 0 0.00 0 4793 18 0 0
t$perf-norm:sp_supplier_order 5474 5474 0 0.00 0 12251 19 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 417 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 1670 29 0 0
t$perf-norm:srv_make_invnt_saldo 535 535 0 0.00 0 2722 26 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 2050 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 214 55 0 0
v_add_invoice_to_stock 9824 9594 230 2.34 0 8132 22 0 230
v_all_customers 37430 37430 0 0.00 0 16 0 0 0
v_all_suppliers 30424 30424 0 0.00 0 16 1 0 0
v_all_wares 40316 40316 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 2556 15 0 0
v_cancel_client_order 3376 3376 0 0.00 0 3734 6 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 2646 7 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 1885 7 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 1944 9 0 0
v_cancel_supplier_invoice 816 806 10 1.22 0 5683 34 0 10
v_cancel_supplier_order 840 840 0 0.00 0 1793 7 0 0
v_cancel_write_off 2000 2000 0 0.00 0 2670 16 0 0
v_max_id_clo_ord 5474 5474 0 0.00 0 342 1 0 0
v_max_id_clo_res 941 941 0 0.00 0 2500 13 0 0
v_max_id_ord_sup 5403 5403 0 0.00 0 6895 6 0 0
v_max_non_paid_invoice 1509 1509 0 0.00 0 2329 4 0 0
v_max_non_paid_realizn 3547 3547 0 0.00 0 1487 4 0 0
v_min_id_clo_ord 5474 5474 0 0.00 0 2727 7 0 0
v_min_id_clo_res 941 941 0 0.00 0 8244 36 0 0
v_min_id_ord_sup 5403 5403 0 0.00 0 5451 7 0 0
v_min_non_paid_invoice 5844 5844 0 0.00 0 9854 27 0 0
v_min_non_paid_realizn 7083 7083 0 0.00 0 8674 26 0 0
v_our_firm 9958 9958 0 0.00 0 13 1 0 0
v_reserve_write_off 19692 19692 0 0.00 0 8636 11 0 0
x_lock_depdocs_on_canc_invoice 12461 12461 0 0.00 0 12954 110 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 9052 350 0 0
x_make_qstorno_1000_1200 5474 5474 0 0.00 1 28404 1201 0 0
x_make_qstorno_1200_2000 5410 5410 0 0.00 2 78351 3110 0 0
x_make_qstorno_2100_3300 451447 451447 0 0.00 0 77040 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-11-2021 01:40:24 to 27-11-2021 01:40:28.

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:00 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:01 30435 8192000000 9907466256 10222628864 985702288 10.740 100 87 173 0 1014386128 5665024 23018816
23:10:41 23384 8192000000 9950446976 10275885056 1033924048 11.206 100 86 171 2 1063916672 7912208 22080416
23:12:10 26155 8192000000 9989451792 10326114304 1070969472 11.561 100 87 174 0 1102635616 10249536 21416608
23:13:44 5983 8192000000 10054587024 10405806080 1141057280 12.225 100 80 160 0 1170355584 10961280 18337024
23:14:55 10212 8192000000 10085389968 10447630336 1181153728 12.601 100 82 163 1 1212481472 12487856 18839888
23:16:08 4124 8192000000 10130555104 10500612096 1218474176 12.948 100 82 162 2 1249642096 13045664 18122256
23:17:14 3450 8192000000 10156060000 10536329216 1253690448 13.272 100 75 150 1 1281798240 11547472 16560320
23:18:20 1517 8192000000 10198792416 10582048768 1284865888 13.557 100 90 180 0 1319044432 14686000 19492544
23:19:23 10489 8192000000 10217543472 10603356160 1299557760 13.691 100 69 138 0 1328032112 13493568 14980784
23:20:34 17275 8192000000 10186893152 10606489600 1310359696 13.789 100 43 87 1 1326817376 5841136 10616544
23:21:52 15842 8192000000 10203604592 10628382720 1321529648 13.891 100 20 40 0 1336378256 10382912 4465696
23:23:08 8860 8192000000 10210030160 10629349376 1326337312 13.934 100 11 22 0 1333928688 5326976 2264400
23:24:20 34648 8192000000 10255441872 10655277056 1335061456 14.013 100 59 119 4 1355880224 6068848 14749920
23:25:55 12020 8192000000 10242148320 10657484800 1340555728 14.062 100 15 30 1 1353243120 9542736 3144656
23:27:08 20537 8192000000 10228579296 10652536832 1328511584 13.954 99 33 66 0 1342811824 7177920 7122320
23:28:35 10287 8192000000 10204223360 10621136896 1302550848 13.718 98 23 46 1 1313197904 5423424 5223632
23:29:47 7292 8192000000 10184817808 10594455552 1281107024 13.523 99 32 64 2 1294915840 5888816 7920000
23:30:55 43816 8192000000 10128913760 10534060032 1141110240 12.226 89 26 52 1 1152444608 5516704 5817664
23:32:41 6376 8192000000 10162892704 10560016384 1252183712 13.258 99 28 55 0 1264551104 5549840 6817552
23:33:48 8220 8192000000 10153248096 10553131008 1258426864 13.316 100 28 56 1 1271158512 6195024 6536624
23:34:56 16352 8192000000 10180552560 10593619968 1293002944 13.632 100 30 60 0 1311125296 11151920 6970432
23:36:14 24979 8192000000 10187159504 10600341504 1294885552 13.649 100 27 54 1 1307006112 6587616 5532944
23:37:44 7043 8192000000 10202548928 10615541760 1310118768 13.787 100 21 42 0 1320527792 5832432 4576592
23:39:02 16489 8192000000 10248261024 10654691328 1342561840 14.081 100 46 92 3 1361330400 8594224 10174336
23:40:35 12582 8192000000 10265669104 10675707904 1356851584 14.209 100 49 98 1 1377849664 10208064 10790016
23:42:07 47802 8192000000 10273985488 10694877184 1364872784 14.281 100 34 65 1 1386633344 13414608 8345952
23:44:04 14647 8192000000 10291280480 10712748032 1378701440 14.405 100 50 99 2 1402490720 12513792 11275488
23:45:25 7416 8192000000 10314163296 10732703744 1393111232 14.534 100 68 134 0 1428222928 20122880 14988816
23:46:40 17146 8192000000 10315807744 10741571584 1400087664 14.596 100 48 94 0 1430438240 19741024 10609552
23:48:05 4574 8192000000 10313613232 10734407680 1386009216 14.470 99 60 119 2 1423786624 22884112 14893296
23:49:17 3449 8192000000 10351253568 10765467648 1409278304 14.678 100 76 152 0 1455493376 28075200 18139872
23:50:25 5794 8192000000 10351925232 10768957440 1411119232 14.694 100 70 138 1 1459073568 31000688 16953648
23:51:35 3967 8192000000 10360730688 10773282816 1411098784 14.694 100 83 166 1 1462192064 31115408 19977872
23:52:41 5466 8192000000 10363197648 10786803712 1408403472 14.670 99 68 132 2 1467494928 41861920 17229536
23:53:54 1929 8192000000 10399434688 10816978944 1438892704 14.940 100 88 172 2 1506155584 45836784 21426096
23:55:04 2429 8192000000 10416238160 10834640896 1445300384 14.996 100 84 168 0 1518412560 51997392 21114784
23:56:14 6984 8192000000 10381812000 10805649408 1412720304 14.708 99 55 111 1 1477937744 51363440 13854000
23:57:24 10341 8192000000 10380225072 10810662912 1405562416 14.644 98 46 92 0 1468025008 49809296 12653296
23:58:44 20771 8192000000 10360965600 10791997440 1396674192 14.565 99 38 76 0 1456837136 49950400 10212544
00:00:08 15325 8192000000 10351397776 10790690816 1400601888 14.600 100 31 61 1 1462047440 53516736 7928816
00:01:25 18110 8192000000 10329369072 10771296256 1383004640 14.443 100 25 49 2 1440325600 50321232 6999728
00:02:43 21246 8192000000 10319896400 10757492736 1368814400 14.316 100 25 50 1 1426519744 50514080 7191264
00:04:37 30612 8192000000 10315822368 10765258752 1367868512 14.308 100 20 40 1 1428126928 53937776 6320640
00:07:26 90672 8192000000 10276725680 10712342528 1304764832 13.739 95 13 26 0 1326150848 18039328 3346688
00:09:59 150762 8192000000 10291474400 10725933056 1339096352 14.049 97 36 71 2 1355816032 8244000 8475680
00:13:36 124109 8192000000 10307521680 10756579328 1382084832 14.435 99 23 46 1 1398988784 12239760 4664192
00:16:41 54113 8192000000 10306213408 10747752448 1374564992 14.368 98 29 58 0 1398289552 16697056 7027504
00:18:41 40909 8192000000 10277311312 10737876992 1337112608 14.031 96 14 28 0 1359119104 18617776 3388720
00:20:31 57692 8192000000 10257479008 10710073344 1351183680 14.158 99 19 37 1 1365045296 9767296 4094320
00:22:29 27874 8192000000 10265159488 10715295744 1367643232 14.306 100 13 26 1 1378953632 8114592 3195808
00:23:57 66549 8192000000 10272702432 10732371968 1379793488 14.415 100 14 28 0 1389839344 6663184 3382672
00:26:17 46062 8192000000 10283039488 10734825472 1382683536 14.441 100 16 32 2 1394134416 7738400 3712480
00:28:26 55477 8192000000 10271178016 10736832512 1368162464 14.311 98 19 38 1 1385873616 13054272 4656880
00:30:52 67780 8192000000 10288227392 10758094848 1385261408 14.464 100 10 20 0 1404066048 15885056 2919584
00:33:00 68442 8192000000 10288228864 10740338688 1338424256 14.043 96 13 26 0 1351986704 10611632 2950816
00:35:09 52433 8192000000 10306128560 10757828608 1378963424 14.407 99 25 50 1 1399692128 15249456 5479248
00:37:02 121547 8192000000 10315234560 10768412672 1394471312 14.546 99 10 20 1 1417568640 21042928 2054400
00:40:07 80123 8192000000 10265325136 10711912448 1276439056 13.480 91 15 30 0 1289569824 9527088 3603680
00:42:27 63587 8192000000 10284109312 10729857024 1303685248 13.729 94 23 46 0 1324104896 15287056 5132592
00:44:34 134870 8192000000 10274251696 10723971072 1322659968 13.901 96 21 42 1 1342372480 14335056 5377456
00:47:57 74653 8192000000 10266134576 10728009728 1352241856 14.168 98 21 42 2 1364979280 7321744 5415680
00:50:11 33029 8192000000 10252571872 10717409280 1358739696 14.226 100 8 16 1 1373398080 12565200 2093184
00:51:45 77959 8192000000 10256123488 10712621056 1348745056 14.136 99 8 16 1 1363762752 13107680 1910016
00:54:12 90932 8192000000 10265321296 10716196864 1320410832 13.880 97 14 28 0 1335338048 11596080 3331136
00:56:52 40958 8192000000 10262671104 10714517504 1339424256 14.052 98 30 60 1 1362456336 16235968 6796112
00:58:34 57481 8192000000 10262899552 10714193920 1327770992 13.947 97 18 36 1 1348580768 16518256 4291520
01:00:39 62794 8192000000 10251669776 10697195520 1300284512 13.698 95 15 29 0 1313568000 10478096 2805392
01:02:42 88024 8192000000 10290836528 10744889344 1342202656 14.077 98 18 36 2 1373214096 26943840 4067600
01:05:13 62483 8192000000 10266657488 10717073408 1339711088 14.055 98 5 10 0 1354069632 13688032 670512
01:07:32 100624 8192000000 10273025152 10723643392 1368608416 14.315 100 22 44 1 1389878944 16407248 4863280
01:10:35 54581 8192000000 10258418720 10721550336 1345889984 14.110 97 11 22 1 1358263984 9726928 2647072
01:12:44 70966 8192000000 10265235296 10731868160 1348715200 14.136 98 13 26 2 1362042128 10288832 3038096
01:14:55 79182 8192000000 10280755872 10731352064 1335558672 14.017 97 18 36 0 1352121888 12865712 3697504
01:17:15 63473 8192000000 10296849744 10744344576 1358664160 14.225 98 20 40 0 1377090576 13742176 4684240
01:19:30 33749 8192000000 10299345328 10748669952 1388415888 14.492 100 33 64 1 1405975408 9319424 8240096
01:21:17 23459 8192000000 10307637040 10748973056 1390701296 14.512 100 49 98 2 1412499392 10493824 11304272
01:22:49 113533 8192000000 10296763776 10753638400 1336018144 14.021 96 11 20 2 1355246112 16704688 2523280
01:25:48 39626 8192000000 10309232160 10754220032 1393808160 14.540 100 30 60 1 1412725904 12742128 6175616
01:27:30 100640 8192000000 10290777392 10747641856 1374257616 14.365 99 11 22 0 1390486848 13980992 2248240
01:30:28 27818 8192000000 10278071152 10738302976 1370158976 14.328 99 9 18 0 1389436608 17097728 2179904
01:31:56 72089 8192000000 10267405488 10728407040 1317928880 13.858 95 18 36 0 1333363040 11717504 3716656
01:34:26 50506 8192000000 10273609568 10728624128 1353976016 14.183 98 12 24 1 1369916080 13274320 2665744
01:36:29 72397 8192000000 10286242592 10737344512 1366622848 14.297 99 17 34 0 1388437760 17987920 3826992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-11-2021 01:40:30 to 27-11-2021 01:40:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 27-11-2021 01:40:46 to 27-11-2021 01:40:47.

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          234254
MON$OLDEST_ACTIVE               235389
MON$OLDEST_SNAPSHOT             235389
MON$NEXT_TRANSACTION            235389
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                       2030560
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:421: 
:422: mscs-test-161.budzdorov.ru    Sat Nov 27 01:38:04 2021
:423:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:424: 
:425: 
:426: mscs-test-161.budzdorov.ru    Sat Nov 27 01:38:05 2021
:427:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:428: 
--- end of diff output ---

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

27.11.21 01:40:49. 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-27 01:40:51 No severe PSQL-related problems occured
27.11.21 01:40:52. End of report.