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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16166780 kB
MemAvailable 30911656 kB
Buffers 0 kB
Cached 15489280 kB
SwapCached 1324 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-08-28 01:37:46
DTS_END                         2021-08-28 01:37:46.5370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57428
TRN_ID                          243282
ATT_ID                          697
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: 9525.83 21486 1143100
customer order: creation 180.38 380 21646
customer order: refuse 15.04 362 1805
order to supplier: creation 47.38 1211 5686
order to supplier: removal 3.55 99295 426
invoice (draft): creation 47.44 3302 5693
invoice (draft): removal 53.80 770 6456
invoice accept: apply 43.37 18080 5204
invoice accept: cancel 48.70 862 5844
customer reserve: creation 4001.59 178 480191
customer reserve: removal 4773.82 58 572858
realization accept: apply 85.08 193 10210
realization accept: cancel 91.33 113 10959
payment from customer: creation 61.55 105 7386
payment from customer: removal 5.76 108 691
payment to supplier: creation 51.16 133 6139
payment to supplier: removal 3.62 77 434
service: total inventory turnovers 4.97 1059 596
service: total monetary turnovers 6.48 51 778
service: aggregate perf. data 0.78 20043 94
service: refresh index statistics 0.03 283340 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-08-2021 01:38:46 to 28-08-2021 01:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2391
TEST_TIME 2 4540
TEST_TIME 3 4497
TEST_TIME 4 5836
TEST_TIME 5 5553
TEST_TIME 6 5616
TEST_TIME 7 5418
TEST_TIME 8 5699
TEST_TIME 9 5774
TEST_TIME 10 5841
TEST_TIME 11 6104
TEST_TIME 12 6391
TEST_TIME 13 6452
TEST_TIME 14 6724
TEST_TIME 15 6843
TEST_TIME 16 6686
TEST_TIME 17 7030
TEST_TIME 18 7728
TEST_TIME 19 8293
TEST_TIME 20 8463
TEST_TIME 21 8381
TEST_TIME 22 8654
TEST_TIME 23 9762
TEST_TIME 24 10066
TEST_TIME 25 10209
TEST_TIME 26 10311
TEST_TIME 27 11172
TEST_TIME 28 11056
TEST_TIME 29 10952
TEST_TIME 30 10945
TEST_TIME 31 10684
TEST_TIME 32 10691
TEST_TIME 33 10546
TEST_TIME 34 10504
TEST_TIME 35 10412
TEST_TIME 36 10244
TEST_TIME 37 10254
TEST_TIME 38 10104
TEST_TIME 39 10159
TEST_TIME 40 10275
TEST_TIME 41 10216
TEST_TIME 42 10110
TEST_TIME 43 10192
TEST_TIME 44 10068
TEST_TIME 45 9946
TEST_TIME 46 9901
TEST_TIME 47 9753
TEST_TIME 48 9743
TEST_TIME 49 9699
TEST_TIME 50 9632
TEST_TIME 51 9529
TEST_TIME 52 9470
TEST_TIME 53 9470
TEST_TIME 54 9542
TEST_TIME 55 9554
TEST_TIME 56 9490
TEST_TIME 57 9558
TEST_TIME 58 9680
TEST_TIME 59 9639
TEST_TIME 60 9586
TEST_TIME 61 9889
TEST_TIME 62 10010
TEST_TIME 63 10101
TEST_TIME 64 10126
TEST_TIME 65 10075
TEST_TIME 66 9945
TEST_TIME 67 9877
TEST_TIME 68 9866
TEST_TIME 69 9799
TEST_TIME 70 9787
TEST_TIME 71 9753
TEST_TIME 72 9676
TEST_TIME 73 9665
TEST_TIME 74 9609
TEST_TIME 75 9619
TEST_TIME 76 9620
TEST_TIME 77 9591
TEST_TIME 78 9648
TEST_TIME 79 9695
TEST_TIME 80 9864
TEST_TIME 81 9900
TEST_TIME 82 9842
TEST_TIME 83 9873
TEST_TIME 84 9916
TEST_TIME 85 9905
TEST_TIME 86 9919
TEST_TIME 87 9913
TEST_TIME 88 9817
TEST_TIME 89 9815
TEST_TIME 90 9755
TEST_TIME 91 9734
TEST_TIME 92 9738
TEST_TIME 93 9729
TEST_TIME 94 9677
TEST_TIME 95 9684
TEST_TIME 96 9666
TEST_TIME 97 9704
TEST_TIME 98 9659
TEST_TIME 99 9708
TEST_TIME 100 9716
TEST_TIME 101 9723
TEST_TIME 102 9758
TEST_TIME 103 9737
TEST_TIME 104 9806
TEST_TIME 105 9778
TEST_TIME 106 9786
TEST_TIME 107 9774
TEST_TIME 108 9824
TEST_TIME 109 9741
TEST_TIME 110 9685
TEST_TIME 111 9669
TEST_TIME 112 9654
TEST_TIME 113 9617
TEST_TIME 114 9624
TEST_TIME 115 9586
TEST_TIME 116 9559
TEST_TIME 117 9559
TEST_TIME 118 9540
TEST_TIME 119 9539
TEST_TIME 120 9528

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-08-2021 01:38:50 to 28-08-2021 01:38:53.

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 1587752 1587752 0 0.00 0 18231 6 0 0
doc_list_biud 1587751 1587751 0 0.00 0 78168 27 0 0
sp_add_invoice_to_stock 5357 5204 153 2.85 47 180018 18080 0 153
sp_cancel_adding_invoice 5844 5844 0 0.00 6 78474 863 0 0
sp_cancel_client_order 1805 1805 0 0.00 4 18560 362 0 0
sp_cancel_customer_reserve 572858 572858 0 0.00 1 20152 59 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 6659 108 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 10515 77 0 0
sp_cancel_supplier_invoice 6464 6456 8 0.12 2 78176 771 0 8
sp_cancel_supplier_order 426 426 0 0.00 6 1218364 99296 0 0
sp_cancel_write_off 10959 10959 0 0.00 1 9742 113 0 0
sp_client_order 21646 21646 0 0.00 3 20721 380 0 0
sp_customer_reserve 480191 480191 0 0.00 0 45755 178 0 0
sp_fill_shopping_cart 33023 33023 0 0.00 1 13162 38 0 0
sp_fill_shopping_cart_clo_res 480188 480188 0 0.00 0 16873 14 0 0
sp_get_clo_for_invoice 5197 5197 0 0.00 0 39546 330 0 0
sp_kill_cost_storno 17928 17928 0 0.00 0 9740 59 0 0
sp_kill_qstorno_ret_qs2qd 1163557 1163557 0 0.00 0 78163 32 0 0
sp_kill_qty_storno 613751 613751 0 0.00 0 78166 69 0 0
sp_lock_selected_doc 21376 21376 0 0.00 0 10510 7 0 0
sp_make_cost_storno 28928 28928 0 0.00 0 18229 48 0 0
sp_multiply_rows_for_pdistr 28928 28928 0 0.00 0 7671 32 0 0
sp_multiply_rows_for_qdistr 21646 21646 0 0.00 0 15781 176 0 0
sp_pay_from_customer 7386 7386 0 0.00 4 9273 105 0 0
sp_pay_to_supplier 6139 6139 0 0.00 5 18485 134 0 0
sp_payment_common 13525 13525 0 0.00 1 9267 34 0 0
sp_qd_handle_on_cancel_clo 1805 1805 0 0.00 0 18554 319 0 0
sp_qd_handle_on_invoice_upd_sts 11037 11037 0 0.00 0 12900 330 0 0
sp_qd_handle_on_reserve_upd_sts 10210 10210 0 0.00 0 15373 61 0 0
sp_reserve_write_off 10210 10210 0 0.00 6 15687 193 0 0
sp_supplier_invoice 5693 5693 0 0.00 5 89949 3302 0 0
sp_supplier_order 5686 5686 0 0.00 4 39891 1212 0 0
srv_aggregate_perf_data 95 94 1 1.05 2263 362721 20043 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1640 155136 45436 0 0
srv_find_qd_qs_mism 1093429 1093429 0 0.00 0 14921 6 0 0
srv_make_invnt_saldo 596 596 0 0.00 30 25267 1060 0 0
srv_make_money_saldo 778 778 0 0.00 0 16608 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 218005 387636 283341 0 0
t$perf-abend:sp_add_invoice_to_stock 153 153 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5204 5204 0 0.00 0 14517 189 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 1 4424 85 0 0
t$perf-norm:sp_cancel_client_order 1805 1805 0 0.00 0 10879 19 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 1199 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 1030 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 641 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 446 446 0 0.00 0 716 15 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 25839 1408 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 3346 12 0 0
t$perf-norm:sp_client_order 21646 21646 0 0.00 0 12959 19 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 1519 7 0 0
t$perf-norm:sp_pay_from_customer 7386 7386 0 0.00 0 7679 9 0 0
t$perf-norm:sp_pay_to_supplier 6139 6139 0 0.00 0 7836 12 0 0
t$perf-norm:sp_reserve_write_off 10210 10210 0 0.00 0 6152 10 0 0
t$perf-norm:sp_supplier_invoice 5693 5693 0 0.00 0 6628 23 0 0
t$perf-norm:sp_supplier_order 5686 5686 0 0.00 0 19848 22 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 177 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 3827 92 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 766 18 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 1517 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 13 6 0 0
v_add_invoice_to_stock 10692 10386 306 2.86 0 9177 20 0 306
v_all_customers 40128 40128 0 0.00 0 277 1 0 0
v_all_suppliers 31628 31628 0 0.00 0 275 1 0 0
v_all_wares 43292 43292 0 0.00 0 128 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 2762 10 0 0
v_cancel_client_order 3610 3610 0 0.00 0 7813 7 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 1081 5 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 7858 10 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 622 4 0 0
v_cancel_supplier_invoice 908 892 16 1.76 0 2527 15 0 16
v_cancel_supplier_order 852 852 0 0.00 0 2646 12 0 0
v_cancel_write_off 2106 2106 0 0.00 0 2066 14 0 0
v_max_id_clo_ord 5686 5686 0 0.00 0 342 1 0 0
v_max_id_clo_res 981 981 0 0.00 0 991 8 0 0
v_max_id_ord_sup 5691 5691 0 0.00 0 6156 5 0 0
v_max_non_paid_invoice 1702 1702 0 0.00 0 5444 6 0 0
v_max_non_paid_realizn 3541 3541 0 0.00 0 1580 4 0 0
v_min_id_clo_ord 5686 5686 0 0.00 0 2497 5 0 0
v_min_id_clo_res 981 981 0 0.00 0 7321 28 0 0
v_min_id_ord_sup 5691 5691 0 0.00 0 7564 8 0 0
v_min_non_paid_invoice 6139 6139 0 0.00 0 8361 25 0 0
v_min_non_paid_realizn 7386 7386 0 0.00 0 9171 27 0 0
v_our_firm 10854 10854 0 0.00 0 14 1 0 0
v_reserve_write_off 20420 20420 0 0.00 0 9432 8 0 0
x_lock_depdocs_on_canc_invoice 11854 11854 0 0.00 0 12873 115 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 4304 299 0 0
x_make_qstorno_1000_1200 5686 5686 0 0.00 1 39878 1133 0 0
x_make_qstorno_1200_2000 5693 5693 0 0.00 2 89607 3164 0 0
x_make_qstorno_2100_3300 480191 480191 0 0.00 0 41173 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-08-2021 01:38:54 to 28-08-2021 01:38:58.

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:07:50 3 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:50 24470 8192000000 10381104512 10698469376 1423345840 14.802 100 90 180 0 1459532432 6524048 29662544
23:10:27 20031 8192000000 10431328144 10762633216 1487163280 15.364 100 89 178 1 1525141392 8712544 29265568
23:11:50 12300 8192000000 10484089712 10828455936 1541603312 15.837 100 83 166 1 1577662112 9946000 26112800
23:13:09 13811 8192000000 10570010672 10927923200 1624131696 16.545 100 75 150 1 1658052288 11263504 22657088
23:14:27 7177 8192000000 10626534224 11000700928 1693960640 17.135 100 73 146 0 1727186432 11722864 21502928
23:15:42 4387 8192000000 10695180576 11076464640 1759934448 17.684 100 77 154 2 1795201600 13758496 21508656
23:16:48 9729 8192000000 10753125360 11135234048 1810896128 18.103 100 72 141 0 1842117824 12085152 19136544
23:18:00 2633 8192000000 10798886128 11184402432 1853305600 18.449 100 85 167 3 1889386944 12550352 23530992
23:19:04 1512 8192000000 10827932288 11218616320 1880567024 18.670 100 87 174 1 1920283696 14011520 25705152
23:20:06 4710 8192000000 10803715648 11214499840 1877354192 18.644 100 41 82 1 1897522512 8847616 11320704
23:21:13 8673 8192000000 10793057168 11220377600 1880702976 18.671 100 17 34 0 1891501616 5871968 4926672
23:22:24 56552 8192000000 10808532256 11241357312 1900641904 18.831 100 47 94 2 1921099088 5547344 14909840
23:24:25 7182 8192000000 10815496176 11248787456 1905528112 18.871 100 27 54 1 1920499424 7423520 7547792
23:25:33 6195 8192000000 10828198688 11262672896 1916052352 18.955 100 26 51 4 1929533728 5510368 7971008
23:26:41 63454 8192000000 10798812160 11231068160 1858839392 18.494 97 33 62 1 1873176704 5154400 9182912
23:28:45 6025 8192000000 10787344848 11208859648 1827114000 18.236 97 28 56 1 1845278640 10454832 7709808
23:29:53 8606 8192000000 10691665616 11109539840 1710157760 17.270 95 27 54 2 1723752432 5454000 8140672
23:31:03 10209 8192000000 10705314432 11124973568 1785089824 17.891 99 15 29 1 1794817104 5133920 4593360
23:32:16 46731 8192000000 10740919840 11164057600 1815392416 18.140 99 24 49 0 1827749552 5444240 6912896
23:34:04 6324 8192000000 10742967984 11169705984 1819872528 18.177 99 22 44 0 1832588960 5844848 6871584
23:35:15 5825 8192000000 10770948384 11199385600 1857808928 18.486 100 28 56 2 1871457088 5693792 7954368
23:36:21 12772 8192000000 10780357744 11215142912 1869087248 18.577 100 16 32 1 1878725056 5434192 4203616
23:37:36 29882 8192000000 10818560160 11247091712 1896361008 18.797 100 31 62 1 1915102448 9670912 9070528
23:39:14 29347 8192000000 10862642512 11283902464 1927706288 19.049 100 51 102 1 1954247792 12273216 14268288
23:40:48 14127 8192000000 10884039440 11311542272 1949033360 19.219 100 59 118 1 1981534144 15403056 17097728
23:42:13 2999 8192000000 10927021664 11349061632 1975124304 19.426 100 61 122 1 2013179280 20844512 17210464
23:43:20 8546 8192000000 10924599648 11358543872 1987707424 19.526 100 57 114 1 2024557248 20752336 16097488
23:44:39 7053 8192000000 10940000960 11371323392 1995749712 19.589 100 59 116 0 2035358400 22688592 16920096
23:45:50 14723 8192000000 10959085616 11388669952 2005307856 19.665 100 58 116 0 2049208368 26147808 17752704
23:47:09 3655 8192000000 10949760416 11376066560 1992115072 19.561 99 59 118 0 2040344240 31083328 17145840
23:48:20 2613 8192000000 10968238880 11391332352 1999232816 19.617 100 75 148 0 2057359936 35099104 23028016
23:49:31 3021 8192000000 10965069408 11390865408 1995140224 19.584 100 63 125 1 2050902448 36699328 19062896
23:50:38 3512 8192000000 10976721584 11395575808 1998363728 19.610 100 77 154 0 2063272176 40548688 24359760
23:51:45 2486 8192000000 10950512080 11354710016 1946436336 19.198 97 83 166 1 2012587280 40319840 25831104
23:52:53 10953 8192000000 10946170048 11365351424 1959836352 19.305 100 67 132 2 2025980240 44766368 21377520
23:54:13 2757 8192000000 10958539552 11371057152 1942890336 19.170 99 78 154 0 2028921984 61998096 24033552
23:55:19 1640 8192000000 10974454768 11381821440 1958960576 19.298 100 91 178 4 2047025280 60708000 27356704
23:56:25 9029 8192000000 10928471872 11359121408 1934354608 19.102 98 51 102 1 1996029072 45062608 16611856
23:57:39 9553 8192000000 10939460528 11383472128 1969626592 19.382 100 33 66 0 2027225264 46025088 11573584
23:58:50 3842 8192000000 10950612992 11370119168 1950088576 19.227 100 87 173 4 2033620336 54692608 28839152
23:59:59 13319 8192000000 10966586016 11405082624 1974392112 19.420 100 54 108 3 2051407264 59343296 17671856
00:01:19 33189 8192000000 10914302704 11363926016 1882780320 18.688 96 23 45 2 1935094784 45028480 7285984
00:03:30 52652 8192000000 10881062336 11344408576 1927104480 19.044 100 14 28 0 1970036384 37572848 5359056
00:05:24 57739 8192000000 10885728064 11330699264 1937748624 19.129 100 27 54 2 1957333152 11958576 7625952
00:07:22 126935 8192000000 10905795376 11352379392 1951444240 19.238 99 30 60 3 1971573104 11738992 8389872
00:10:40 86750 8192000000 10909019440 11372081152 1970289024 19.388 100 31 62 0 2000340352 21612320 8439008
00:13:11 18646 8192000000 10915020432 11372527616 1973276144 19.411 100 19 37 3 1999980368 21403216 5301008
00:14:30 28710 8192000000 10900852048 11346493440 1944403696 19.182 99 26 52 3 1966755456 15084096 7267664
00:16:00 33731 8192000000 10938107840 11392671744 1994698304 19.581 100 43 87 1 2028095664 20837968 12559392
00:17:35 18146 8192000000 10921210288 11379961856 1987861936 19.527 100 11 22 1 2007143984 15809568 3472480
00:19:10 45129 8192000000 10913197648 11383492608 1987873920 19.527 100 18 36 1 2005750144 12706336 5169888
00:20:55 52065 8192000000 10887644176 11365482496 1948427552 19.214 98 18 36 0 1960486192 7429504 4629136
00:22:50 47831 8192000000 10896356208 11372916736 1971278848 19.396 100 16 32 0 1984672176 8885872 4507456
00:24:41 67293 8192000000 10879283328 11346333696 1960386128 19.309 99 24 47 1 1977910496 10887632 6636736
00:26:52 66262 8192000000 10878928368 11353464832 1925281744 19.029 97 22 43 1 1938336768 6959216 6095808
00:29:00 57290 8192000000 10905180288 11378769920 1965106720 19.347 99 7 14 0 1974170304 7320672 1742912
00:30:57 24534 8192000000 10908551456 11375140864 1962173040 19.323 99 29 58 2 1982690112 11683744 8833328
00:32:26 29491 8192000000 10895130000 11367550976 1946167696 19.196 98 25 50 1 1964334976 10826240 7341040
00:33:56 54957 8192000000 10884791392 11356491776 1880383648 18.668 95 21 42 0 1906028576 19299872 6345056
00:35:56 49870 8192000000 10864800480 11329044480 1832772832 18.282 94 13 26 0 1857407616 21245424 3389360
00:38:04 60727 8192000000 10907782704 11403571200 1957846432 19.289 100 12 24 1 1999587456 38053952 3687072
00:40:15 39336 8192000000 10859561024 11326095360 1891614032 18.759 97 11 22 2 1908430704 13034800 3781872
00:41:54 116501 8192000000 10890977264 11340341248 1914653664 18.944 98 21 42 1 1935296368 14603024 6039680
00:45:06 40403 8192000000 10886434288 11347472384 1933944736 19.098 99 36 72 2 1956161616 12058480 10158400
00:46:50 67093 8192000000 10866144992 11347910656 1947193648 19.204 100 18 36 1 1963622960 11071280 5358032
00:49:10 64563 8192000000 10832269648 11299516416 1858999184 18.495 96 19 38 1 1875505408 10628064 5878160
00:51:57 51448 8192000000 10867667040 11349299200 1918815680 18.977 99 9 17 1 1939293632 18287280 2190672
00:53:49 74965 8192000000 10908269344 11385528320 1957624432 19.287 100 17 34 1 1978684128 16063952 4995744
00:56:15 61628 8192000000 10872040208 11351085056 1901336688 18.837 98 9 18 0 1930001632 25821728 2843216
00:58:24 73611 8192000000 10877665200 11341635584 1942400816 19.166 100 17 34 2 1959906640 12472560 5033264
01:00:39 92272 8192000000 10899730640 11367862272 1956702592 19.280 100 21 42 0 1975548048 13062544 5782912
01:03:16 61827 8192000000 10896396448 11363713024 1928569664 19.055 98 21 42 1 1944093200 9721168 5802368
01:05:22 155136 8192000000 10914915696 11382124544 1960542208 19.310 99 34 68 2 1978343024 8252704 9548112
01:09:04 57711 8192000000 10904304480 11398201344 1988674128 19.533 100 10 19 0 2003574416 11846144 3054144
01:11:05 100646 8192000000 10895977216 11375271936 1905539808 18.871 95 10 20 0 1915845136 7661824 2643504
01:13:46 73740 8192000000 10916299632 11388424192 1968954080 19.377 99 17 34 0 1982354080 8643856 4756144
01:16:00 70986 8192000000 10919640752 11405131776 1951118432 19.235 98 17 34 1 1967951424 12456912 4376080
01:18:12 22299 8192000000 10921362640 11385135104 1965698512 19.351 99 34 67 0 1986701888 11582672 9420704
01:19:45 25960 8192000000 10944385168 11399708672 1993735536 19.573 100 28 56 2 2014992896 13504736 7752624
01:21:15 99138 8192000000 10910766448 11382595584 1932222368 19.085 97 16 32 1 1954053120 17812000 4018752
01:23:54 67742 8192000000 10890950208 11358740480 1908204928 18.892 96 15 30 2 1921992256 9538448 4248880
01:26:09 84690 8192000000 10869530784 11333734400 1922928896 19.010 99 22 44 1 1939621424 10780752 5911776
01:28:35 48829 8192000000 10868032384 11322900480 1872766256 18.607 95 38 76 1 1894717760 11288624 10662880
01:30:43 53291 8192000000 10843446320 11326447616 1895159584 18.787 98 10 20 0 1910343680 12312720 2871376
01:32:39 86539 8192000000 10869734112 11346247680 1932494112 19.087 99 21 42 1 1946254912 7435312 6325488
01:35:09 34454 8192000000 10876104176 11350470656 1954313376 19.261 100 24 48 0 1973035968 11891568 6831024
01:37:04 43393 8192000000 10175171936 10581688320 1225532208 13.013 63 14 26 1 1243087680 14566016 2989456

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-08-2021 01:39:00 to 28-08-2021 01:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 153 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-08-2021 01:39:16 to 28-08-2021 01:39:18.

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          241733
MON$OLDEST_ACTIVE               243491
MON$OLDEST_SNAPSHOT             243491
MON$NEXT_TRANSACTION            243491
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                       2030048
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:625: 
:626: mscs-test-161.budzdorov.ru    Sat Aug 28 01:37:52 2021
:627:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:628: 
--- end of diff output ---

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

28.08.21 01:39:19. 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-08-28 01:39:21 No severe PSQL-related problems occured
28.08.21 01:39:23. End of report.