Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.09.2021 19: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 15798396 kB
MemAvailable 30773992 kB
Buffers 0 kB
Cached 15914588 kB
SwapCached 2944 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-09-30 19:37:53
DTS_END                         2021-09-30 19:37:53.9160
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/50388
TRN_ID                          242319
ATT_ID                          691
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: 9352.90 23416 1122348
customer order: creation 174.78 336 20973
customer order: refuse 14.48 388 1737
order to supplier: creation 46.54 1265 5585
order to supplier: removal 3.56 101218 427
invoice (draft): creation 47.24 3388 5669
invoice (draft): removal 53.91 840 6469
invoice accept: apply 42.56 17734 5107
invoice accept: cancel 48.25 794 5790
customer reserve: creation 3913.19 176 469583
customer reserve: removal 4703.79 59 564455
realization accept: apply 86.34 176 10361
realization accept: cancel 85.73 102 10288
payment from customer: creation 60.83 114 7300
payment from customer: removal 5.79 130 695
payment to supplier: creation 49.54 146 5945
payment to supplier: removal 3.51 73 421
service: total inventory turnovers 5.04 1139 605
service: total monetary turnovers 6.89 32 827
service: aggregate perf. data 0.89 16181 107
service: refresh index statistics 0.03 324029 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-09-2021 19:40:09 to 30-09-2021 19:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2132
TEST_TIME 2 3656
TEST_TIME 3 3971
TEST_TIME 4 5117
TEST_TIME 5 5115
TEST_TIME 6 5022
TEST_TIME 7 5598
TEST_TIME 8 5887
TEST_TIME 9 5865
TEST_TIME 10 6445
TEST_TIME 11 6356
TEST_TIME 12 6754
TEST_TIME 13 6718
TEST_TIME 14 7094
TEST_TIME 15 7145
TEST_TIME 16 7134
TEST_TIME 17 7338
TEST_TIME 18 7298
TEST_TIME 19 7338
TEST_TIME 20 7714
TEST_TIME 21 7644
TEST_TIME 22 7944
TEST_TIME 23 8241
TEST_TIME 24 8607
TEST_TIME 25 9157
TEST_TIME 26 9109
TEST_TIME 27 9558
TEST_TIME 28 10468
TEST_TIME 29 10353
TEST_TIME 30 10511
TEST_TIME 31 10560
TEST_TIME 32 10596
TEST_TIME 33 10390
TEST_TIME 34 10419
TEST_TIME 35 10419
TEST_TIME 36 10342
TEST_TIME 37 10162
TEST_TIME 38 10005
TEST_TIME 39 9868
TEST_TIME 40 9980
TEST_TIME 41 10057
TEST_TIME 42 10133
TEST_TIME 43 10135
TEST_TIME 44 10146
TEST_TIME 45 10173
TEST_TIME 46 10250
TEST_TIME 47 10231
TEST_TIME 48 10122
TEST_TIME 49 9982
TEST_TIME 50 9836
TEST_TIME 51 9857
TEST_TIME 52 9731
TEST_TIME 53 9704
TEST_TIME 54 9731
TEST_TIME 55 9636
TEST_TIME 56 9560
TEST_TIME 57 9647
TEST_TIME 58 9720
TEST_TIME 59 9681
TEST_TIME 60 9624
TEST_TIME 61 9558
TEST_TIME 62 9540
TEST_TIME 63 9486
TEST_TIME 64 9446
TEST_TIME 65 9472
TEST_TIME 66 9600
TEST_TIME 67 9609
TEST_TIME 68 9615
TEST_TIME 69 9613
TEST_TIME 70 9638
TEST_TIME 71 9632
TEST_TIME 72 9708
TEST_TIME 73 9715
TEST_TIME 74 9709
TEST_TIME 75 9761
TEST_TIME 76 9748
TEST_TIME 77 9763
TEST_TIME 78 9760
TEST_TIME 79 9661
TEST_TIME 80 9625
TEST_TIME 81 9638
TEST_TIME 82 9603
TEST_TIME 83 9594
TEST_TIME 84 9562
TEST_TIME 85 9524
TEST_TIME 86 9484
TEST_TIME 87 9455
TEST_TIME 88 9423
TEST_TIME 89 9398
TEST_TIME 90 9416
TEST_TIME 91 9425
TEST_TIME 92 9441
TEST_TIME 93 9433
TEST_TIME 94 9551
TEST_TIME 95 9544
TEST_TIME 96 9521
TEST_TIME 97 9576
TEST_TIME 98 9579
TEST_TIME 99 9628
TEST_TIME 100 9630
TEST_TIME 101 9661
TEST_TIME 102 9684
TEST_TIME 103 9655
TEST_TIME 104 9795
TEST_TIME 105 9820
TEST_TIME 106 9729
TEST_TIME 107 9682
TEST_TIME 108 9645
TEST_TIME 109 9625
TEST_TIME 110 9620
TEST_TIME 111 9590
TEST_TIME 112 9577
TEST_TIME 113 9550
TEST_TIME 114 9507
TEST_TIME 115 9480
TEST_TIME 116 9499
TEST_TIME 117 9456
TEST_TIME 118 9445
TEST_TIME 119 9423
TEST_TIME 120 9360

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-09-2021 19:40:13 to 30-09-2021 19:40:16.

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 1557286 1557286 0 0.00 0 19329 6 0 0
doc_list_biud 1557284 1557284 0 0.00 0 46983 28 0 0
sp_add_invoice_to_stock 5234 5107 127 2.42 39 177742 17735 0 127
sp_cancel_adding_invoice 5790 5790 0 0.00 4 57426 795 0 0
sp_cancel_client_order 1737 1737 0 0.00 5 12419 388 0 0
sp_cancel_customer_reserve 564455 564455 0 0.00 1 30128 60 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 11337 131 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 3548 74 0 0
sp_cancel_supplier_invoice 6475 6469 6 0.09 1 42831 841 0 6
sp_cancel_supplier_order 427 427 0 0.00 6 1064920 101219 0 0
sp_cancel_write_off 10288 10288 0 0.00 1 9844 102 0 0
sp_client_order 20973 20973 0 0.00 3 21599 336 0 0
sp_customer_reserve 469583 469583 0 0.00 0 96353 176 0 0
sp_fill_shopping_cart 32220 32220 0 0.00 1 15606 39 0 0
sp_fill_shopping_cart_clo_res 469568 469568 0 0.00 0 10750 12 0 0
sp_get_clo_for_invoice 5092 5092 0 0.00 0 25982 261 0 0
sp_kill_cost_storno 17194 17194 0 0.00 0 6744 53 0 0
sp_kill_qstorno_ret_qs2qd 1146094 1146094 0 0.00 0 42824 33 0 0
sp_kill_qty_storno 604619 604619 0 0.00 0 46983 71 0 0
sp_lock_selected_doc 21351 21351 0 0.00 0 11330 6 0 0
sp_make_cost_storno 28698 28698 0 0.00 0 19045 47 0 0
sp_multiply_rows_for_pdistr 28698 28698 0 0.00 0 8907 26 0 0
sp_multiply_rows_for_qdistr 20973 20973 0 0.00 0 12885 144 0 0
sp_pay_from_customer 7300 7300 0 0.00 4 11021 114 0 0
sp_pay_to_supplier 5945 5945 0 0.00 5 10303 146 0 0
sp_payment_common 13245 13245 0 0.00 1 9480 27 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 12379 336 0 0
sp_qd_handle_on_invoice_upd_sts 10882 10882 0 0.00 0 46982 343 0 0
sp_qd_handle_on_reserve_upd_sts 10361 10361 0 0.00 0 28197 66 0 0
sp_reserve_write_off 10361 10361 0 0.00 6 28215 176 0 0
sp_supplier_invoice 5669 5669 0 0.00 7 80661 3388 0 0
sp_supplier_order 5585 5585 0 0.00 5 37725 1266 0 0
srv_aggregate_perf_data 107 107 0 0.00 2280 159309 16182 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2890 160099 49728 0 0
srv_find_qd_qs_mism 1073777 1073777 0 0.00 0 15917 5 0 0
srv_make_invnt_saldo 605 605 0 0.00 46 60585 1140 0 0
srv_make_money_saldo 827 827 0 0.00 0 5341 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 203900 410860 324030 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5108 5108 0 0.00 0 14408 156 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 5044 86 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 13971 18 0 0
t$perf-norm:sp_cancel_customer_reserve 733 733 0 0.00 0 3741 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1102 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 533 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 3474 29 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 28699 1641 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 4047 10 0 0
t$perf-norm:sp_client_order 20973 20973 0 0.00 0 14038 19 0 0
t$perf-norm:sp_customer_reserve 1069 1069 0 0.00 0 4107 10 0 0
t$perf-norm:sp_pay_from_customer 7300 7300 0 0.00 0 5540 10 0 0
t$perf-norm:sp_pay_to_supplier 5945 5945 0 0.00 0 12015 16 0 0
t$perf-norm:sp_reserve_write_off 10361 10361 0 0.00 0 8558 12 0 0
t$perf-norm:sp_supplier_invoice 5669 5669 0 0.00 0 11439 14 0 0
t$perf-norm:sp_supplier_order 5585 5585 0 0.00 0 5492 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 700 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 171 4 0 0
t$perf-norm:srv_make_invnt_saldo 605 605 0 0.00 0 1883 33 0 0
t$perf-norm:srv_make_money_saldo 827 827 0 0.00 0 4955 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10430 10176 254 2.43 0 6231 22 0 254
v_all_customers 38300 38300 0 0.00 0 59 1 0 0
v_all_suppliers 31242 31242 0 0.00 0 17 1 0 0
v_all_wares 41942 41942 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1489 11 0 0
v_cancel_client_order 3474 3474 0 0.00 0 5766 12 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 3799 11 0 0
v_cancel_customer_reserve 1466 1466 0 0.00 0 4530 13 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 2107 6 0 0
v_cancel_supplier_invoice 884 872 12 1.35 0 2073 17 0 12
v_cancel_supplier_order 854 854 0 0.00 0 1073 5 0 0
v_cancel_write_off 2086 2086 0 0.00 0 5210 15 0 0
v_max_id_clo_ord 5585 5585 0 0.00 0 1881 1 0 0
v_max_id_clo_res 1069 1069 0 0.00 0 2722 12 0 0
v_max_id_ord_sup 5664 5664 0 0.00 0 2525 2 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 1888 6 0 0
v_max_non_paid_realizn 3895 3895 0 0.00 0 4765 6 0 0
v_min_id_clo_ord 5585 5585 0 0.00 0 1922 5 0 0
v_min_id_clo_res 1069 1069 0 0.00 0 3757 11 0 0
v_min_id_ord_sup 5664 5664 0 0.00 0 4519 7 0 0
v_min_non_paid_invoice 5945 5945 0 0.00 0 6809 16 0 0
v_min_non_paid_realizn 7300 7300 0 0.00 0 3641 12 0 0
v_our_firm 10452 10452 0 0.00 0 59 1 0 0
v_reserve_write_off 20722 20722 0 0.00 0 5981 8 0 0
x_lock_depdocs_on_canc_invoice 11823 11823 0 0.00 0 23317 126 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 4008 294 0 0
x_make_qstorno_1000_1200 5585 5585 0 0.00 1 37713 1180 0 0
x_make_qstorno_1200_2000 5669 5669 0 0.00 3 80648 3249 0 0
x_make_qstorno_2100_3300 469583 469583 0 0.00 0 96352 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-09-2021 19:40:17 to 30-09-2021 19:40:21.

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
17:07:57 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:58 8852 8192000000 10342952992 10659344384 1399784480 14.593 100 85 170 0 1434090960 5413488 28892992
17:10:10 22557 8192000000 10426090272 10755764224 1480179632 15.303 100 92 184 0 1517873584 7581584 30112368
17:11:41 14800 8192000000 10462663904 10804084736 1527210816 15.713 100 88 176 0 1564483056 8982912 28289328
17:13:02 10174 8192000000 10539657344 10892812288 1594537744 16.293 100 77 155 0 1627962864 10149648 23275472
17:14:16 11801 8192000000 10632451040 10994446336 1684837888 17.058 100 85 172 0 1721347904 12059808 24450208
17:15:34 8515 8192000000 10680659536 11054170112 1743070032 17.544 100 82 162 2 1778883408 12825488 22987888
17:16:47 8639 8192000000 10720068976 11108069376 1793497648 17.961 100 67 134 0 1824077696 12554592 18025456
17:17:57 2435 8192000000 10786023488 11173363712 1841447008 18.353 100 89 178 1 1881978736 15940944 24590784
17:19:02 2027 8192000000 10796290688 11187847168 1861447312 18.515 100 81 161 1 1896645840 12723664 22474864
17:20:05 4024 8192000000 10807365296 11208421376 1871933136 18.600 100 62 124 4 1900601792 10964608 17704048
17:21:10 6932 8192000000 10784580192 11212210176 1877824672 18.648 100 26 52 0 1892804448 7429936 7549840
17:22:20 9331 8192000000 10801251920 11229409280 1891405600 18.757 100 19 39 1 1903181360 6108160 5667600
17:23:30 45766 8192000000 10852428352 11251666944 1904240320 18.860 100 58 116 3 1926904416 5292048 17372048
17:25:17 4876 8192000000 10834868992 11261345792 1909027312 18.899 100 34 64 2 1925628704 7062464 9538928
17:26:25 7370 8192000000 10828801936 11263909888 1915224176 18.949 100 22 44 0 1926651632 5119536 6307920
17:27:37 53700 8192000000 10790242416 11220807680 1875517408 18.629 99 34 67 1 1892561280 6602880 10440992
17:29:36 3881 8192000000 10767767152 11183468544 1830403600 18.263 99 36 72 0 1848555168 8095008 10056560
17:30:45 6790 8192000000 10690995280 11105083392 1747566064 17.581 97 27 53 1 1762050896 6227312 8257520
17:31:56 6694 8192000000 10684648704 11103842304 1750538880 17.606 98 30 59 1 1763698064 5009440 8149744
17:33:03 23901 8192000000 10705870896 11127431168 1734086224 17.469 96 16 32 1 1744828832 5310800 5431808
17:34:28 7816 8192000000 10773375440 11183316992 1838505344 18.329 100 32 64 0 1858432512 10739520 9187648
17:35:37 8163 8192000000 10772654800 11200344064 1855688576 18.468 100 16 32 0 1865859520 5651840 4519104
17:36:45 113662 8192000000 10828602928 11250573312 1881140240 18.674 100 33 66 0 1895715680 4937328 9638112
17:39:44 15253 8192000000 10869119264 11290419200 1928043920 19.051 100 48 94 0 1953285232 11356848 13884464
17:41:01 17843 8192000000 10883427056 11316211712 1944198992 19.180 100 35 70 3 1970362432 16377408 9786032
17:42:22 17779 8192000000 10891419488 11322195968 1957685632 19.288 100 52 102 0 1986194608 13820464 14688512
17:43:43 3216 8192000000 10926919200 11345895424 1976547344 19.437 100 72 144 0 2015292640 18011728 20733568
17:45:06 5288 8192000000 10937098480 11360907264 1988467664 19.532 100 65 130 1 2022923456 15617952 18837840
17:46:16 30373 8192000000 10924344352 11357822976 1968509264 19.374 99 47 93 2 2003109328 20246496 14353568
17:47:51 6919 8192000000 10936138528 11373789184 1988586336 19.533 100 45 91 3 2027605600 25080288 13938976
17:49:02 5202 8192000000 10958170288 11389136896 1998962096 19.615 100 65 125 1 2045913424 27415712 19535616
17:50:10 10380 8192000000 10964044032 11385016320 1992998464 19.567 100 69 139 1 2043894880 29776224 21120192
17:51:27 6750 8192000000 10970258976 11395723264 1982482352 19.484 99 70 139 4 2040705632 37030608 21192672
17:52:38 3643 8192000000 10969837648 11391807488 1996464320 19.595 100 79 155 2 2051976864 31777664 23734880
17:53:45 9176 8192000000 10974708896 11411537920 2009012160 19.694 100 56 112 1 2064198480 37561760 17624560
17:55:07 7838 8192000000 10990200512 11418963968 2012297472 19.720 100 68 136 2 2070800384 37353920 21148992
17:56:20 2890 8192000000 10974760384 11402326016 1969489728 19.381 98 75 149 0 2041143440 47375424 24278288
17:57:29 7563 8192000000 10959384384 11389702144 1964046064 19.338 99 46 92 2 2024669584 45408224 15215296
17:58:51 12818 8192000000 10974503568 11409952768 1967223840 19.363 98 52 104 0 2042464464 57552240 17688384
18:00:15 13390 8192000000 10976753120 11412488192 1977529616 19.445 100 53 106 3 2056941408 61123680 18288112
18:01:30 10016 8192000000 10934435632 11385479168 1954841152 19.265 100 35 70 0 2021300208 54859584 11599472
18:02:43 30111 8192000000 10900496112 11350130688 1905208368 18.868 98 24 48 0 1962323200 48551632 8563200
18:04:16 14664 8192000000 10908406736 11360915456 1911106048 18.916 99 38 76 5 1977129728 52699280 13324400
18:05:33 66285 8192000000 10874153072 11334451200 1910054384 18.907 99 19 38 1 1953767264 37366960 6345920
18:07:43 48286 8192000000 10888521840 11361771520 1939550704 19.143 99 19 38 1 1964382976 19323184 5509088
18:10:16 42917 8192000000 10865656688 11304341504 1866717744 18.558 96 34 68 1 1884668256 8204432 9746080
18:12:01 113268 8192000000 10870523680 11340619776 1882206832 18.683 97 16 32 0 1900698944 14200896 4291216
18:15:42 81754 8192000000 10909866880 11354648576 1940223328 19.149 99 31 63 1 1967446672 18212992 9010352
18:18:07 39919 8192000000 10889017648 11348983808 1952023904 19.243 100 11 21 1 1969757024 15111296 2621824
18:19:53 128861 8192000000 10910135280 11376545792 1949438064 19.222 99 10 20 0 1964304544 12929088 1937392
18:23:03 71681 8192000000 10913175472 11372191744 1949460592 19.222 99 9 18 0 1971598688 19508512 2629584
18:25:16 35550 8192000000 10914970512 11370209280 1983271056 19.491 100 31 62 2 1999855728 8314464 8270208
18:27:04 46185 8192000000 10930162624 11371716608 1982654432 19.486 100 38 76 2 2000398640 6771840 10972368
18:28:58 73320 8192000000 10921459760 11384184832 1980223520 19.466 99 57 115 0 2008306448 11517904 16565024
18:31:14 39235 8192000000 10887850416 11368439808 1935570000 19.111 98 7 14 1 1948001856 10377088 2054768
18:33:01 41171 8192000000 10919164752 11371569152 1947808736 19.209 98 42 82 0 1977899824 18063312 12027776
18:34:49 43312 8192000000 10871857552 11366215680 1922519808 19.007 98 11 22 0 1950302800 23979872 3803120
18:36:34 160099 8192000000 10888100256 11361021952 1909797152 18.905 97 8 16 0 1920876528 9222432 1856944
18:40:18 69377 8192000000 10864127136 11342200832 1891943808 18.761 96 16 32 1 1904434432 8542048 3948576
18:42:30 63042 8192000000 10899122352 11381153792 1954181728 19.260 99 13 26 0 1967616496 9918512 3516256
18:45:26 44326 8192000000 10915559152 11360780288 1937687488 19.128 98 36 72 1 1957361120 8538912 11134720
18:48:04 118722 8192000000 10877707440 11358818304 1795654560 17.978 91 12 24 1 1817708144 18282176 3771408
18:51:03 50279 8192000000 10854847520 11314331648 1841837616 18.356 95 18 36 0 1857666768 11153184 4675968
18:52:56 72918 8192000000 10898694192 11352559616 1906011264 18.875 98 26 52 2 1923638592 10931936 6695392
18:55:09 106390 8192000000 10834375888 11288911872 1790336704 17.935 92 23 45 3 1808442928 11810448 6295776
18:57:56 64668 8192000000 10887552784 11350827008 1932202400 19.084 99 19 36 1 1948998704 11800768 4995536
19:00:11 31770 8192000000 10894425552 11345776640 1959518720 19.302 100 39 77 1 1977230880 6774288 10937872
19:01:49 69438 8192000000 10849784640 11328909312 1907068544 18.883 98 11 22 0 1924294608 13365920 3860144
19:03:59 79773 8192000000 10866079968 11349594112 1949136688 19.220 100 17 34 0 1967981296 13732976 5111632
19:06:47 53949 8192000000 10908650048 11379781632 1974973040 19.425 100 21 41 0 1994390464 13337072 6080352
19:08:48 58300 8192000000 10912901904 11387584512 1983878064 19.495 100 26 52 1 2010956240 19584480 7493696
19:10:58 89504 8192000000 10912486128 11372859392 1939437056 19.142 98 12 24 1 1951612960 9054912 3120992
19:13:35 14866 8192000000 10942537152 11403657216 1999955360 19.622 100 44 88 0 2023509680 11648880 11905440
19:14:51 83529 8192000000 10918438064 11402899456 1954761616 19.264 98 30 60 2 1977916832 15042848 8112368
19:17:15 82885 8192000000 10904210480 11380670464 1955038992 19.267 99 13 26 0 1972100224 13445888 3615344
19:19:45 46964 8192000000 10923302400 11395080192 1954261888 19.260 99 13 26 0 1968837008 10702832 3872288
19:21:50 145543 8192000000 10884735776 11353743360 1945293744 19.189 99 9 18 1 1967018432 19206496 2518192
19:25:16 55750 8192000000 10901694240 11375132672 1956491664 19.278 99 23 46 0 1978519712 15656752 6371296
19:27:33 50902 8192000000 10895472032 11371868160 1975081808 19.426 100 13 26 0 1986191520 6853664 4256048
19:29:25 55363 8192000000 10876987936 11346288640 1905580704 18.871 97 8 14 1 1914226816 6382160 2263952
19:31:33 42043 8192000000 10873171296 11345461248 1943015520 19.171 99 21 42 0 1957668176 8614944 6037712
19:33:19 65349 8192000000 10870643440 11331174400 1888657888 18.735 97 13 26 0 1900666832 8299424 3709520
19:35:26 29087 8192000000 10863889856 11336982528 1940064896 19.147 100 25 47 2 1955260768 7967040 7228832
19:37:12 61704 8192000000 9506610656 9831673856 651068512 7.362 34 8 13 0 663406624 9868464 2469648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-09-2021 19:40:23 to 30-09-2021 19:40:38.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-09-2021 19:40:38 to 30-09-2021 19:40:40.

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          242971
MON$OLDEST_ACTIVE               242972
MON$OLDEST_SNAPSHOT             242972
MON$NEXT_TRANSACTION            242972
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                       2060832
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.33505 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33505 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33505 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33505 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Sep 30 19:38:26 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
--- end of diff output ---

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

30.09.21 19:40:41. 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-09-30 19:40:44 No severe PSQL-related problems occured
30.09.21 19:40:45. End of report.