Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.11.2021 22: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 16653176 kB
MemAvailable 31559448 kB
Buffers 0 kB
Cached 15044180 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-28 22:37:58
DTS_END                         2021-11-28 22:37:58.2790
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/56288
TRN_ID                          239571
ATT_ID                          656
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: 9378.79 26056 1125455
customer order: creation 172.91 354 20749
customer order: refuse 14.70 360 1764
order to supplier: creation 45.31 1374 5437
order to supplier: removal 3.57 94925 428
invoice (draft): creation 47.40 3434 5688
invoice (draft): removal 54.56 789 6547
invoice accept: apply 40.94 18928 4913
invoice accept: cancel 49.18 800 5901
customer reserve: creation 3819.50 186 458340
customer reserve: removal 4826.44 54 579173
realization accept: apply 83.77 187 10052
realization accept: cancel 90.69 111 10883
payment from customer: creation 60.53 119 7263
payment from customer: removal 5.56 88 667
payment to supplier: creation 48.13 130 5775
payment to supplier: removal 3.64 102 437
service: total inventory turnovers 4.56 1232 547
service: total monetary turnovers 6.68 26 801
service: aggregate perf. data 0.72 28158 86
service: refresh index statistics 0.03 369753 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-11-2021 22:39:35 to 28-11-2021 22:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2340
TEST_TIME 2 3215
TEST_TIME 3 3464
TEST_TIME 4 4916
TEST_TIME 5 5219
TEST_TIME 6 5465
TEST_TIME 7 5538
TEST_TIME 8 5584
TEST_TIME 9 5611
TEST_TIME 10 5683
TEST_TIME 11 6358
TEST_TIME 12 6180
TEST_TIME 13 6450
TEST_TIME 14 6856
TEST_TIME 15 7036
TEST_TIME 16 7030
TEST_TIME 17 6960
TEST_TIME 18 7143
TEST_TIME 19 7506
TEST_TIME 20 7740
TEST_TIME 21 8035
TEST_TIME 22 8819
TEST_TIME 23 8878
TEST_TIME 24 9213
TEST_TIME 25 9449
TEST_TIME 26 9671
TEST_TIME 27 10208
TEST_TIME 28 10357
TEST_TIME 29 10222
TEST_TIME 30 10169
TEST_TIME 31 10088
TEST_TIME 32 10012
TEST_TIME 33 10108
TEST_TIME 34 9870
TEST_TIME 35 10019
TEST_TIME 36 10069
TEST_TIME 37 10007
TEST_TIME 38 9906
TEST_TIME 39 9890
TEST_TIME 40 9850
TEST_TIME 41 9854
TEST_TIME 42 9769
TEST_TIME 43 9678
TEST_TIME 44 9587
TEST_TIME 45 9545
TEST_TIME 46 9476
TEST_TIME 47 9440
TEST_TIME 48 9379
TEST_TIME 49 9376
TEST_TIME 50 9297
TEST_TIME 51 9354
TEST_TIME 52 9282
TEST_TIME 53 9270
TEST_TIME 54 9267
TEST_TIME 55 9376
TEST_TIME 56 9422
TEST_TIME 57 9562
TEST_TIME 58 9533
TEST_TIME 59 9568
TEST_TIME 60 9544
TEST_TIME 61 9655
TEST_TIME 62 9786
TEST_TIME 63 9852
TEST_TIME 64 9846
TEST_TIME 65 9717
TEST_TIME 66 9576
TEST_TIME 67 9633
TEST_TIME 68 9648
TEST_TIME 69 9629
TEST_TIME 70 9543
TEST_TIME 71 9534
TEST_TIME 72 9527
TEST_TIME 73 9512
TEST_TIME 74 9590
TEST_TIME 75 9578
TEST_TIME 76 9625
TEST_TIME 77 9671
TEST_TIME 78 9655
TEST_TIME 79 9677
TEST_TIME 80 9670
TEST_TIME 81 9785
TEST_TIME 82 9783
TEST_TIME 83 9715
TEST_TIME 84 9641
TEST_TIME 85 9614
TEST_TIME 86 9653
TEST_TIME 87 9584
TEST_TIME 88 9529
TEST_TIME 89 9521
TEST_TIME 90 9537
TEST_TIME 91 9544
TEST_TIME 92 9501
TEST_TIME 93 9533
TEST_TIME 94 9473
TEST_TIME 95 9445
TEST_TIME 96 9471
TEST_TIME 97 9508
TEST_TIME 98 9521
TEST_TIME 99 9490
TEST_TIME 100 9559
TEST_TIME 101 9533
TEST_TIME 102 9561
TEST_TIME 103 9573
TEST_TIME 104 9643
TEST_TIME 105 9700
TEST_TIME 106 9693
TEST_TIME 107 9688
TEST_TIME 108 9703
TEST_TIME 109 9695
TEST_TIME 110 9634
TEST_TIME 111 9588
TEST_TIME 112 9559
TEST_TIME 113 9505
TEST_TIME 114 9505
TEST_TIME 115 9494
TEST_TIME 116 9471
TEST_TIME 117 9434
TEST_TIME 118 9413
TEST_TIME 119 9360
TEST_TIME 120 9384

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-11-2021 22:39:39 to 28-11-2021 22:39:42.

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 1552119 1552119 0 0.00 0 18759 6 0 0
doc_list_biud 1552116 1552116 0 0.00 0 61699 26 0 0
sp_add_invoice_to_stock 5047 4913 134 2.65 36 180502 18929 0 134
sp_cancel_adding_invoice 5901 5901 0 0.00 6 63264 800 0 0
sp_cancel_client_order 1764 1764 0 0.00 4 10175 361 0 0
sp_cancel_customer_reserve 579173 579173 0 0.00 1 61700 54 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 2 7441 89 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 7320 103 0 0
sp_cancel_supplier_invoice 6557 6547 10 0.15 1 36218 790 0 10
sp_cancel_supplier_order 428 428 0 0.00 7 1179243 94926 0 0
sp_cancel_write_off 10883 10883 0 0.00 1 18761 112 0 0
sp_client_order 20749 20749 0 0.00 3 16759 354 0 0
sp_customer_reserve 458340 458340 0 0.00 0 84165 186 0 0
sp_fill_shopping_cart 31868 31868 0 0.00 1 12287 34 0 0
sp_fill_shopping_cart_clo_res 458326 458326 0 0.00 0 18473 14 0 0
sp_get_clo_for_invoice 4899 4899 0 0.00 0 23671 268 0 0
sp_kill_cost_storno 17888 17888 0 0.00 0 17870 56 0 0
sp_kill_qstorno_ret_qs2qd 1176204 1176204 0 0.00 0 61697 30 0 0
sp_kill_qty_storno 619645 619645 0 0.00 0 61699 65 0 0
sp_lock_selected_doc 20801 20801 0 0.00 0 7532 4 0 0
sp_make_cost_storno 27989 27989 0 0.00 0 17388 52 0 0
sp_multiply_rows_for_pdistr 27989 27989 0 0.00 0 8671 30 0 0
sp_multiply_rows_for_qdistr 20749 20749 0 0.00 0 13524 149 0 0
sp_pay_from_customer 7263 7263 0 0.00 4 15603 120 0 0
sp_pay_to_supplier 5775 5775 0 0.00 5 12763 131 0 0
sp_payment_common 13038 13038 0 0.00 1 15508 31 0 0
sp_qd_handle_on_cancel_clo 1764 1764 0 0.00 0 7463 320 0 0
sp_qd_handle_on_invoice_upd_sts 10798 10798 0 0.00 0 24252 342 0 0
sp_qd_handle_on_reserve_upd_sts 10052 10052 0 0.00 0 16776 65 0 0
sp_reserve_write_off 10052 10052 0 0.00 6 17428 187 0 0
sp_supplier_invoice 5688 5688 0 0.00 5 90218 3435 0 0
sp_supplier_order 5437 5437 0 0.00 5 42030 1374 0 0
srv_aggregate_perf_data 86 86 0 0.00 2487 863545 28159 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1835 162637 44741 0 0
srv_find_qd_qs_mism 1078820 1078820 0 0.00 0 18748 5 0 0
srv_make_invnt_saldo 547 547 0 0.00 68 60573 1233 0 0
srv_make_money_saldo 801 801 0 0.00 1 2541 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 165038 515116 369754 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4913 4913 0 0.00 0 13748 194 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 5450 109 0 0
t$perf-norm:sp_cancel_client_order 1764 1764 0 0.00 0 2404 13 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 1263 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 1782 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 407 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1459 23 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 28541 1818 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 3353 12 0 0
t$perf-norm:sp_client_order 20749 20749 0 0.00 0 8075 19 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 672 4 0 0
t$perf-norm:sp_pay_from_customer 7263 7263 0 0.00 0 7073 10 0 0
t$perf-norm:sp_pay_to_supplier 5775 5775 0 0.00 0 12618 19 0 0
t$perf-norm:sp_reserve_write_off 10052 10052 0 0.00 0 6454 12 0 0
t$perf-norm:sp_supplier_invoice 5688 5688 0 0.00 0 10640 27 0 0
t$perf-norm:sp_supplier_order 5437 5437 0 0.00 0 5886 18 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 3372 56 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2052 36 0 0
t$perf-norm:srv_make_invnt_saldo 547 547 0 0.00 0 4011 41 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 1585 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10058 9790 268 2.66 0 3475 19 0 268
v_all_customers 38214 38214 0 0.00 0 27 1 0 0
v_all_suppliers 30498 30498 0 0.00 0 29 1 0 0
v_all_wares 41498 41498 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 7323 20 0 0
v_cancel_client_order 3528 3528 0 0.00 0 9744 11 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 1283 7 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 2138 8 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 7309 16 0 0
v_cancel_supplier_invoice 850 830 20 2.35 0 3523 26 0 20
v_cancel_supplier_order 856 856 0 0.00 0 867 6 0 0
v_cancel_write_off 2004 2004 0 0.00 0 1756 13 0 0
v_max_id_clo_ord 5435 5435 0 0.00 0 1035 2 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 4316 15 0 0
v_max_id_ord_sup 5684 5684 0 0.00 0 4612 4 0 0
v_max_non_paid_invoice 1645 1645 0 0.00 0 2863 4 0 0
v_max_non_paid_realizn 3743 3743 0 0.00 0 1329 4 0 0
v_min_id_clo_ord 5435 5435 0 0.00 0 1460 4 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 3080 6 0 0
v_min_id_ord_sup 5684 5684 0 0.00 0 12282 13 0 0
v_min_non_paid_invoice 5775 5775 0 0.00 0 12632 24 0 0
v_min_non_paid_realizn 7263 7263 0 0.00 0 15502 24 0 0
v_our_firm 10324 10324 0 0.00 0 25 1 0 0
v_reserve_write_off 20104 20104 0 0.00 0 11984 9 0 0
x_lock_depdocs_on_canc_invoice 12033 12033 0 0.00 0 14210 117 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 8765 315 0 0
x_make_qstorno_1000_1200 5437 5437 0 0.00 2 41995 1300 0 0
x_make_qstorno_1200_2000 5688 5688 0 0.00 2 79808 3319 0 0
x_make_qstorno_2100_3300 458340 458340 0 0.00 0 84160 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-11-2021 22:39:43 to 28-11-2021 22:39:47.

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
20:08:02 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:03 17896 8192000000 9889781536 10195943424 970160816 10.588 100 88 176 0 998883264 5574032 23148416
20:10:27 13295 8192000000 9930538640 10250309632 1017061632 11.044 100 89 178 0 1048493136 7613328 23818176
20:11:53 22762 8192000000 9984027136 10316029952 1067946288 11.532 100 87 174 0 1099685120 9776704 21962128
20:13:23 13356 8192000000 10032121936 10379943936 1117739952 12.006 100 78 156 1 1147124992 11134352 18250688
20:14:43 8096 8192000000 10092873936 10450935808 1171701296 12.513 100 83 162 2 1203104352 13007264 18395792
20:15:54 2577 8192000000 10129448736 10498060288 1215626832 12.921 100 81 162 3 1247094672 13092432 18375408
20:16:58 3250 8192000000 10161448480 10532896768 1238719760 13.134 100 92 183 2 1273671632 14767376 20184496
20:18:03 2326 8192000000 10182766560 10567847936 1271676560 13.437 100 86 172 1 1303488448 13007408 18804480
20:19:06 18107 8192000000 10182841232 10585079808 1285948864 13.567 100 46 90 1 1303713200 6348752 11415584
20:20:25 3495 8192000000 10194259664 10600144896 1297642336 13.674 100 44 88 0 1317007984 9858256 9507392
20:21:29 6009 8192000000 10197271840 10609426432 1305388576 13.744 100 24 48 1 1316616352 5733856 5493920
20:22:36 33387 8192000000 10197316992 10619052032 1312535232 13.809 100 29 58 2 1325274944 6163136 6576576
20:24:09 8228 8192000000 10212708096 10630209536 1316800736 13.848 100 21 42 1 1327147616 5591552 4755328
20:25:19 6392 8192000000 10212718816 10632065024 1322368704 13.898 100 18 35 0 1332181008 4941424 4870880
20:26:27 66862 8192000000 10220345808 10631294976 1299764064 13.693 98 31 63 1 1312173280 4630800 7778416
20:28:34 7121 8192000000 10211281648 10622525440 1301600592 13.710 99 34 68 0 1317146304 7984544 7561168
20:29:41 6995 8192000000 10124049168 10521747456 1188585072 12.670 94 17 34 0 1197434512 5205232 3644208
20:30:50 8034 8192000000 10146446048 10547290112 1238931152 13.136 99 28 56 0 1256141872 10635472 6575248
20:31:59 27131 8192000000 10115425744 10517643264 1170236096 12.499 93 32 64 0 1182908656 5254944 7417616
20:33:27 6323 8192000000 10176474000 10579079168 1270603264 13.427 99 32 64 3 1284836992 7435984 6797744
20:34:36 4634 8192000000 10178696832 10588602368 1288406048 13.590 100 26 51 1 1300521552 6054816 6060688
20:35:41 7823 8192000000 10190576656 10604363776 1301918336 13.713 100 18 36 0 1311549232 5609104 4021792
20:36:49 39998 8192000000 10203371840 10621411328 1314916304 13.831 100 29 59 1 1327425760 6450192 6059264
20:38:33 15254 8192000000 10238954928 10647969792 1336450864 14.025 100 52 104 1 1355146256 7648160 11047232
20:39:52 16379 8192000000 10264047552 10677772288 1357241952 14.213 100 59 117 1 1382899936 12209328 13448656
20:41:26 13918 8192000000 10284124896 10702929920 1374071024 14.364 100 45 89 2 1399519328 14886688 10561616
20:42:52 16990 8192000000 10312631728 10729050112 1388505456 14.493 100 69 136 0 1425455408 21541280 15408672
20:44:14 10269 8192000000 10308984608 10733477888 1394153168 14.543 100 48 94 1 1422200112 16743392 11303552
20:45:29 10564 8192000000 10313860784 10739859456 1398885088 14.585 100 49 98 1 1429118080 18396544 11836448
20:46:45 3924 8192000000 10331448688 10752651264 1404465472 14.635 100 54 108 1 1439274000 21567312 13241216
20:47:52 3058 8192000000 10335236528 10759340032 1410882144 14.692 100 63 126 1 1449432128 23662480 14887504
20:49:04 3037 8192000000 10349931424 10776719360 1425758016 14.824 100 72 137 4 1463008992 19714240 17536736
20:50:18 2650 8192000000 10332099248 10751213568 1396210592 14.561 100 58 115 2 1440357264 29716464 14430208
20:51:25 6757 8192000000 10318130336 10730172416 1386787696 14.477 100 67 132 1 1424804640 22005328 16011616
20:52:36 3146 8192000000 10323943264 10740019200 1390405184 14.509 100 63 126 1 1429082544 23209280 15468080
20:53:44 2446 8192000000 10357919168 10770767872 1408966896 14.675 100 73 146 1 1456704240 29850240 17887104
20:54:55 3165 8192000000 10354370160 10767462400 1405515872 14.644 100 85 169 1 1462951040 35313472 22121696
20:56:03 1835 8192000000 10366658080 10774892544 1404540000 14.635 100 87 175 1 1465875200 39843488 21491712
20:57:10 3178 8192000000 10348840480 10764615680 1396405968 14.563 100 66 132 1 1451421504 37532224 17483312
20:58:20 10851 8192000000 10322035840 10749177856 1370415936 14.331 98 39 78 1 1421709696 40006592 11287168
20:59:33 24084 8192000000 10306097984 10740793344 1368909200 14.317 99 31 62 2 1404252624 26268560 9074864
21:01:00 7522 8192000000 10292630832 10715832320 1342186272 14.077 98 35 70 0 1383452240 31827040 9438928
21:02:12 14553 8192000000 10282401936 10724855808 1363530992 14.269 100 19 38 1 1391179408 22151168 5497248
21:03:28 31711 8192000000 10271642720 10714066944 1352116944 14.167 100 11 22 2 1379890976 24495024 3279008
21:05:19 36941 8192000000 10256088832 10700734464 1349011488 14.139 100 8 16 2 1361812688 11026816 1774384
21:06:57 108217 8192000000 10292194720 10731819008 1359789088 14.235 99 19 38 1 1379165680 14948496 4428096
21:09:56 77158 8192000000 10304654352 10751799296 1386242160 14.472 99 24 46 1 1406267632 14363184 5662288
21:12:52 32331 8192000000 10314573296 10761940992 1396807808 14.567 100 42 86 4 1422482448 15874944 9799696
21:14:29 44003 8192000000 10299107792 10739855360 1383334640 14.446 99 25 49 2 1395355456 6684976 5335840
21:16:14 11586 8192000000 10307248432 10752622592 1386635040 14.476 99 45 90 1 1406288048 9663648 9989360
21:17:27 70136 8192000000 10288244272 10754068480 1368662768 14.315 98 43 86 0 1387157840 8564240 9930832
21:19:41 28583 8192000000 10276146208 10735968256 1372824288 14.352 99 11 22 0 1383043984 7691296 2528400
21:21:13 42435 8192000000 10285396624 10747138048 1380272976 14.419 99 20 40 0 1394500704 8899408 5328320
21:22:56 46983 8192000000 10289934800 10754994176 1392636624 14.529 100 13 26 0 1406166128 9979360 3550144
21:24:43 58197 8192000000 10297506496 10759458816 1380938576 14.425 99 36 72 1 1403936944 14374272 8624096
21:27:06 57143 8192000000 10292077680 10760499200 1397960944 14.577 100 10 20 1 1412028080 11608272 2458864
21:29:35 49403 8192000000 10311500000 10779017216 1397652208 14.574 100 18 36 0 1415282320 13362640 4267472
21:31:32 50421 8192000000 10323749200 10787061760 1390208976 14.508 99 31 62 0 1421145392 23786416 7150000
21:33:55 41294 8192000000 10300100096 10756694016 1376286016 14.383 99 29 58 1 1407434512 24293456 6855040
21:36:00 64224 8192000000 10288673824 10737147904 1333891152 14.002 97 27 54 0 1354533488 14286496 6355840
21:38:08 58688 8192000000 10300020528 10765479936 1370286768 14.330 99 23 44 2 1401672592 26665664 4720160
21:40:12 73018 8192000000 10258659744 10708656128 1245314864 13.195 90 18 36 0 1264381232 15355408 3710960
21:42:29 115061 8192000000 10252292240 10704228352 1329646032 13.964 98 31 62 1 1351358080 14346096 7365952
21:45:38 53746 8192000000 10244379280 10697031680 1328408736 13.953 98 4 8 0 1335971808 6915904 647168
21:47:40 19985 8192000000 10283097072 10715656192 1346370272 14.115 98 46 87 2 1365318448 9151552 9796624
21:49:04 64531 8192000000 10266222752 10711289856 1315290688 13.834 97 14 28 0 1332983456 14386224 3306544
21:51:12 37331 8192000000 10238505648 10681663488 1292347168 13.626 96 23 46 0 1309350480 11846032 5157280
21:52:53 64623 8192000000 10259707264 10714316800 1327434176 13.944 98 24 48 1 1352819232 19780240 5604816
21:54:58 162634 8192000000 10281781536 10728542208 1300520928 13.700 95 9 19 0 1314804480 12566688 1716864
21:58:42 91992 8192000000 10274307488 10725777408 1302651936 13.719 95 8 16 0 1318085184 14156384 1276864
22:01:15 90423 8192000000 10263893344 10710585344 1336676416 14.027 98 53 106 2 1358480848 9765824 12038608
22:04:05 47460 8192000000 10278291792 10739924992 1373183664 14.356 100 22 44 0 1392590608 14693808 4713136
22:05:53 63722 8192000000 10293132064 10753671168 1383956256 14.452 100 16 32 0 1403694080 15648880 4088944
22:07:58 66747 8192000000 10301422656 10763292672 1394629296 14.547 100 12 24 1 1412736944 15075280 3032368
22:10:08 52848 8192000000 10295232944 10774126592 1397072768 14.569 100 7 14 1 1416229264 17899184 1257312
22:12:08 55982 8192000000 10306229488 10767663104 1387000096 14.479 99 8 16 2 1399887648 11216128 1671424
22:14:54 52644 8192000000 10290469312 10742693888 1340024560 14.058 96 18 36 0 1355428272 10882416 4521296
22:16:47 39399 8192000000 10302252352 10757799936 1379976704 14.416 99 33 66 0 1402601504 15082880 7541920
22:18:54 77276 8192000000 10319694000 10773094400 1403280256 14.624 100 29 58 0 1422450672 12416624 6753792
22:21:12 66029 8192000000 10319689200 10787090432 1401149776 14.605 100 6 12 0 1421665776 19112336 1403664
22:23:51 127347 8192000000 10312775344 10773979136 1346246032 14.114 96 8 16 0 1362992416 15179616 1566768
22:26:59 96579 8192000000 10290243040 10741366784 1371467232 14.340 99 22 44 0 1386778656 10418400 4893024
22:30:11 19843 8192000000 10256397504 10684530688 1297814448 13.675 95 32 64 0 1313464256 8182688 7467120
22:31:32 55095 8192000000 10263257152 10713358336 1340422160 14.061 98 12 24 0 1353823408 10731760 2669488
22:33:47 54873 8192000000 10248614448 10698317824 1302764864 13.720 95 12 24 0 1317266800 11265728 3236208
22:35:43 86364 8192000000 10258055104 10703261696 1318817600 13.866 97 18 36 0 1334785712 11184960 4783152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-11-2021 22:39:49 to 28-11-2021 22:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-11-2021 22:40:05 to 28-11-2021 22:40:07.

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          238831
MON$OLDEST_ACTIVE               240472
MON$OLDEST_SNAPSHOT             240472
MON$NEXT_TRANSACTION            240472
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                       2044384
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 ---
:508: 
:509: mscs-test-161.budzdorov.ru    Sun Nov 28 22:38:08 2021
:510:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:511: 
:512: 
:513: mscs-test-161.budzdorov.ru    Sun Nov 28 22:38:10 2021
:514:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:515: 
:516: 
:517: mscs-test-161.budzdorov.ru    Sun Nov 28 22:38:11 2021
:518:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:519: 
--- end of diff output ---

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

28.11.21 22:40:08. 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-28 22:40:11 No severe PSQL-related problems occured
28.11.21 22:40:12. End of report.