Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.04.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 16573632 kB
MemAvailable 31475860 kB
Buffers 0 kB
Cached 15108624 kB
SwapCached 2688 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.10
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-04-16 19:38:16
DTS_END                         2022-04-16 19:38:16.2530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57310
TRN_ID                          255573
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: 9267.71 19778 1112125
customer order: creation 187.03 343 22443
customer order: refuse 16.13 318 1935
order to supplier: creation 49.33 1348 5919
order to supplier: removal 3.22 90323 386
invoice (draft): creation 49.60 3201 5952
invoice (draft): removal 49.79 700 5975
invoice accept: apply 44.97 19219 5396
invoice accept: cancel 44.54 733 5345
customer reserve: creation 4084.78 195 490174
customer reserve: removal 4430.13 52 531616
realization accept: apply 90.89 167 10907
realization accept: cancel 75.04 106 9005
payment from customer: creation 64.71 123 7765
payment from customer: removal 6.49 61 779
payment to supplier: creation 53.92 103 6470
payment to supplier: removal 3.42 91 410
service: total inventory turnovers 5.15 1030 618
service: total monetary turnovers 7.57 28 908
service: aggregate perf. data 0.98 15521 118
service: refresh index statistics 0.03 261890 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-04-2022 19:39:27 to 16-04-2022 19:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9478
TEST_TIME 2 8968
TEST_TIME 3 7503
TEST_TIME 4 7570
TEST_TIME 5 6847
TEST_TIME 6 7554
TEST_TIME 7 7289
TEST_TIME 8 6829
TEST_TIME 9 6697
TEST_TIME 10 7404
TEST_TIME 11 7547
TEST_TIME 12 7503
TEST_TIME 13 7717
TEST_TIME 14 7749
TEST_TIME 15 8008
TEST_TIME 16 8224
TEST_TIME 17 8451
TEST_TIME 18 8925
TEST_TIME 19 9077
TEST_TIME 20 8861
TEST_TIME 21 9113
TEST_TIME 22 9148
TEST_TIME 23 9370
TEST_TIME 24 9418
TEST_TIME 25 10049
TEST_TIME 26 10529
TEST_TIME 27 10694
TEST_TIME 28 10550
TEST_TIME 29 10492
TEST_TIME 30 10479
TEST_TIME 31 10470
TEST_TIME 32 10197
TEST_TIME 33 10089
TEST_TIME 34 10083
TEST_TIME 35 9916
TEST_TIME 36 9878
TEST_TIME 37 9796
TEST_TIME 38 9774
TEST_TIME 39 9778
TEST_TIME 40 9730
TEST_TIME 41 9594
TEST_TIME 42 9567
TEST_TIME 43 9712
TEST_TIME 44 9604
TEST_TIME 45 9655
TEST_TIME 46 9614
TEST_TIME 47 9539
TEST_TIME 48 9632
TEST_TIME 49 9718
TEST_TIME 50 9646
TEST_TIME 51 9729
TEST_TIME 52 9691
TEST_TIME 53 9633
TEST_TIME 54 9794
TEST_TIME 55 9692
TEST_TIME 56 9803
TEST_TIME 57 9742
TEST_TIME 58 9727
TEST_TIME 59 9703
TEST_TIME 60 9645
TEST_TIME 61 9560
TEST_TIME 62 9530
TEST_TIME 63 9506
TEST_TIME 64 9453
TEST_TIME 65 9393
TEST_TIME 66 9404
TEST_TIME 67 9399
TEST_TIME 68 9450
TEST_TIME 69 9432
TEST_TIME 70 9469
TEST_TIME 71 9458
TEST_TIME 72 9411
TEST_TIME 73 9646
TEST_TIME 74 9749
TEST_TIME 75 9710
TEST_TIME 76 9700
TEST_TIME 77 9689
TEST_TIME 78 9739
TEST_TIME 79 9636
TEST_TIME 80 9581
TEST_TIME 81 9527
TEST_TIME 82 9487
TEST_TIME 83 9562
TEST_TIME 84 9494
TEST_TIME 85 9455
TEST_TIME 86 9383
TEST_TIME 87 9375
TEST_TIME 88 9331
TEST_TIME 89 9365
TEST_TIME 90 9388
TEST_TIME 91 9424
TEST_TIME 92 9451
TEST_TIME 93 9435
TEST_TIME 94 9470
TEST_TIME 95 9463
TEST_TIME 96 9524
TEST_TIME 97 9505
TEST_TIME 98 9529
TEST_TIME 99 9543
TEST_TIME 100 9608
TEST_TIME 101 9580
TEST_TIME 102 9552
TEST_TIME 103 9631
TEST_TIME 104 9630
TEST_TIME 105 9558
TEST_TIME 106 9558
TEST_TIME 107 9517
TEST_TIME 108 9482
TEST_TIME 109 9447
TEST_TIME 110 9459
TEST_TIME 111 9415
TEST_TIME 112 9377
TEST_TIME 113 9370
TEST_TIME 114 9332
TEST_TIME 115 9288
TEST_TIME 116 9316
TEST_TIME 117 9301
TEST_TIME 118 9337
TEST_TIME 119 9318
TEST_TIME 120 9273

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-04-2022 19:39:31 to 16-04-2022 19:39:34.

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 1565693 1565693 0 0.00 0 17833 7 0 0
doc_list_biud 1565693 1565693 0 0.00 0 43592 23 0 0
sp_add_invoice_to_stock 5521 5396 125 2.26 48 216172 19219 0 125
sp_cancel_adding_invoice 5345 5345 0 0.00 3 68234 733 0 0
sp_cancel_client_order 1935 1935 0 0.00 4 11546 318 0 0
sp_cancel_customer_reserve 531616 531616 0 0.00 1 43603 52 0 0
sp_cancel_pay_from_customer 779 779 0 0.00 3 4386 61 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 2 8161 91 0 0
sp_cancel_supplier_invoice 5982 5975 7 0.11 2 31252 700 0 7
sp_cancel_supplier_order 386 386 0 0.00 5 855928 90323 0 0
sp_cancel_write_off 9005 9005 0 0.00 1 12723 106 0 0
sp_client_order 22443 22443 0 0.00 3 41321 344 0 0
sp_customer_reserve 490174 490174 0 0.00 0 157410 195 0 0
sp_fill_shopping_cart 34311 34311 0 0.00 1 13053 36 0 0
sp_fill_shopping_cart_clo_res 490165 490165 0 0.00 0 13184 13 0 0
sp_get_clo_for_invoice 5387 5387 0 0.00 0 17906 256 0 0
sp_kill_cost_storno 15539 15539 0 0.00 0 6928 51 0 0
sp_kill_qstorno_ret_qs2qd 1078598 1078598 0 0.00 0 43588 28 0 0
sp_kill_qty_storno 570556 570556 0 0.00 0 43592 61 0 0
sp_lock_selected_doc 22564 22564 0 0.00 0 9151 6 0 0
sp_make_cost_storno 30529 30529 0 0.00 0 14017 46 0 0
sp_multiply_rows_for_pdistr 30529 30529 0 0.00 0 7192 31 0 0
sp_multiply_rows_for_qdistr 22443 22443 0 0.00 0 8709 140 0 0
sp_pay_from_customer 7765 7765 0 0.00 4 13468 124 0 0
sp_pay_to_supplier 6470 6470 0 0.00 5 9682 104 0 0
sp_payment_common 14235 14235 0 0.00 1 9675 22 0 0
sp_qd_handle_on_cancel_clo 1935 1935 0 0.00 0 11535 287 0 0
sp_qd_handle_on_invoice_upd_sts 10732 10732 0 0.00 0 33545 303 0 0
sp_qd_handle_on_reserve_upd_sts 10907 10907 0 0.00 0 38061 59 0 0
sp_reserve_write_off 10907 10907 0 0.00 7 38347 168 0 0
sp_supplier_invoice 5952 5952 0 0.00 6 73684 3201 0 0
sp_supplier_order 5919 5919 0 0.00 5 65227 1349 0 0
srv_aggregate_perf_data 118 118 0 0.00 2645 140568 15522 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1961 145343 43616 0 0
srv_find_qd_qs_mism 1060415 1060415 0 0.00 0 17833 6 0 0
srv_make_invnt_saldo 618 618 0 0.00 43 24888 1030 0 0
srv_make_money_saldo 908 908 0 0.00 1 4939 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 107991 410456 261891 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5396 5396 0 0.00 0 21147 194 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 4719 95 0 0
t$perf-norm:sp_cancel_client_order 1935 1935 0 0.00 0 4620 20 0 0
t$perf-norm:sp_cancel_customer_reserve 771 771 0 0.00 0 1492 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 779 779 0 0.00 0 1006 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1333 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 457 457 0 0.00 0 3180 26 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 25229 1721 0 0
t$perf-norm:sp_cancel_write_off 1129 1129 0 0.00 0 6025 14 0 0
t$perf-norm:sp_client_order 22443 22443 0 0.00 0 10736 19 0 0
t$perf-norm:sp_customer_reserve 1117 1117 0 0.00 0 758 4 0 0
t$perf-norm:sp_pay_from_customer 7765 7765 0 0.00 0 12130 13 0 0
t$perf-norm:sp_pay_to_supplier 6470 6470 0 0.00 0 13199 14 0 0
t$perf-norm:sp_reserve_write_off 10907 10907 0 0.00 0 7181 14 0 0
t$perf-norm:sp_supplier_invoice 5952 5952 0 0.00 0 4080 18 0 0
t$perf-norm:sp_supplier_order 5919 5919 0 0.00 0 6325 19 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 267 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 306 11 0 0
t$perf-norm:srv_make_invnt_saldo 618 618 0 0.00 0 3980 34 0 0
t$perf-norm:srv_make_money_saldo 908 908 0 0.00 0 6414 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 11016 10766 250 2.26 0 6555 16 0 250
v_all_customers 40596 40596 0 0.00 0 76 1 0 0
v_all_suppliers 32520 32520 0 0.00 0 76 1 0 0
v_all_wares 44886 44886 0 0.00 0 85 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 665 6 0 0
v_cancel_client_order 3870 3870 0 0.00 0 3975 6 0 0
v_cancel_customer_prepayment 1558 1558 0 0.00 0 1061 3 0 0
v_cancel_customer_reserve 1542 1542 0 0.00 0 1235 5 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 5019 11 0 0
v_cancel_supplier_invoice 928 914 14 1.50 0 3322 19 0 14
v_cancel_supplier_order 772 772 0 0.00 0 1149 5 0 0
v_cancel_write_off 2258 2258 0 0.00 0 4292 15 0 0
v_max_id_clo_ord 5918 5918 0 0.00 0 680 1 0 0
v_max_id_clo_res 1117 1117 0 0.00 0 1612 13 0 0
v_max_id_ord_sup 5950 5950 0 0.00 0 7243 5 0 0
v_max_non_paid_invoice 2078 2078 0 0.00 0 132 2 0 0
v_max_non_paid_realizn 4333 4333 0 0.00 0 5580 5 0 0
v_min_id_clo_ord 5918 5918 0 0.00 0 3640 7 0 0
v_min_id_clo_res 1117 1117 0 0.00 0 4577 14 0 0
v_min_id_ord_sup 5950 5950 0 0.00 0 3919 6 0 0
v_min_non_paid_invoice 6470 6470 0 0.00 0 9673 12 0 0
v_min_non_paid_realizn 7765 7765 0 0.00 1 7555 18 0 0
v_our_firm 11154 11154 0 0.00 0 60 1 0 0
v_reserve_write_off 21814 21814 0 0.00 0 5580 7 0 0
x_lock_depdocs_on_canc_invoice 10863 10863 0 0.00 0 17846 111 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 20768 331 0 0
x_make_qstorno_1000_1200 5919 5919 0 0.00 1 65204 1269 0 0
x_make_qstorno_1200_2000 5952 5952 0 0.00 3 70771 3073 0 0
x_make_qstorno_2100_3300 490174 490174 0 0.00 0 157407 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-04-2022 19:39:35 to 16-04-2022 19:39:39.

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:20 2 8192000000 8802512720 8814956544 7763376 0.094 2 2 3 0 7904464 66144 74944
17:09:20 32580 8192000000 9914554624 10228789248 998063072 10.860 100 82 165 1 1030604096 11246288 21294736
17:11:02 22693 8192000000 9951688224 10273566720 1030439600 11.173 100 87 174 1 1062063856 9141120 22483136
17:12:34 12422 8192000000 9984247616 10317459456 1068390128 11.537 100 81 162 0 1098980576 10203680 20386768
17:13:49 12957 8192000000 10059920032 10408419328 1139341312 12.209 100 85 170 0 1171040752 11790560 19908880
17:15:04 6848 8192000000 10094933872 10456219648 1184561232 12.633 100 86 172 1 1218033136 13629792 19842112
17:16:12 3452 8192000000 10136230080 10509021184 1224178928 13.000 100 79 158 1 1255156912 13865920 17112064
17:17:17 2935 8192000000 10173511392 10552643584 1260227792 13.332 100 87 171 2 1292592560 12545520 19819248
17:18:22 1246 8192000000 10213236720 10593136640 1291133456 13.615 100 91 182 0 1325229088 13461616 20634016
17:19:25 19686 8192000000 10193162928 10600304640 1302164256 13.715 100 67 135 0 1325847712 7687904 15995552
17:20:46 8277 8192000000 10206598624 10617597952 1313457344 13.817 100 18 36 0 1327571488 10523536 3590608
17:21:55 5015 8192000000 10210251424 10626371584 1322299200 13.898 100 23 45 0 1333873968 5955568 5619200
17:23:00 8215 8192000000 10208230448 10628898816 1329885088 13.966 100 16 32 0 1338909360 5751872 3272400
17:24:09 19953 8192000000 10243192672 10647113728 1329759632 13.965 100 72 145 2 1353110704 6485616 16865456
17:25:30 9168 8192000000 10236693104 10651336704 1337823520 14.038 100 20 40 0 1348052560 5204560 5024480
17:26:41 8893 8192000000 10233727088 10653941760 1342476528 14.080 100 19 38 2 1351950160 5520320 3953312
17:27:56 31701 8192000000 10223685376 10623401984 1297498976 13.672 98 59 119 0 1323389984 12054384 13836624
17:29:28 7585 8192000000 10155350608 10556493824 1233721344 13.088 96 25 49 3 1245325760 5302448 6301968
17:30:38 4912 8192000000 10139069472 10536099840 1219336880 12.956 97 25 48 2 1229801776 4843744 5621152
17:31:44 11194 8192000000 10162787808 10561843200 1265239808 13.378 100 31 62 0 1279945040 6124112 8581120
17:32:56 13944 8192000000 10148355008 10551828480 1238736224 13.135 98 17 32 2 1247818288 5339312 3742752
17:34:13 8680 8192000000 10185301312 10594271232 1293613120 13.637 100 25 50 1 1304813584 5657664 5542800
17:35:22 14545 8192000000 10191418112 10609016832 1305292208 13.743 100 22 44 1 1316490208 5997712 5200288
17:36:37 10412 8192000000 10206979360 10617827328 1313426688 13.817 100 23 45 2 1324399968 5429040 5544240
17:37:49 33920 8192000000 10222462256 10638729216 1326378864 13.934 100 26 52 2 1337425680 5181360 5865456
17:39:30 5051 8192000000 10256594992 10669109248 1349996496 14.147 100 53 106 3 1371141248 9187504 11957248
17:40:39 3162 8192000000 10280720608 10696396800 1366038688 14.292 100 61 121 1 1390247152 9585040 14623424
17:41:48 2580 8192000000 10292236768 10706923520 1376411872 14.384 100 61 121 2 1402035152 12334160 13289120
17:42:54 16867 8192000000 10299421392 10725298176 1384820336 14.460 100 39 78 2 1406350992 12726096 8804560
17:44:16 7412 8192000000 10299541184 10723876864 1386169968 14.472 100 40 80 1 1411281472 15167952 9943552
17:45:31 10403 8192000000 10285476288 10708934656 1359109088 14.229 98 46 91 0 1383054416 12414928 11530400
17:46:50 7024 8192000000 10313320192 10733297664 1394736736 14.548 100 64 126 2 1425758352 16484000 14537616
17:48:03 3740 8192000000 10289561808 10701410304 1358333504 14.222 98 59 118 0 1391324288 18803232 14187552
17:49:12 5494 8192000000 10282609504 10694033408 1350650016 14.153 99 58 114 1 1382798672 18067648 14081008
17:50:22 5096 8192000000 10298106656 10703634432 1363153104 14.266 100 81 162 0 1404613920 21948032 19512784
17:51:34 8324 8192000000 10312070240 10717306880 1371574624 14.341 100 66 132 1 1414481264 26518608 16388032
17:52:46 1961 8192000000 10277617200 10678505472 1333809408 14.002 99 73 145 0 1381198640 29202672 18186560
17:53:54 12656 8192000000 10314915392 10726371328 1372511920 14.350 100 66 130 1 1421594672 33385728 15697024
17:55:15 3516 8192000000 10342328192 10755756032 1390314832 14.509 100 76 148 3 1444761200 35428368 19018000
17:56:24 5004 8192000000 10337910640 10742173696 1380266288 14.419 100 78 156 1 1434185424 33784160 20134976
17:57:33 15542 8192000000 10313612640 10738888704 1373715440 14.360 99 42 84 1 1417466832 32846528 10904864
17:58:53 8537 8192000000 10336943568 10752180224 1387893168 14.487 100 60 118 0 1438042816 34288240 15861408
18:00:08 8815 8192000000 10322031664 10751688704 1387189648 14.481 100 41 82 0 1432124896 33249808 11685440
18:01:19 23417 8192000000 10304099792 10746392576 1383607152 14.449 100 18 36 1 1421476752 32525728 5343872
18:02:56 16618 8192000000 10312255584 10748555264 1365047744 14.283 99 27 52 2 1402875600 30926720 6901136
18:04:13 39375 8192000000 10274863696 10719137792 1347906560 14.129 99 15 30 0 1373557232 22015888 3634784
18:06:11 26486 8192000000 10282386480 10727628800 1377035712 14.390 100 17 35 0 1390209568 9121760 4052096
18:07:44 41006 8192000000 10291276976 10734759936 1366311776 14.294 99 31 62 1 1386828448 13060688 7455984
18:09:37 99224 8192000000 10312385152 10750627840 1382632624 14.440 99 36 69 1 1399223584 7938672 8652288
18:12:32 145343 8192000000 10299178960 10736631808 1315943504 13.840 94 12 24 0 1325936576 7010960 2982112
18:15:58 35881 8192000000 10305906064 10757808128 1386532208 14.475 99 19 38 2 1405448624 14184224 4732192
18:17:40 82064 8192000000 10317603120 10774949888 1411126720 14.694 100 15 29 0 1427452304 12289136 4036448
18:20:45 17437 8192000000 10301179200 10773508096 1385339744 14.464 99 10 20 0 1407749952 19439936 2970272
18:22:03 58067 8192000000 10291174560 10753290240 1364781040 14.280 97 16 32 0 1375860592 7298992 3780560
18:24:18 37232 8192000000 10302926176 10762039296 1398920032 14.585 100 23 46 2 1412147984 8321584 4906368
18:26:03 37996 8192000000 10295776064 10748264448 1363305376 14.267 98 33 66 0 1390063232 19650992 7106864
18:27:41 26807 8192000000 10282953184 10741317632 1369705744 14.324 99 20 40 0 1384575296 10163984 4705568
18:29:09 73682 8192000000 10269626832 10712178688 1283045088 13.541 93 20 40 0 1296363376 8952608 4365680
18:31:23 27260 8192000000 10304288592 10742525952 1373984848 14.363 100 45 88 1 1396437104 12376688 10075568
18:33:04 44360 8192000000 10289529504 10733887488 1334381680 14.007 97 20 40 1 1348920000 10263344 4274976
18:34:52 61120 8192000000 10282702688 10722177024 1308755088 13.775 95 27 55 2 1323565264 8584128 6226048
18:36:58 98480 8192000000 10234572096 10688069632 1317754768 13.856 98 23 48 3 1338431552 15249408 5427376
18:39:39 46166 8192000000 10259920736 10694823936 1339581392 14.054 99 39 79 0 1357955840 9687728 8686720
18:41:26 46269 8192000000 10254119664 10705371136 1351200704 14.158 100 20 37 2 1364663152 9377792 4084656
18:43:31 27090 8192000000 10259322256 10707410944 1330291152 13.970 98 10 20 0 1340291600 7761744 2238704
18:44:58 46255 8192000000 10279711984 10729541632 1367076640 14.301 100 20 40 0 1384807968 13102240 4629088
18:47:03 63589 8192000000 10285360208 10735296512 1361635072 14.252 99 15 30 2 1378688352 13878288 3174992
18:49:50 41699 8192000000 10322258096 10769006592 1390461728 14.510 100 23 46 0 1415500272 19097568 5940976
18:52:05 85927 8192000000 10290731680 10741452800 1362863008 14.263 98 8 16 0 1384979840 20334032 1782800
18:54:33 54121 8192000000 10313863536 10756108288 1379037232 14.408 99 32 64 0 1396121360 10137584 6946544
18:56:28 76576 8192000000 10300729520 10759737344 1379925472 14.416 99 21 42 0 1400670848 16263456 4481920
18:58:46 84777 8192000000 10292952192 10746212352 1379856240 14.415 99 19 38 0 1393899856 10003600 4040016
19:01:12 73668 8192000000 10281262896 10761830400 1383723936 14.450 100 13 26 1 1401933808 14018240 4191632
19:03:31 81228 8192000000 10317871824 10776494080 1403426688 14.625 100 10 19 1 1423087824 17358848 2302288
19:05:52 41855 8192000000 10304310080 10765897728 1392054144 14.524 99 18 36 0 1411569184 14266016 5249024
19:07:34 108403 8192000000 10284789968 10755694592 1323673664 13.910 94 13 25 2 1341350144 14271504 3404976
19:10:26 73834 8192000000 10290919280 10749874176 1357925120 14.219 97 17 34 0 1374008096 12333584 3749392
19:12:40 35250 8192000000 10317038720 10767024128 1361229856 14.248 97 34 68 0 1379651776 11107696 7314224
19:14:25 76047 8192000000 10291356240 10743140352 1314250128 13.825 94 15 30 2 1329463664 12287728 2925808
19:16:43 67323 8192000000 10310508000 10762010624 1350226432 14.150 98 21 42 3 1368546624 13865920 4454272
19:18:51 68642 8192000000 10278454800 10717814784 1300855488 13.703 94 20 40 0 1313381280 8493376 4032416
19:21:00 48011 8192000000 10292518336 10759073792 1365503040 14.287 99 25 50 1 1391185392 20207312 5475040
19:22:48 137457 8192000000 10272542048 10728280064 1335607664 14.018 97 14 28 1 1349475808 10518016 3350128
19:26:08 24876 8192000000 10266509280 10718720000 1342203024 14.077 98 22 43 1 1359970480 13299584 4467872
19:27:39 55985 8192000000 10259823584 10709917696 1327305472 13.943 98 15 30 0 1348215584 17372208 3537904
19:29:42 59405 8192000000 10245527952 10700140544 1315930816 13.840 97 20 40 0 1336731136 16594464 4205856
19:31:41 49688 8192000000 10254874128 10707218432 1344315856 14.096 99 14 29 1 1358688704 10882480 3490368
19:33:32 81596 8192000000 10236042352 10690277376 1303016736 13.723 96 13 26 0 1314996432 9596400 2383296
19:35:53 35162 8192000000 10250266288 10704998400 1322102096 13.896 98 22 43 2 1338049296 11469616 4477584
19:37:30 30749 8192000000 10270366240 10712072192 1328112384 13.950 98 34 66 2 1351775680 16258880 7404416

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-04-2022 19:39:41 to 16-04-2022 19:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-04-2022 19:39:57 to 16-04-2022 19:39:59.

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          254516
MON$OLDEST_ACTIVE               256065
MON$OLDEST_SNAPSHOT             256065
MON$NEXT_TRANSACTION            256065
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                       2034528
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:535: 
:536: mscs-test-161.budzdorov.ru    Sat Apr 16 19:38:25 2022
:537:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:538: 
--- end of diff output ---

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

16.04.22 19:40:00. 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-04-16 19:40:02 No severe PSQL-related problems occured
16.04.22 19:40:04. End of report.