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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15236696 kB
MemAvailable 30512792 kB
Buffers 0 kB
Cached 16467932 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-12 19:38:32
DTS_END                         2022-10-12 19:38:32.0460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40452
TRN_ID                          242628
ATT_ID                          706
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: 9260.69 22864 1111283
customer order: creation 166.15 359 19938
customer order: refuse 14.54 356 1745
order to supplier: creation 46.22 1366 5546
order to supplier: removal 3.61 77599 433
invoice (draft): creation 46.38 3299 5566
invoice (draft): removal 54.48 679 6538
invoice accept: apply 40.88 17177 4905
invoice accept: cancel 48.74 705 5849
customer reserve: creation 3736.43 171 448372
customer reserve: removal 4815.59 44 577871
realization accept: apply 82.40 196 9888
realization accept: cancel 77.85 98 9342
payment from customer: creation 58.74 118 7049
payment from customer: removal 5.82 74 698
payment to supplier: creation 47.88 157 5745
payment to supplier: removal 3.40 86 408
service: total inventory turnovers 4.53 1213 543
service: total monetary turnovers 6.17 23 740
service: aggregate perf. data 0.86 20726 103
service: refresh index statistics 0.03 332841 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-10-2022 19:39:58 to 12-10-2022 19:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4512
TEST_TIME 2 5639
TEST_TIME 3 4675
TEST_TIME 4 5969
TEST_TIME 5 5476
TEST_TIME 6 5588
TEST_TIME 7 5385
TEST_TIME 8 5677
TEST_TIME 9 5861
TEST_TIME 10 5517
TEST_TIME 11 5618
TEST_TIME 12 5744
TEST_TIME 13 5951
TEST_TIME 14 5939
TEST_TIME 15 6491
TEST_TIME 16 6537
TEST_TIME 17 6708
TEST_TIME 18 6748
TEST_TIME 19 6824
TEST_TIME 20 7122
TEST_TIME 21 7132
TEST_TIME 22 7917
TEST_TIME 23 8165
TEST_TIME 24 8528
TEST_TIME 25 8563
TEST_TIME 26 8715
TEST_TIME 27 9024
TEST_TIME 28 9221
TEST_TIME 29 9602
TEST_TIME 30 9342
TEST_TIME 31 9695
TEST_TIME 32 9908
TEST_TIME 33 9855
TEST_TIME 34 10096
TEST_TIME 35 10173
TEST_TIME 36 10009
TEST_TIME 37 9819
TEST_TIME 38 9727
TEST_TIME 39 9682
TEST_TIME 40 9711
TEST_TIME 41 9615
TEST_TIME 42 9590
TEST_TIME 43 9514
TEST_TIME 44 9483
TEST_TIME 45 9739
TEST_TIME 46 9626
TEST_TIME 47 9545
TEST_TIME 48 9512
TEST_TIME 49 9465
TEST_TIME 50 9333
TEST_TIME 51 9240
TEST_TIME 52 9399
TEST_TIME 53 9314
TEST_TIME 54 9286
TEST_TIME 55 9408
TEST_TIME 56 9385
TEST_TIME 57 9416
TEST_TIME 58 9443
TEST_TIME 59 9480
TEST_TIME 60 9478
TEST_TIME 61 9573
TEST_TIME 62 9552
TEST_TIME 63 9584
TEST_TIME 64 9695
TEST_TIME 65 9917
TEST_TIME 66 9918
TEST_TIME 67 9892
TEST_TIME 68 9904
TEST_TIME 69 9919
TEST_TIME 70 9950
TEST_TIME 71 9852
TEST_TIME 72 9810
TEST_TIME 73 9779
TEST_TIME 74 9823
TEST_TIME 75 9756
TEST_TIME 76 9712
TEST_TIME 77 9666
TEST_TIME 78 9703
TEST_TIME 79 9646
TEST_TIME 80 9597
TEST_TIME 81 9637
TEST_TIME 82 9626
TEST_TIME 83 9670
TEST_TIME 84 9655
TEST_TIME 85 9625
TEST_TIME 86 9633
TEST_TIME 87 9681
TEST_TIME 88 9671
TEST_TIME 89 9677
TEST_TIME 90 9758
TEST_TIME 91 9752
TEST_TIME 92 9791
TEST_TIME 93 9784
TEST_TIME 94 9806
TEST_TIME 95 9787
TEST_TIME 96 9831
TEST_TIME 97 9856
TEST_TIME 98 9768
TEST_TIME 99 9720
TEST_TIME 100 9671
TEST_TIME 101 9641
TEST_TIME 102 9588
TEST_TIME 103 9569
TEST_TIME 104 9527
TEST_TIME 105 9490
TEST_TIME 106 9433
TEST_TIME 107 9441
TEST_TIME 108 9448
TEST_TIME 109 9426
TEST_TIME 110 9403
TEST_TIME 111 9356
TEST_TIME 112 9386
TEST_TIME 113 9376
TEST_TIME 114 9324
TEST_TIME 115 9307
TEST_TIME 116 9263
TEST_TIME 117 9251
TEST_TIME 118 9251
TEST_TIME 119 9278
TEST_TIME 120 9261

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-10-2022 19:40:02 to 12-10-2022 19:40:05.

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 1527256 1527256 0 0.00 0 29053 6 0 0
doc_list_biud 1527256 1527256 0 0.00 0 29285 22 0 0
sp_add_invoice_to_stock 4982 4905 77 1.54 24 151817 17177 0 77
sp_cancel_adding_invoice 5849 5849 0 0.00 4 63753 706 0 0
sp_cancel_client_order 1745 1745 0 0.00 4 14241 356 0 0
sp_cancel_customer_reserve 577871 577871 0 0.00 0 29065 45 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 3833 74 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 7702 87 0 0
sp_cancel_supplier_invoice 6541 6538 3 0.04 2 21524 680 0 3
sp_cancel_supplier_order 433 433 0 0.00 7 1006215 77600 0 0
sp_cancel_write_off 9342 9342 0 0.00 1 9284 99 0 0
sp_client_order 19938 19938 0 0.00 3 36744 359 0 0
sp_customer_reserve 448372 448372 0 0.00 0 41052 171 0 0
sp_fill_shopping_cart 31045 31045 0 0.00 1 18083 40 0 0
sp_fill_shopping_cart_clo_res 448369 448369 0 0.00 0 15328 13 0 0
sp_get_clo_for_invoice 4902 4902 0 0.00 0 17239 310 0 0
sp_kill_cost_storno 16297 16297 0 0.00 0 7755 50 0 0
sp_kill_qstorno_ret_qs2qd 1172055 1172055 0 0.00 0 25029 25 0 0
sp_kill_qty_storno 616568 616568 0 0.00 0 29285 55 0 0
sp_lock_selected_doc 20547 20547 0 0.00 0 5946 7 0 0
sp_make_cost_storno 27584 27584 0 0.00 0 19233 62 0 0
sp_multiply_rows_for_pdistr 27584 27584 0 0.00 0 13885 29 0 0
sp_multiply_rows_for_qdistr 19938 19938 0 0.00 0 11571 133 0 0
sp_pay_from_customer 7049 7049 0 0.00 4 9281 119 0 0
sp_pay_to_supplier 5745 5745 0 0.00 5 19246 158 0 0
sp_payment_common 12794 12794 0 0.00 1 11456 37 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 14234 323 0 0
sp_qd_handle_on_invoice_upd_sts 10751 10751 0 0.00 0 29285 331 0 0
sp_qd_handle_on_reserve_upd_sts 9888 9888 0 0.00 0 15354 65 0 0
sp_reserve_write_off 9888 9888 0 0.00 6 17745 196 0 0
sp_supplier_invoice 5566 5566 0 0.00 7 82535 3299 0 0
sp_supplier_order 5546 5546 0 0.00 6 32473 1366 0 0
srv_aggregate_perf_data 103 103 0 0.00 2921 260032 20726 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 975 103645 41139 0 0
srv_find_qd_qs_mism 1064966 1064966 0 0.00 0 29051 4 0 0
srv_make_invnt_saldo 543 543 0 0.00 49 64226 1214 0 0
srv_make_money_saldo 740 740 0 0.00 1 990 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 160906 597533 332842 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4905 4905 0 0.00 0 9869 193 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 8664 119 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 6897 13 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 1978 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 1121 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 1185 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1745 13 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 24111 1618 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 11429 28 0 0
t$perf-norm:sp_client_order 19938 19938 0 0.00 0 10478 18 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 3068 7 0 0
t$perf-norm:sp_pay_from_customer 7049 7049 0 0.00 0 6786 10 0 0
t$perf-norm:sp_pay_to_supplier 5745 5745 0 0.00 0 5872 14 0 0
t$perf-norm:sp_reserve_write_off 9888 9888 0 0.00 0 11427 13 0 0
t$perf-norm:sp_supplier_invoice 5566 5566 0 0.00 0 6778 29 0 0
t$perf-norm:sp_supplier_order 5546 5546 0 0.00 0 9606 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 316 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 543 543 0 0.00 0 3894 28 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 1254 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9954 9800 154 1.54 0 9234 17 0 154
v_all_customers 36414 36414 0 0.00 0 23 1 0 0
v_all_suppliers 30420 30420 0 0.00 0 63 1 0 0
v_all_wares 39876 39876 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 469 6 0 0
v_cancel_client_order 3490 3490 0 0.00 0 3605 8 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 1891 5 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 803 4 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 345 3 0 0
v_cancel_supplier_invoice 836 830 6 0.71 0 2396 18 0 6
v_cancel_supplier_order 866 866 0 0.00 0 1703 7 0 0
v_cancel_write_off 1952 1952 0 0.00 0 9247 20 0 0
v_max_id_clo_ord 5546 5546 0 0.00 0 1364 2 0 0
v_max_id_clo_res 984 984 0 0.00 0 2409 17 0 0
v_max_id_ord_sup 5561 5561 0 0.00 0 4505 4 0 0
v_max_non_paid_invoice 1642 1642 0 0.00 0 3239 9 0 0
v_max_non_paid_realizn 3828 3828 0 0.00 0 2361 5 0 0
v_min_id_clo_ord 5546 5546 0 0.00 0 7222 11 0 0
v_min_id_clo_res 984 984 0 0.00 0 6448 12 0 0
v_min_id_ord_sup 5561 5561 0 0.00 0 5346 7 0 0
v_min_non_paid_invoice 5745 5745 0 0.00 0 11452 26 0 0
v_min_non_paid_realizn 7049 7049 0 0.00 0 7001 32 0 0
v_our_firm 9904 9904 0 0.00 0 225 1 0 0
v_reserve_write_off 19776 19776 0 0.00 0 9244 7 0 0
x_lock_depdocs_on_canc_invoice 11972 11972 0 0.00 0 14650 108 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 8013 344 0 0
x_make_qstorno_1000_1200 5546 5546 0 0.00 2 32469 1284 0 0
x_make_qstorno_1200_2000 5566 5566 0 0.00 3 82506 3165 0 0
x_make_qstorno_2100_3300 448372 448372 0 0.00 0 41051 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2022 19:40:06 to 12-10-2022 19:40:10.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:37 22335 8192000000 9900396800 10209132544 983149360 10.715 100 85 170 0 1011111264 5454704 22507200
17:11:03 14432 8192000000 9932856656 10249940992 1016738224 11.041 100 88 176 0 1047925440 7858384 23328832
17:12:29 13431 8192000000 9970727904 10300993536 1055725504 11.416 100 83 166 0 1086115040 9589280 20800256
17:13:57 8357 8192000000 10033133136 10378600448 1115913840 11.988 100 83 164 0 1146697584 10386192 20397552
17:15:07 5907 8192000000 10102402608 10462044160 1182329968 12.612 100 79 158 0 1211968400 11803360 17835072
17:16:17 3552 8192000000 10130278192 10498342912 1219679872 12.959 100 81 162 0 1251561504 13732304 18149328
17:17:23 2712 8192000000 10169764448 10546212864 1257429792 13.306 100 85 168 1 1289821648 13857792 18534064
17:18:27 820 8192000000 10208189376 10588962816 1291264880 13.616 100 96 194 0 1325558976 12600768 21693328
17:19:30 2014 8192000000 10209662368 10601558016 1303618784 13.728 100 71 142 0 1332405760 13553936 15233040
17:20:33 9218 8192000000 10202542080 10614263808 1319451840 13.872 100 32 64 1 1333882800 7947984 6482976
17:21:42 21318 8192000000 10227399600 10634768384 1325675952 13.928 100 66 130 0 1347043520 6473200 14894368
17:23:05 2813 8192000000 10232072080 10643697664 1333871024 14.002 100 41 82 0 1350423872 7098144 9454704
17:24:09 9803 8192000000 10229320224 10648313856 1339725680 14.055 100 20 38 0 1350549296 5432752 5390864
17:25:23 55118 8192000000 10236602384 10653130752 1343974816 14.093 100 19 37 0 1354104656 5850848 4278992
17:27:18 6132 8192000000 10210585808 10630582272 1315767456 13.838 98 21 38 1 1324912912 5259216 3886240
17:28:27 7031 8192000000 10218207856 10634641408 1304861952 13.739 98 25 50 0 1321142384 10850944 5429488
17:29:42 7253 8192000000 10136061648 10536624128 1191426512 12.697 94 10 21 2 1198605824 4930928 2248384
17:30:52 34236 8192000000 10130443488 10538438656 1182318400 12.612 93 28 56 0 1194556560 5801840 6436320
17:32:27 7051 8192000000 10140035488 10538713088 1209558064 12.865 96 24 49 0 1220203792 5129216 5516512
17:33:34 7492 8192000000 10153061536 10562662400 1257699536 13.309 99 27 54 1 1269624208 5719200 6205472
17:34:46 15623 8192000000 10181311808 10592518144 1290059584 13.605 100 27 54 1 1301865968 5280368 6526016
17:36:04 18751 8192000000 10185002784 10602115072 1295051504 13.650 100 24 48 0 1310513680 10171568 5290608
17:37:24 9791 8192000000 10189667712 10607443968 1301620656 13.710 100 25 50 0 1313076528 5713376 5742496
17:38:40 10152 8192000000 10232991008 10642829312 1331662576 13.982 100 42 86 0 1347743008 5876592 10203840
17:39:56 11756 8192000000 10241335728 10657660928 1340152672 14.059 100 40 75 2 1356066064 7742048 8171344
17:41:13 45026 8192000000 10258455216 10686836736 1361110992 14.247 100 40 80 0 1378778608 8104640 9562976
17:43:01 19365 8192000000 10272723696 10698600448 1371968384 14.345 100 45 90 1 1391227248 9150448 10108416
17:44:30 20627 8192000000 10297179440 10723823616 1390796096 14.513 100 42 84 0 1410903856 10439472 9668288
17:45:55 19954 8192000000 10292031904 10718883840 1379692176 14.414 99 40 80 0 1400796192 11738880 9365136
17:47:19 2669 8192000000 10322634384 10747637760 1403232208 14.624 100 58 115 0 1429515680 13149664 13133808
17:48:33 31258 8192000000 10319414928 10750255104 1398867552 14.585 99 45 90 0 1427541632 17956416 10717664
17:50:07 12504 8192000000 10323373120 10756595712 1408992320 14.675 100 47 94 0 1438032304 17750336 11289648
17:51:31 4762 8192000000 10293996432 10718412800 1378190080 14.400 100 40 80 1 1405427200 17517328 9719792
17:52:40 4492 8192000000 10310554416 10729431040 1384301392 14.455 100 66 132 1 1421577104 21927008 15348704
17:53:51 2057 8192000000 10296108304 10711126016 1372697328 14.351 100 62 122 1 1404603648 17539120 14367200
17:55:00 4476 8192000000 10282988096 10694561792 1354840240 14.191 100 57 113 1 1389110480 20660304 13609936
17:56:12 4612 8192000000 10292188688 10699956224 1346955840 14.120 99 71 138 4 1388317264 24017536 17343888
17:57:25 10071 8192000000 10282076528 10697068544 1340935696 14.066 98 41 82 0 1376089344 24742096 10411552
17:58:40 11338 8192000000 10284201520 10704203776 1357447680 14.214 100 40 78 0 1397729376 29478288 10803408
17:59:54 974 8192000000 10311228784 10710724608 1352808304 14.173 100 92 182 0 1414408512 39002176 22598032
18:00:59 4963 8192000000 10281960112 10694467584 1351190032 14.158 100 53 106 1 1393214704 28616368 13408304
18:02:08 5795 8192000000 10284134080 10701897728 1348349760 14.133 100 56 110 0 1393040528 30056160 14634608
18:03:15 20306 8192000000 10289838688 10715131904 1361561968 14.251 100 38 77 0 1397702560 26575392 9565200
18:04:38 10276 8192000000 10275197408 10705813504 1353937040 14.183 100 23 46 0 1382955120 22752320 6265760
18:05:53 69193 8192000000 10294323344 10735378432 1371442032 14.340 100 16 30 0 1402491904 27050240 3999632
18:08:11 28757 8192000000 10308504672 10735906816 1369562128 14.323 99 26 51 2 1394932960 18729680 6641152
18:09:41 38730 8192000000 10309960048 10748506112 1387751248 14.486 100 11 22 0 1413532576 22842800 2938528
18:11:31 49273 8192000000 10300890144 10745528320 1376159136 14.382 99 26 53 0 1399413488 17209024 6045328
18:13:30 32524 8192000000 10294987248 10744991744 1391139840 14.516 100 14 26 1 1401984640 8637216 2207584
18:16:28 20654 8192000000 10322661200 10763280384 1403236304 14.624 100 56 110 4 1422442704 6722368 12484032
18:17:51 22296 8192000000 10322329776 10765635584 1397507440 14.573 99 52 104 0 1419410992 9861088 12042464
18:19:17 44896 8192000000 10318991968 10783088640 1418046048 14.755 100 18 34 0 1434017328 12370112 3601168
18:21:06 39846 8192000000 10333409648 10783723520 1410019520 14.684 99 37 75 0 1430654640 11618928 9016192
18:22:46 38270 8192000000 10334110064 10790854656 1418306368 14.758 100 36 72 0 1446873728 19852064 8715296
18:24:26 15366 8192000000 10330654912 10783002624 1411624560 14.698 99 18 35 0 1421780752 6199776 3956416
18:25:49 60595 8192000000 10320145792 10790789120 1427929840 14.843 100 14 28 0 1439309728 8379792 3000096
18:27:51 72503 8192000000 10333986544 10796699648 1422578144 14.796 100 15 30 1 1438405072 12118224 3708704
18:30:04 59559 8192000000 10323271168 10806390784 1410379088 14.687 99 15 30 0 1434921744 20873632 3669024
18:32:05 62079 8192000000 10302341440 10773385216 1384217008 14.454 97 14 28 0 1401904624 14159920 3527696
18:34:19 35857 8192000000 10292419104 10757890048 1373933136 14.362 98 14 27 0 1385980304 9126896 2920272
18:35:56 11557 8192000000 10310046080 10757165056 1390893920 14.514 99 54 106 2 1412493904 9413872 12186112
18:37:10 20908 8192000000 10299947904 10755866624 1384954576 14.461 99 24 49 0 1400203584 9153536 6095472
18:39:16 49953 8192000000 10233943840 10683695104 1221557248 12.976 89 20 40 0 1247364368 21523424 4283696
18:41:06 56362 8192000000 10247981904 10690994176 1254688272 13.281 93 14 28 0 1277309648 19432784 3188592
18:43:06 54841 8192000000 10244732976 10712281088 1319561408 13.873 99 7 14 0 1340415264 19275744 1578112
18:45:01 36136 8192000000 10260691168 10711547904 1354911408 14.192 100 24 46 0 1370125648 9847968 5366272
18:46:41 101438 8192000000 10252746224 10703310848 1264310512 13.370 94 17 34 0 1281668080 13663824 3693744
18:49:37 82939 8192000000 10255594432 10697105408 1294444832 13.645 96 30 58 1 1309565840 7398640 7722368
18:52:04 25910 8192000000 10238533568 10695651328 1333042784 13.995 100 21 42 2 1357304384 19428528 4833072
18:53:45 21583 8192000000 10269727568 10715136000 1356164768 14.203 100 29 59 0 1370637616 8316800 6156048
18:55:12 58085 8192000000 10280399536 10728816640 1362581232 14.261 99 18 36 0 1379196592 12229136 4386224
18:57:15 84930 8192000000 10251088320 10710474752 1331440304 13.980 97 21 40 0 1351114384 14186608 5487472
18:59:41 58926 8192000000 10264710160 10730872832 1360293488 14.240 100 11 21 1 1379431200 16438272 2699440
19:01:40 45834 8192000000 10283180080 10737348608 1380247728 14.419 100 11 22 2 1393522160 11182032 2092400
19:03:33 50480 8192000000 10281570096 10735992832 1364611808 14.279 99 23 44 0 1380608496 10894864 5101824
19:05:24 92507 8192000000 10297923472 10757136384 1374033312 14.363 99 10 20 0 1393485728 17294000 2158416
19:08:18 49564 8192000000 10307265968 10787737600 1396864720 14.567 100 8 16 0 1419067328 20428864 1773744
19:10:08 84578 8192000000 10310684096 10778558464 1380123248 14.418 98 22 42 0 1395295152 10541104 4630800
19:12:33 66078 8192000000 10306986576 10761109504 1382805872 14.442 99 11 22 0 1394005328 9311280 1888176
19:14:40 95559 8192000000 10308172864 10787917824 1390271312 14.508 99 5 10 0 1412843312 21768976 803024
19:17:27 72592 8192000000 10248930448 10705260544 1310628432 13.792 94 14 28 1 1322118368 8645824 2844112
19:19:42 66638 8192000000 10287462192 10741809152 1371190528 14.338 99 8 16 0 1381697968 7666464 2840976
19:21:58 71296 8192000000 10284607648 10742296576 1371825712 14.343 99 23 47 0 1386518128 8936400 5756016
19:24:19 103624 8192000000 10264928192 10727264256 1335004816 14.012 96 16 32 0 1352012000 12945152 4062032
19:27:13 60320 8192000000 10258926304 10714415104 1342459424 14.080 98 19 38 0 1355866880 9368720 4038736
19:29:14 25178 8192000000 10263191104 10703736832 1334403232 14.007 98 33 66 0 1351384752 9396496 7585024
19:30:43 74271 8192000000 10232699088 10694709248 1308977776 13.777 97 18 36 0 1329783056 16629936 4175344
19:32:58 95794 8192000000 10238869840 10684608512 1337730528 14.037 100 21 42 0 1350480320 7654384 5095408
19:37:46 49315 8192000000 9401733264 9755246592 479317728 5.527 36 2 4 0 485920384 6194848 407808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-10-2022 19:40:12 to 12-10-2022 19:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 77 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-10-2022 19:40:29 to 12-10-2022 19:40:31.

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          242930
MON$OLDEST_ACTIVE               242931
MON$OLDEST_SNAPSHOT             242931
MON$NEXT_TRANSACTION            242931
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                       2060896
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 ---
:275: 
:276: mscs-test-161.budzdorov.ru    Wed Oct 12 19:38:41 2022
:277:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:278: 
--- end of diff output ---

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

12.10.22 19:40:32. 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-12 19:40:34 No severe PSQL-related problems occured
12.10.22 19:40:36. End of report.