Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.02.2023 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 15329240 kB
MemAvailable 30394136 kB
Buffers 0 kB
Cached 16345620 kB
SwapCached 5068 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 2023-02-10 19:38:38
DTS_END                         2023-02-10 19:38:38.8710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36468
TRN_ID                          243016
ATT_ID                          690
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: 9281.08 21044 1113729
customer order: creation 174.19 357 20903
customer order: refuse 14.96 324 1795
order to supplier: creation 47.12 1137 5654
order to supplier: removal 3.32 104402 398
invoice (draft): creation 46.18 3469 5541
invoice (draft): removal 53.49 713 6419
invoice accept: apply 41.93 16590 5031
invoice accept: cancel 47.44 792 5693
customer reserve: creation 3864.22 163 463706
customer reserve: removal 4692.23 59 563068
realization accept: apply 84.07 180 10088
realization accept: cancel 80.36 106 9643
payment from customer: creation 60.58 109 7269
payment from customer: removal 5.98 86 718
payment to supplier: creation 49.51 128 5941
payment to supplier: removal 3.27 49 392
service: total inventory turnovers 4.74 1080 569
service: total monetary turnovers 6.71 45 805
service: aggregate perf. data 0.77 25734 92
service: refresh index statistics 0.03 265348 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-02-2023 19:39:55 to 10-02-2023 19:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2546
TEST_TIME 2 2327
TEST_TIME 3 2094
TEST_TIME 4 2743
TEST_TIME 5 3512
TEST_TIME 6 4497
TEST_TIME 7 4684
TEST_TIME 8 4937
TEST_TIME 9 5259
TEST_TIME 10 5728
TEST_TIME 11 5738
TEST_TIME 12 6451
TEST_TIME 13 6480
TEST_TIME 14 6482
TEST_TIME 15 7109
TEST_TIME 16 7237
TEST_TIME 17 7451
TEST_TIME 18 7877
TEST_TIME 19 7963
TEST_TIME 20 8069
TEST_TIME 21 8268
TEST_TIME 22 8414
TEST_TIME 23 9189
TEST_TIME 24 9294
TEST_TIME 25 9310
TEST_TIME 26 9979
TEST_TIME 27 11023
TEST_TIME 28 10824
TEST_TIME 29 10661
TEST_TIME 30 10545
TEST_TIME 31 10580
TEST_TIME 32 10595
TEST_TIME 33 10419
TEST_TIME 34 10270
TEST_TIME 35 10122
TEST_TIME 36 10175
TEST_TIME 37 10109
TEST_TIME 38 10011
TEST_TIME 39 9881
TEST_TIME 40 9820
TEST_TIME 41 9797
TEST_TIME 42 9722
TEST_TIME 43 9817
TEST_TIME 44 9829
TEST_TIME 45 9914
TEST_TIME 46 9769
TEST_TIME 47 9621
TEST_TIME 48 9631
TEST_TIME 49 9651
TEST_TIME 50 9609
TEST_TIME 51 9493
TEST_TIME 52 9419
TEST_TIME 53 9359
TEST_TIME 54 9374
TEST_TIME 55 9297
TEST_TIME 56 9371
TEST_TIME 57 9378
TEST_TIME 58 9467
TEST_TIME 59 9543
TEST_TIME 60 9483
TEST_TIME 61 9648
TEST_TIME 62 9645
TEST_TIME 63 9744
TEST_TIME 64 9761
TEST_TIME 65 9771
TEST_TIME 66 9768
TEST_TIME 67 9734
TEST_TIME 68 9633
TEST_TIME 69 9640
TEST_TIME 70 9592
TEST_TIME 71 9553
TEST_TIME 72 9519
TEST_TIME 73 9477
TEST_TIME 74 9438
TEST_TIME 75 9446
TEST_TIME 76 9436
TEST_TIME 77 9468
TEST_TIME 78 9471
TEST_TIME 79 9482
TEST_TIME 80 9492
TEST_TIME 81 9498
TEST_TIME 82 9565
TEST_TIME 83 9542
TEST_TIME 84 9560
TEST_TIME 85 9568
TEST_TIME 86 9543
TEST_TIME 87 9620
TEST_TIME 88 9693
TEST_TIME 89 9610
TEST_TIME 90 9574
TEST_TIME 91 9560
TEST_TIME 92 9527
TEST_TIME 93 9483
TEST_TIME 94 9418
TEST_TIME 95 9417
TEST_TIME 96 9455
TEST_TIME 97 9454
TEST_TIME 98 9469
TEST_TIME 99 9415
TEST_TIME 100 9458
TEST_TIME 101 9510
TEST_TIME 102 9479
TEST_TIME 103 9505
TEST_TIME 104 9565
TEST_TIME 105 9569
TEST_TIME 106 9600
TEST_TIME 107 9595
TEST_TIME 108 9576
TEST_TIME 109 9501
TEST_TIME 110 9492
TEST_TIME 111 9461
TEST_TIME 112 9436
TEST_TIME 113 9422
TEST_TIME 114 9377
TEST_TIME 115 9359
TEST_TIME 116 9327
TEST_TIME 117 9322
TEST_TIME 118 9327
TEST_TIME 119 9321
TEST_TIME 120 9284

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-02-2023 19:39:59 to 10-02-2023 19:40:02.

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 1543924 1543924 0 0.00 0 26585 6 0 0
doc_list_biud 1543924 1543924 0 0.00 0 51192 27 0 0
sp_add_invoice_to_stock 5177 5031 146 2.82 18 144859 16591 0 146
sp_cancel_adding_invoice 5693 5693 0 0.00 4 91747 793 0 0
sp_cancel_client_order 1795 1795 0 0.00 5 9280 324 0 0
sp_cancel_customer_reserve 563068 563068 0 0.00 0 51193 59 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 2 5633 86 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 2 1708 50 0 0
sp_cancel_supplier_invoice 6430 6419 11 0.17 1 25540 713 0 11
sp_cancel_supplier_order 398 398 0 0.00 7 1200199 104403 0 0
sp_cancel_write_off 9643 9643 0 0.00 1 12396 106 0 0
sp_client_order 20903 20903 0 0.00 3 18969 358 0 0
sp_customer_reserve 463706 463706 0 0.00 0 69838 163 0 0
sp_fill_shopping_cart 32096 32096 0 0.00 1 32867 40 0 0
sp_fill_shopping_cart_clo_res 463702 463702 0 0.00 0 21871 15 0 0
sp_get_clo_for_invoice 5024 5024 0 0.00 0 24616 321 0 0
sp_kill_cost_storno 16446 16446 0 0.00 0 10386 51 0 0
sp_kill_qstorno_ret_qs2qd 1142596 1142596 0 0.00 0 51190 32 0 0
sp_kill_qty_storno 602128 602128 0 0.00 0 51192 68 0 0
sp_lock_selected_doc 21014 21014 0 0.00 0 9012 6 0 0
sp_make_cost_storno 28322 28322 0 0.00 0 19280 60 0 0
sp_multiply_rows_for_pdistr 28322 28322 0 0.00 0 10439 26 0 0
sp_multiply_rows_for_qdistr 20903 20903 0 0.00 0 18936 176 0 0
sp_pay_from_customer 7269 7269 0 0.00 4 11880 109 0 0
sp_pay_to_supplier 5941 5941 0 0.00 4 15065 128 0 0
sp_payment_common 13210 13210 0 0.00 1 10088 30 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 8014 288 0 0
sp_qd_handle_on_invoice_upd_sts 10717 10717 0 0.00 0 50398 322 0 0
sp_qd_handle_on_reserve_upd_sts 10088 10088 0 0.00 0 13162 56 0 0
sp_reserve_write_off 10088 10088 0 0.00 6 21240 180 0 0
sp_supplier_invoice 5541 5541 0 0.00 6 100210 3470 0 0
sp_supplier_order 5654 5654 0 0.00 5 29665 1138 0 0
srv_aggregate_perf_data 92 92 0 0.00 2993 522081 25734 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2600 148404 44202 0 0
srv_find_qd_qs_mism 1065868 1065868 0 0.00 0 26585 5 0 0
srv_make_invnt_saldo 569 569 0 0.00 23 46731 1080 0 0
srv_make_money_saldo 805 805 0 0.00 0 15291 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 172168 390860 265348 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5031 5031 0 0.00 0 15651 183 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 3256 89 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 13459 24 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 3735 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 869 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 3070 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 1120 14 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 33834 1909 0 0
t$perf-norm:sp_cancel_write_off 1040 1040 0 0.00 0 453 3 0 0
t$perf-norm:sp_client_order 20903 20903 0 0.00 0 21227 16 0 0
t$perf-norm:sp_customer_reserve 1064 1064 0 0.00 0 7471 16 0 0
t$perf-norm:sp_pay_from_customer 7269 7269 0 0.00 0 9937 9 0 0
t$perf-norm:sp_pay_to_supplier 5941 5941 0 0.00 0 3992 8 0 0
t$perf-norm:sp_reserve_write_off 10088 10088 0 0.00 0 22700 16 0 0
t$perf-norm:sp_supplier_invoice 5541 5541 0 0.00 0 12301 19 0 0
t$perf-norm:sp_supplier_order 5654 5654 0 0.00 0 4494 13 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 332 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 534 13 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 1232 24 0 0
t$perf-norm:srv_make_money_saldo 805 805 0 0.00 0 1471 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 1 0 0
v_add_invoice_to_stock 10340 10048 292 2.82 0 6173 18 0 292
v_all_customers 38266 38266 0 0.00 0 20 1 0 0
v_all_suppliers 31096 31096 0 0.00 0 113 1 0 0
v_all_wares 41806 41806 0 0.00 0 45 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1219 8 0 0
v_cancel_client_order 3590 3590 0 0.00 0 499 2 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 2683 11 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 882 4 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 156 3 0 0
v_cancel_supplier_invoice 900 878 22 2.44 0 931 12 0 22
v_cancel_supplier_order 796 796 0 0.00 0 901 4 0 0
v_cancel_write_off 2080 2080 0 0.00 0 7845 19 0 0
v_max_id_clo_ord 5654 5654 0 0.00 0 3597 2 0 0
v_max_id_clo_res 1064 1064 0 0.00 0 1811 11 0 0
v_max_id_ord_sup 5539 5539 0 0.00 0 13886 7 0 0
v_max_non_paid_invoice 1586 1586 0 0.00 0 220 2 0 0
v_max_non_paid_realizn 3911 3911 0 0.00 0 8152 8 0 0
v_min_id_clo_ord 5654 5654 0 0.00 0 2456 8 0 0
v_min_id_clo_res 1064 1064 0 0.00 0 4024 12 0 0
v_min_id_ord_sup 5539 5539 0 0.00 0 11375 10 0 0
v_min_non_paid_invoice 5941 5941 0 0.00 0 10079 24 0 0
v_min_non_paid_realizn 7269 7269 0 0.00 0 9264 22 0 0
v_our_firm 10256 10256 0 0.00 0 19 1 0 0
v_reserve_write_off 20176 20176 0 0.00 0 2601 5 0 0
x_lock_depdocs_on_canc_invoice 11673 11673 0 0.00 0 21231 132 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 7660 361 0 0
x_make_qstorno_1000_1200 5654 5654 0 0.00 1 29615 1056 0 0
x_make_qstorno_1200_2000 5541 5541 0 0.00 3 96653 3327 0 0
x_make_qstorno_2100_3300 463706 463706 0 0.00 0 69837 145 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-02-2023 19:40:03 to 10-02-2023 19:40:08.

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:42 5 8192000000 8806964736 8821116928 11774896 0.143 5 2 3 0 11919984 70128 74960
17:09:43 33570 8192000000 9914315248 10229956608 992564352 10.806 100 89 178 0 1021663872 6023152 23076368
17:11:21 21779 8192000000 9963190064 10288197632 1043986608 11.303 100 90 180 0 1075285216 8253680 23044928
17:12:49 10482 8192000000 9989082144 10322280448 1074393008 11.594 100 92 184 0 1107604768 10006288 23205472
17:14:09 8896 8192000000 10038020896 10385215488 1118698624 12.015 100 81 162 0 1148959552 11012496 19248432
17:15:24 7692 8192000000 10086266880 10445213696 1171062928 12.507 100 80 161 0 1200885488 11823808 17998752
17:16:34 3019 8192000000 10118692224 10488954880 1207818464 12.849 100 80 158 1 1238514816 13117968 17578384
17:17:39 2464 8192000000 10146484384 10521161728 1242385472 13.168 100 77 154 0 1273417344 14545072 16486800
17:18:45 1699 8192000000 10196123008 10577879040 1281122064 13.523 100 87 174 2 1313204800 12940240 19142496
17:19:48 1252 8192000000 10220725472 10607783936 1305600864 13.746 100 90 178 1 1338864064 13069072 20194128
17:20:50 1509 8192000000 10218905440 10614702080 1312738240 13.811 100 84 171 0 1339293136 6758160 19796736
17:21:53 6397 8192000000 10200933808 10620190720 1316700288 13.847 100 17 34 0 1328012016 6703552 4608176
17:23:00 8395 8192000000 10214932544 10631385088 1326643072 13.937 100 29 56 0 1339292720 5991136 6658512
17:24:11 7372 8192000000 10216982528 10637242368 1329481872 13.962 100 13 26 0 1337979504 5708544 2789088
17:25:19 66458 8192000000 10242434720 10649907200 1338150160 14.041 100 34 68 1 1351429056 5724448 7554448
17:27:26 4542 8192000000 10232273264 10652684288 1329168160 13.960 99 33 66 0 1343889840 6798272 7923408
17:28:35 12243 8192000000 10221932752 10645594112 1307762112 13.766 98 15 30 0 1320431968 9423728 3246128
17:29:51 50038 8192000000 10166488352 10561781760 1207316112 12.844 93 40 79 1 1222574752 5960336 9298304
17:31:42 3575 8192000000 10147368256 10548973568 1237473152 13.123 98 40 80 1 1254039744 6834688 9731904
17:32:48 6520 8192000000 10133740288 10533978112 1205910880 12.831 96 17 35 1 1215109472 4971968 4226624
17:33:57 18592 8192000000 10157278656 10564390912 1263294128 13.360 99 28 56 1 1276665760 5778928 7592704
17:35:17 16430 8192000000 10161979584 10577186816 1283424896 13.544 100 23 47 0 1294420336 5254992 5740448
17:36:34 4361 8192000000 10176861008 10589421568 1290168384 13.606 100 24 48 0 1301587344 5536192 5882768
17:37:40 7007 8192000000 10184247856 10601562112 1301627312 13.710 100 9 18 0 1309148960 5388272 2133376
17:38:49 61732 8192000000 10233213200 10647588864 1329655888 13.964 100 27 54 0 1341078576 5347552 6075136
17:40:53 65710 8192000000 10255131664 10674819072 1351285504 14.159 100 35 70 0 1371666240 11920512 8460224
17:43:02 2653 8192000000 10281775616 10695839744 1367714064 14.307 100 50 95 0 1395657728 16767776 11175888
17:44:09 10874 8192000000 10296284928 10713538560 1379878496 14.415 100 49 97 0 1411766272 20994368 10893408
17:45:24 7931 8192000000 10303326528 10726301696 1389782064 14.504 100 55 110 1 1420832016 17499024 13550928
17:46:39 3995 8192000000 10318561984 10737442816 1396042416 14.560 100 53 106 2 1430582928 22241328 12299184
17:47:48 6550 8192000000 10327584240 10753466368 1404916128 14.639 100 55 106 1 1442892368 25285552 12690688
17:48:58 4209 8192000000 10327677536 10752421888 1402181824 14.614 100 46 92 1 1442063728 28363152 11518752
17:50:06 3021 8192000000 10336003808 10755063808 1393672368 14.539 100 69 138 0 1446566944 36359200 16535376
17:51:16 2798 8192000000 10306279552 10718605312 1364613648 14.279 99 75 149 0 1416154224 33537792 18002784
17:52:26 2600 8192000000 10354385952 10763624448 1398595280 14.582 100 78 154 1 1457832784 40556320 18681184
17:53:37 3055 8192000000 10349793616 10762170368 1401218800 14.606 100 72 144 1 1462095424 43139520 17737104
17:54:48 3078 8192000000 10357556800 10768621568 1380387200 14.420 98 78 156 1 1439865200 39960864 19517136
17:55:54 3288 8192000000 10347459312 10754109440 1385892368 14.469 100 72 143 0 1444494192 40298464 18303360
17:57:02 10376 8192000000 10331359760 10746105856 1378033616 14.399 100 50 96 1 1434977712 44487664 12456432
17:58:17 5303 8192000000 10354067776 10770067456 1393147472 14.534 100 60 120 0 1457434912 48629968 15657472
17:59:35 5090 8192000000 10369907248 10788052992 1395204224 14.552 100 62 124 1 1472537232 61254032 16078976
18:00:49 11234 8192000000 10332223632 10756173824 1349188928 14.140 98 39 78 1 1421469248 61413248 10867072
18:02:02 17676 8192000000 10307643616 10735951872 1364592064 14.279 100 29 57 0 1416799968 44061184 8146720
18:03:23 44914 8192000000 10309170128 10753744896 1372209728 14.347 100 23 46 0 1428161056 49606000 6345328
18:05:33 36401 8192000000 10257496272 10709704704 1331421712 13.980 98 11 22 0 1352647456 18587760 2637984
18:07:11 29539 8192000000 10267624448 10712596480 1358322448 14.222 100 12 24 0 1371720288 10392224 3005616
18:09:29 42686 8192000000 10262239504 10718007296 1337760256 14.037 98 7 15 1 1354306608 15034448 1511904
18:11:17 90792 8192000000 10288920816 10726338560 1353164544 14.176 98 22 44 0 1368967136 10757904 5044688
18:13:56 146825 8192000000 10286235664 10753069056 1371521216 14.341 99 18 36 0 1392883168 17506832 3855120
18:17:24 6486 8192000000 10292334720 10736386048 1386351776 14.473 100 33 66 1 1402096512 8360816 7383920
18:18:31 34114 8192000000 10298411936 10747121664 1391064704 14.515 100 44 86 2 1414825728 13164864 10596160
18:20:14 87878 8192000000 10308088640 10769207296 1401672608 14.610 100 38 74 1 1429484272 18920848 8890816
18:24:43 51351 8192000000 10309957712 10762821632 1407528000 14.662 100 17 34 1 1423768144 12460224 3779920
18:26:44 54552 8192000000 10290426208 10763370496 1392097856 14.525 99 17 34 0 1406891072 10753520 4039696
18:29:06 67104 8192000000 10290124496 10761359360 1401540528 14.609 100 19 38 1 1412877568 7317568 4019472
18:31:13 43987 8192000000 10297889008 10752892928 1384298336 14.455 99 28 56 0 1400209824 9733472 6178016
18:32:59 38058 8192000000 10297122336 10751700992 1379337408 14.411 99 11 22 0 1395281744 13591120 2353216
18:34:52 33795 8192000000 10303159264 10756284416 1373962528 14.363 98 25 50 0 1392216496 12649168 5604800
18:36:26 63960 8192000000 10294109968 10760450048 1365191600 14.284 98 12 24 0 1384909392 16931888 2785904
18:38:31 55164 8192000000 10284640128 10750398464 1350298544 14.150 97 19 38 0 1372486976 17685952 4502480
18:40:33 41755 8192000000 10268300208 10716413952 1345049792 14.103 97 30 61 1 1367561392 15850384 6661216
18:42:25 41529 8192000000 10242601808 10684243968 1277613824 13.491 95 16 32 0 1289747136 8648256 3485056
18:44:07 58795 8192000000 10262088640 10702577664 1316326832 13.843 97 24 48 0 1340709584 19243664 5139088
18:46:10 72979 8192000000 10213570640 10655870976 1277719936 13.492 96 12 24 0 1291932736 11350112 2862688
18:48:24 35178 8192000000 10257148592 10692947968 1329570576 13.963 99 35 68 1 1353716368 16094640 8051152
18:50:02 59907 8192000000 10247028000 10698473472 1323581952 13.909 98 16 33 0 1340933344 13654720 3696672
18:53:29 85654 8192000000 10262295392 10722992128 1311343472 13.798 97 12 24 0 1329068832 15236224 2489136
18:56:38 65097 8192000000 10271821760 10727804928 1338429184 14.043 98 13 26 0 1354605072 13202576 2973312
18:58:43 47781 8192000000 10288666064 10726998016 1358174336 14.221 99 18 36 0 1372747216 10632016 3940864
19:00:42 50291 8192000000 10283766656 10724352000 1346034912 14.112 98 18 36 0 1358999216 8865072 4099232
19:02:37 15706 8192000000 10288641312 10723348480 1356197680 14.203 99 35 70 0 1372457632 8283472 7976480
19:03:53 59090 8192000000 10299294912 10742345728 1362513056 14.260 99 25 50 0 1378838528 10968064 5357408
19:05:53 88666 8192000000 10292799312 10749652992 1384564048 14.457 100 15 30 2 1401699904 14153872 2981984
19:08:26 89759 8192000000 10264392400 10726690816 1325258080 13.924 95 18 37 0 1339090848 9866352 3966416
19:10:57 59176 8192000000 10294951840 10755465216 1392293152 14.526 100 17 34 2 1410217760 14131760 3792848
19:13:39 52323 8192000000 10278392016 10747117568 1355530992 14.197 98 4 8 0 1372624272 16398832 694448
19:15:33 51556 8192000000 10317798784 10770382848 1403920272 14.630 100 16 32 0 1418476416 10881792 3674352
19:17:25 47694 8192000000 10320595024 10757844992 1398711264 14.584 100 27 54 1 1413880576 8967104 6202208
19:19:15 65490 8192000000 10308224784 10764763136 1389576720 14.502 99 11 22 0 1402217728 10273472 2367536
19:21:28 49364 8192000000 10315787616 10774593536 1381555648 14.430 98 21 42 0 1398365584 12285008 4524928
19:23:55 148404 8192000000 10291751888 10739195904 1302619440 13.719 93 12 24 0 1322030240 17153760 2257040
19:27:23 66186 8192000000 10276282656 10724450304 1357333328 14.213 98 25 50 0 1373721328 10575952 5812048
19:29:32 42348 8192000000 10268983328 10726137856 1349312480 14.141 98 20 40 0 1365114672 11453104 4349088
19:31:21 75820 8192000000 10238043120 10691846144 1295195696 13.652 95 12 24 0 1305916704 8085536 2635472
19:33:43 45597 8192000000 10261397376 10714660864 1358282064 14.222 100 17 34 0 1377050848 14603552 4165232
19:35:29 78619 8192000000 10245605296 10706755584 1328413456 13.953 98 12 24 0 1346142336 14960768 2768112
19:38:03 31056 8192000000 10270210192 10718375936 1336724320 14.028 98 34 68 0 1357169776 13167552 7277904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-02-2023 19:40:09 to 10-02-2023 19:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-02-2023 19:40:26 to 10-02-2023 19:40:28.

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          241324
MON$OLDEST_ACTIVE               243130
MON$OLDEST_SNAPSHOT             243130
MON$NEXT_TRANSACTION            243130
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                       2024576
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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 ---
:47: 
:48: mscs-test-161.budzdorov.ru    Fri Feb 10 19:38:40 2023
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:50: 
:51: 
:52: mscs-test-161.budzdorov.ru    Fri Feb 10 19:38:41 2023
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:54: 
--- end of diff output ---

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

10.02.23 19:40:29. 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
2023-02-10 19:40:31 No severe PSQL-related problems occured
10.02.23 19:40:33. End of report.