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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15302084 kB
MemAvailable 30524500 kB
Buffers 0 kB
Cached 16435160 kB
SwapCached 2160 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.11
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 2022-10-16 01:38:29
DTS_END                         2022-10-16 01:38:29.7500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34314
TRN_ID                          247571
ATT_ID                          736
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: 9439.14 18971 1132697
customer order: creation 178.08 369 21370
customer order: refuse 15.19 339 1823
order to supplier: creation 47.08 1319 5650
order to supplier: removal 3.70 87382 444
invoice (draft): creation 46.47 3214 5576
invoice (draft): removal 53.78 728 6453
invoice accept: apply 41.99 18254 5039
invoice accept: cancel 48.27 738 5792
customer reserve: creation 3937.85 179 472542
customer reserve: removal 4764.88 53 571785
realization accept: apply 85.98 210 10318
realization accept: cancel 83.57 104 10028
payment from customer: creation 60.57 141 7268
payment from customer: removal 6.08 75 730
payment to supplier: creation 50.15 148 6018
payment to supplier: removal 3.55 86 426
service: total inventory turnovers 4.63 1135 556
service: total monetary turnovers 6.43 61 772
service: aggregate perf. data 0.86 21645 103
service: refresh index statistics 0.03 243234 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-10-2022 01:39:56 to 16-10-2022 01:39:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4129
TEST_TIME 2 3330
TEST_TIME 3 4323
TEST_TIME 4 4622
TEST_TIME 5 4422
TEST_TIME 6 4634
TEST_TIME 7 5094
TEST_TIME 8 5452
TEST_TIME 9 5824
TEST_TIME 10 6270
TEST_TIME 11 6496
TEST_TIME 12 6432
TEST_TIME 13 6385
TEST_TIME 14 6448
TEST_TIME 15 6644
TEST_TIME 16 6687
TEST_TIME 17 7025
TEST_TIME 18 7285
TEST_TIME 19 7791
TEST_TIME 20 7916
TEST_TIME 21 8662
TEST_TIME 22 8690
TEST_TIME 23 9013
TEST_TIME 24 9574
TEST_TIME 25 9655
TEST_TIME 26 9948
TEST_TIME 27 10409
TEST_TIME 28 10340
TEST_TIME 29 10408
TEST_TIME 30 10455
TEST_TIME 31 10344
TEST_TIME 32 10317
TEST_TIME 33 10399
TEST_TIME 34 10370
TEST_TIME 35 10339
TEST_TIME 36 10213
TEST_TIME 37 10070
TEST_TIME 38 10079
TEST_TIME 39 10052
TEST_TIME 40 9924
TEST_TIME 41 9836
TEST_TIME 42 9788
TEST_TIME 43 9735
TEST_TIME 44 9745
TEST_TIME 45 9790
TEST_TIME 46 9718
TEST_TIME 47 9810
TEST_TIME 48 9781
TEST_TIME 49 9775
TEST_TIME 50 9829
TEST_TIME 51 9802
TEST_TIME 52 9905
TEST_TIME 53 9871
TEST_TIME 54 9816
TEST_TIME 55 9891
TEST_TIME 56 9924
TEST_TIME 57 9991
TEST_TIME 58 9906
TEST_TIME 59 10034
TEST_TIME 60 10005
TEST_TIME 61 10090
TEST_TIME 62 10058
TEST_TIME 63 10095
TEST_TIME 64 9993
TEST_TIME 65 9920
TEST_TIME 66 9958
TEST_TIME 67 9884
TEST_TIME 68 9844
TEST_TIME 69 9786
TEST_TIME 70 9786
TEST_TIME 71 9824
TEST_TIME 72 9742
TEST_TIME 73 9698
TEST_TIME 74 9662
TEST_TIME 75 9699
TEST_TIME 76 9628
TEST_TIME 77 9622
TEST_TIME 78 9668
TEST_TIME 79 9657
TEST_TIME 80 9655
TEST_TIME 81 9699
TEST_TIME 82 9661
TEST_TIME 83 9743
TEST_TIME 84 9733
TEST_TIME 85 9698
TEST_TIME 86 9721
TEST_TIME 87 9799
TEST_TIME 88 9783
TEST_TIME 89 9752
TEST_TIME 90 9666
TEST_TIME 91 9682
TEST_TIME 92 9614
TEST_TIME 93 9575
TEST_TIME 94 9528
TEST_TIME 95 9513
TEST_TIME 96 9554
TEST_TIME 97 9614
TEST_TIME 98 9598
TEST_TIME 99 9580
TEST_TIME 100 9612
TEST_TIME 101 9646
TEST_TIME 102 9634
TEST_TIME 103 9644
TEST_TIME 104 9656
TEST_TIME 105 9629
TEST_TIME 106 9714
TEST_TIME 107 9725
TEST_TIME 108 9732
TEST_TIME 109 9724
TEST_TIME 110 9651
TEST_TIME 111 9613
TEST_TIME 112 9573
TEST_TIME 113 9557
TEST_TIME 114 9570
TEST_TIME 115 9527
TEST_TIME 116 9487
TEST_TIME 117 9473
TEST_TIME 118 9416
TEST_TIME 119 9454
TEST_TIME 120 9443

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-10-2022 01:40:00 to 16-10-2022 01:40:03.

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 1570720 1570720 0 0.00 0 23979 7 0 0
doc_list_biud 1570719 1570719 0 0.00 0 25738 25 0 0
sp_add_invoice_to_stock 5142 5039 103 2.00 57 192813 18255 0 103
sp_cancel_adding_invoice 5792 5792 0 0.00 4 74533 739 0 0
sp_cancel_client_order 1823 1823 0 0.00 4 17376 340 0 0
sp_cancel_customer_reserve 571785 571785 0 0.00 0 25755 54 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 2 6792 76 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 5537 86 0 0
sp_cancel_supplier_invoice 6458 6453 5 0.07 1 15635 728 0 5
sp_cancel_supplier_order 444 444 0 0.00 7 960763 87382 0 0
sp_cancel_write_off 10028 10028 0 0.00 1 14487 105 0 0
sp_client_order 21370 21370 0 0.00 3 39989 370 0 0
sp_customer_reserve 472542 472542 0 0.00 0 96008 179 0 0
sp_fill_shopping_cart 32592 32592 0 0.00 1 20831 37 0 0
sp_fill_shopping_cart_clo_res 472538 472538 0 0.00 0 13153 13 0 0
sp_get_clo_for_invoice 5035 5035 0 0.00 0 21960 259 0 0
sp_kill_cost_storno 16976 16976 0 0.00 0 15073 53 0 0
sp_kill_qstorno_ret_qs2qd 1160495 1160495 0 0.00 0 25734 29 0 0
sp_kill_qty_storno 611678 611678 0 0.00 0 25738 63 0 0
sp_lock_selected_doc 21328 21328 0 0.00 0 10579 9 0 0
sp_make_cost_storno 28639 28639 0 0.00 0 14338 64 0 0
sp_multiply_rows_for_pdistr 28639 28639 0 0.00 0 18475 32 0 0
sp_multiply_rows_for_qdistr 21370 21370 0 0.00 0 39982 159 0 0
sp_pay_from_customer 7268 7268 0 0.00 3 19290 142 0 0
sp_pay_to_supplier 6018 6018 0 0.00 4 19302 148 0 0
sp_payment_common 13286 13286 0 0.00 1 19272 49 0 0
sp_qd_handle_on_cancel_clo 1823 1823 0 0.00 0 17371 309 0 0
sp_qd_handle_on_invoice_upd_sts 10827 10827 0 0.00 0 15116 299 0 0
sp_qd_handle_on_reserve_upd_sts 10318 10318 0 0.00 0 11819 67 0 0
sp_reserve_write_off 10318 10318 0 0.00 6 18770 211 0 0
sp_supplier_invoice 5576 5576 0 0.00 7 65977 3215 0 0
sp_supplier_order 5650 5650 0 0.00 5 37523 1320 0 0
srv_aggregate_perf_data 104 103 1 0.96 2771 292774 21646 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2686 106257 42968 0 0
srv_find_qd_qs_mism 1083981 1083981 0 0.00 0 23978 6 0 0
srv_make_invnt_saldo 556 556 0 0.00 47 22722 1136 0 0
srv_make_money_saldo 772 772 0 0.00 1 14693 62 0 0
srv_recalc_idx_stat 4 4 0 0.00 114359 410061 243235 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5039 5039 0 0.00 0 13922 177 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 3518 72 0 0
t$perf-norm:sp_cancel_client_order 1823 1823 0 0.00 0 2570 14 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 772 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 7645 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 1250 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 6309 25 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 26456 1464 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 2441 13 0 0
t$perf-norm:sp_client_order 21370 21370 0 0.00 0 8036 16 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 6436 11 0 0
t$perf-norm:sp_pay_from_customer 7268 7268 0 0.00 0 7240 12 0 0
t$perf-norm:sp_pay_to_supplier 6018 6018 0 0.00 0 8698 12 0 0
t$perf-norm:sp_reserve_write_off 10318 10318 0 0.00 0 6400 11 0 0
t$perf-norm:sp_supplier_invoice 5576 5576 0 0.00 0 5955 17 0 0
t$perf-norm:sp_supplier_order 5650 5650 0 0.00 0 14027 21 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 265 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 426 7 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 735 15 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 4124 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10276 10070 206 2.00 0 7795 17 0 206
v_all_customers 38946 38946 0 0.00 0 20 1 0 0
v_all_suppliers 31078 31078 0 0.00 0 17 1 0 0
v_all_wares 42740 42740 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 2559 10 0 0
v_cancel_client_order 3646 3646 0 0.00 0 3144 6 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 1898 5 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 2252 9 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 2825 7 0 0
v_cancel_supplier_invoice 828 818 10 1.20 0 9787 41 0 10
v_cancel_supplier_order 888 888 0 0.00 0 501 4 0 0
v_cancel_write_off 2030 2030 0 0.00 0 4497 15 0 0
v_max_id_clo_ord 5650 5650 0 0.00 0 1949 2 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 3933 14 0 0
v_max_id_ord_sup 5572 5572 0 0.00 0 6449 5 0 0
v_max_non_paid_invoice 1701 1701 0 0.00 0 6219 12 0 0
v_max_non_paid_realizn 3839 3839 0 0.00 0 13192 10 0 0
v_min_id_clo_ord 5650 5650 0 0.00 0 8681 9 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 1180 5 0 0
v_min_id_ord_sup 5572 5572 0 0.00 0 9536 10 0 0
v_min_non_paid_invoice 6018 6018 0 0.00 0 18636 28 0 0
v_min_non_paid_realizn 7268 7268 0 0.00 0 15123 43 0 0
v_our_firm 10652 10652 0 0.00 0 17 1 0 0
v_reserve_write_off 20632 20632 0 0.00 0 3783 6 0 0
x_lock_depdocs_on_canc_invoice 11836 11836 0 0.00 0 16828 122 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 8672 317 0 0
x_make_qstorno_1000_1200 5650 5650 0 0.00 1 37495 1242 0 0
x_make_qstorno_1200_2000 5576 5576 0 0.00 2 63413 3079 0 0
x_make_qstorno_2100_3300 472542 472542 0 0.00 0 96006 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2022 01:40:05 to 16-10-2022 01:40:09.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:33 2 8192000000 8799024576 8810303488 5311392 0.064 2 1 2 0 5410992 56816 42784
23:09:34 15846 8192000000 9886132704 10191949824 974944048 10.635 100 81 162 0 1001207392 5352672 20910672
23:10:53 19956 8192000000 9940661328 10258706432 1019899232 11.071 100 87 173 0 1050485184 8566704 22019248
23:12:21 18832 8192000000 9971385328 10300739584 1054588672 11.405 100 83 166 0 1084802656 9502752 20711232
23:13:45 9669 8192000000 10027428208 10374012928 1116003600 11.989 100 81 160 1 1145381696 10470768 18907328
23:14:57 6234 8192000000 10079444064 10437054464 1165455680 12.454 100 75 150 0 1195094336 12215696 17422960
23:16:05 3140 8192000000 10125034096 10493132800 1209294560 12.863 100 75 150 1 1237793456 11960096 16538800
23:17:10 1584 8192000000 10162946400 10533834752 1246446000 13.206 100 89 178 1 1280564032 14262352 19855680
23:18:13 1615 8192000000 10182578560 10559934464 1269974560 13.421 100 90 180 2 1304431088 13917712 20538816
23:19:16 1193 8192000000 10201405136 10588262400 1288637008 13.592 100 88 176 1 1320851328 12134272 20080048
23:20:19 19337 8192000000 10189656992 10600714240 1298773584 13.684 100 39 78 1 1315825552 8096816 8955152
23:21:40 8835 8192000000 10204137376 10618761216 1315894816 13.840 100 19 38 0 1327133568 7237760 4000992
23:22:50 14986 8192000000 10206754576 10627989504 1324896736 13.921 100 15 30 0 1333982272 5654864 3430672
23:24:05 16237 8192000000 10219664224 10637078528 1333517920 13.999 100 22 43 1 1343651488 5813424 4320144
23:25:45 4659 8192000000 10236000480 10654388224 1342278208 14.078 100 29 56 0 1353803824 5129248 6396368
23:26:51 10690 8192000000 10231138192 10648633344 1326625520 13.937 99 26 52 1 1339592240 6688768 6277952
23:28:02 8279 8192000000 10197845184 10612572160 1300558000 13.700 99 24 46 0 1312025680 5706352 5761328
23:29:13 74110 8192000000 10149756784 10556526592 1156399216 12.370 90 21 42 0 1165288528 3997296 4892016
23:31:27 4636 8192000000 10143126240 10537758720 1217841056 12.942 97 43 85 0 1235063568 7552288 9670224
23:32:32 3722 8192000000 10147571504 10548510720 1245911760 13.201 99 29 57 1 1257643216 5052992 6678464
23:33:37 7657 8192000000 10170797136 10579369984 1272896496 13.448 99 25 50 0 1283735856 5160768 5678592
23:34:45 53677 8192000000 10189388912 10599337984 1294710976 13.647 100 26 53 1 1305874832 4865600 6298256
23:36:39 9361 8192000000 10199624960 10610745344 1301946304 13.713 100 26 52 0 1314677616 7021584 5709728
23:37:49 9013 8192000000 10203468976 10617901056 1310615440 13.792 100 15 30 1 1319604592 4832464 4156688
23:39:05 10124 8192000000 10235694144 10646499328 1324788112 13.920 99 51 99 1 1345182416 8967904 11426400
23:40:20 67837 8192000000 10253544064 10675335168 1350730496 14.154 100 42 84 0 1372535456 12124992 9679968
23:42:32 3446 8192000000 10277819888 10698063872 1370935648 14.335 100 51 100 1 1397728000 14741856 12050496
23:43:39 27312 8192000000 10293137376 10717192192 1377955664 14.398 100 38 74 1 1405751120 19326608 8468848
23:45:13 12417 8192000000 10300499808 10727202816 1379808704 14.415 99 40 81 0 1411027200 21400880 9817616
23:46:31 17008 8192000000 10326270960 10747752448 1394452400 14.546 100 57 114 1 1438129232 30286912 13389920
23:48:01 6564 8192000000 10330333120 10758832128 1403247136 14.624 100 47 94 1 1442057136 27484608 11325392
23:49:12 7997 8192000000 10325167728 10753847296 1398167952 14.579 100 45 90 0 1438877360 29273296 11436112
23:50:23 6356 8192000000 10309775408 10730307584 1372510848 14.350 99 48 95 1 1408306144 24759792 11035504
23:51:34 7577 8192000000 10304597520 10721480704 1360234016 14.239 98 57 112 0 1405778592 32022416 13522160
23:52:53 2743 8192000000 10307007456 10716491776 1362903616 14.263 100 67 133 0 1415069424 36203008 15962800
23:54:01 6604 8192000000 10310237664 10715222016 1355523392 14.197 99 67 131 0 1407589696 36555872 15510432
23:55:16 2686 8192000000 10336612576 10739752960 1379628800 14.413 100 81 159 0 1443371744 43732928 20010016
23:56:23 5043 8192000000 10334345696 10744184832 1380693280 14.423 100 60 121 3 1443009760 47073072 15243408
23:57:33 12787 8192000000 10344610576 10765369344 1388422240 14.492 100 58 113 2 1456592240 52653120 15516880
23:58:53 16353 8192000000 10343207440 10765807616 1382686000 14.441 100 41 81 0 1448741808 54799600 11256208
00:00:11 16636 8192000000 10335202064 10769813504 1396321152 14.562 100 39 78 0 1448139376 41877616 9940608
00:01:31 16857 8192000000 10306527376 10736783360 1345280208 14.105 98 28 56 0 1402148032 48571152 8296672
00:02:50 23621 8192000000 10281443168 10720120832 1344381680 14.097 99 20 39 0 1385154192 35247808 5524704
00:04:24 18838 8192000000 10292568480 10732707840 1355779648 14.199 100 13 26 1 1391740336 32121840 3838848
00:05:56 36595 8192000000 10272959808 10712854528 1325002608 13.922 97 12 23 0 1342902416 15323616 2576192
00:07:58 73573 8192000000 10302082208 10738049024 1365522416 14.287 99 18 36 0 1378747488 9451728 3773344
00:10:14 106256 8192000000 10298997136 10744455168 1362156656 14.257 98 17 32 0 1374513520 9170176 3186688
00:14:08 71396 8192000000 10311079856 10760105984 1395281264 14.553 100 27 54 0 1418503936 17176304 6046368
00:16:31 15674 8192000000 10308104464 10764185600 1404666352 14.637 100 35 66 1 1418985344 6654448 7664544
00:18:03 84368 8192000000 10329859888 10778697728 1410919952 14.692 100 27 53 0 1426862112 9734880 6207280
00:20:30 29907 8192000000 10321297248 10769444864 1396633840 14.565 99 29 52 1 1416292560 13352528 6306192
00:22:04 25056 8192000000 10316703280 10768572416 1393863696 14.540 99 34 67 0 1419542944 17396480 8282768
00:23:34 42603 8192000000 10310687264 10767429632 1392335936 14.527 99 17 34 0 1412078496 15820352 3922208
00:25:29 80110 8192000000 10328413504 10792415232 1417482512 14.750 100 24 47 0 1438599088 15126176 5990400
00:27:49 38296 8192000000 10314598640 10768723968 1374608896 14.368 97 33 68 0 1394729552 12161056 7959600
00:29:34 69479 8192000000 10262358208 10722140160 1327911472 13.948 95 21 43 0 1345185312 12215392 5058448
00:31:43 64852 8192000000 10298269808 10744856576 1361181200 14.248 99 19 38 1 1377909712 11908160 4820352
00:33:49 53239 8192000000 10273199696 10728988672 1333305584 13.997 97 11 22 1 1348390256 12650752 2433920
00:35:43 57397 8192000000 10273337072 10725363712 1340609744 14.063 98 12 24 0 1357577760 13585488 3382528
00:37:40 65677 8192000000 10268947728 10715402240 1319651840 13.874 97 18 36 0 1332277760 8995056 3630864
00:40:05 53925 8192000000 10253568416 10689835008 1277124560 13.487 95 20 38 0 1290001536 8865360 4011616
00:42:36 105858 8192000000 10255772272 10706440192 1313628112 13.819 97 29 58 0 1333165312 12801120 6736080
00:45:23 17145 8192000000 10250988848 10706718720 1294464864 13.645 96 18 36 1 1314955360 16050912 4439584
00:46:40 60132 8192000000 10258178880 10717298688 1350431056 14.151 100 13 24 0 1367995536 14369040 3195440
00:48:42 49707 8192000000 10250124240 10703413248 1328080656 13.950 98 14 28 0 1348006112 16596112 3329344
00:50:33 39691 8192000000 10255511648 10713612288 1347625296 14.126 99 13 26 0 1363552144 12901520 3025328
00:52:17 55347 8192000000 10268026816 10725978112 1364004976 14.273 100 22 44 0 1378717536 9913296 4799264
00:54:34 60410 8192000000 10257271616 10713755648 1326307760 13.934 97 25 50 1 1340512800 8899792 5305248
00:56:35 53133 8192000000 10280505536 10737676288 1356515472 14.206 99 18 35 0 1369987760 9588064 3884224
00:58:28 33064 8192000000 10295584192 10747510784 1378671872 14.405 100 40 80 0 1407091184 19704000 8715312
01:00:09 37750 8192000000 10290524080 10737782784 1361389808 14.250 99 29 59 0 1380428544 12900592 6138144
01:01:51 66580 8192000000 10297210192 10746798080 1390027328 14.506 100 25 50 0 1405342160 9845136 5469696
01:04:04 4623 8192000000 10329550896 10768265216 1397837840 14.576 100 55 111 0 1422379568 12841680 11700048
01:05:13 103690 8192000000 10304957008 10760994816 1344196016 14.095 96 14 29 0 1360580880 13381216 3003648
01:07:56 102099 8192000000 10305796944 10774556672 1401383584 14.607 100 15 30 0 1413003776 8331776 3288416
01:10:53 32132 8192000000 10316219712 10766082048 1369987472 14.327 97 38 72 1 1388845120 10338896 8518752
01:12:26 63824 8192000000 10310797424 10790965248 1408593872 14.671 100 8 16 0 1424241152 13822960 1824320
01:15:15 54128 8192000000 10296513648 10761711616 1361984624 14.255 97 16 32 0 1374426480 9032656 3409200
01:17:10 68012 8192000000 10322090256 10772209664 1391831472 14.522 99 24 47 0 1413342752 15884560 5626720
01:19:22 41637 8192000000 10299242608 10752765952 1345611904 14.108 96 13 26 0 1355941952 7575296 2754752
01:21:04 48064 8192000000 10310754784 10749587456 1349513984 14.143 97 41 82 0 1372804864 14524352 8766528
01:22:54 59219 8192000000 10279698752 10727141376 1312747632 13.811 95 21 42 1 1332709136 15235648 4725856
01:24:55 52177 8192000000 10248441024 10692468736 1259774048 13.328 92 22 43 0 1280437376 16091536 4571792
01:26:48 84894 8192000000 10239872272 10679951360 1285425536 13.563 96 18 36 0 1300261984 10646768 4189680
01:30:12 48062 8192000000 10257444336 10705747968 1342113440 14.076 99 30 59 0 1356414704 8149536 6151728
01:32:03 40089 8192000000 10236197168 10695372800 1332679024 13.991 99 9 18 0 1343982272 9169680 2133568
01:33:57 54788 8192000000 10262126016 10708287488 1353761584 14.181 100 15 30 0 1369478928 12361376 3355968
01:35:56 65134 8192000000 10265361504 10717200384 1361218224 14.248 100 14 28 0 1376238512 11475120 3545168
01:38:02 34271 8192000000 9105212992 9340612608 264202032 3.124 20 3 4 0 266626416 2101744 322640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-10-2022 01:40:11 to 16-10-2022 01:40:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 103 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-10-2022 01:40:27 to 16-10-2022 01:40:29.

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          247878
MON$OLDEST_ACTIVE               247879
MON$OLDEST_SNAPSHOT             247879
MON$NEXT_TRANSACTION            247879
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                       2051040
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
--- end of diff output ---

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

16.10.22 01:40:30. 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
2022-10-16 01:40:33 No severe PSQL-related problems occured
16.10.22 01:40:34. End of report.