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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16586484 kB
MemAvailable 31539396 kB
Buffers 0 kB
Cached 15099216 kB
SwapCached 2888 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-26 01:38:13
DTS_END                         2022-04-26 01:38:13.7360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56926
TRN_ID                          240190
ATT_ID                          655
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: 9590.93 19974 1150912
customer order: creation 170.66 351 20479
customer order: refuse 14.29 346 1715
order to supplier: creation 46.35 1267 5562
order to supplier: removal 3.47 102838 416
invoice (draft): creation 44.91 3328 5389
invoice (draft): removal 54.49 837 6539
invoice accept: apply 41.68 18460 5002
invoice accept: cancel 49.64 782 5957
customer reserve: creation 3934.84 178 472181
customer reserve: removal 4931.70 55 591804
realization accept: apply 82.61 156 9913
realization accept: cancel 86.88 124 10426
payment from customer: creation 58.77 109 7052
payment from customer: removal 5.57 81 668
payment to supplier: creation 49.20 111 5904
payment to supplier: removal 3.74 48 449
service: total inventory turnovers 4.78 1224 573
service: total monetary turnovers 6.42 31 770
service: aggregate perf. data 0.91 16359 109
service: refresh index statistics 0.03 252792 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-04-2022 01:39:18 to 26-04-2022 01:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10309
TEST_TIME 2 8068
TEST_TIME 3 6783
TEST_TIME 4 6708
TEST_TIME 5 6800
TEST_TIME 6 6478
TEST_TIME 7 6803
TEST_TIME 8 6648
TEST_TIME 9 6884
TEST_TIME 10 7517
TEST_TIME 11 7527
TEST_TIME 12 7690
TEST_TIME 13 8005
TEST_TIME 14 8012
TEST_TIME 15 7898
TEST_TIME 16 8071
TEST_TIME 17 8150
TEST_TIME 18 8516
TEST_TIME 19 8812
TEST_TIME 20 8931
TEST_TIME 21 9096
TEST_TIME 22 9226
TEST_TIME 23 9128
TEST_TIME 24 9281
TEST_TIME 25 9251
TEST_TIME 26 9254
TEST_TIME 27 9735
TEST_TIME 28 10350
TEST_TIME 29 11487
TEST_TIME 30 11148
TEST_TIME 31 11124
TEST_TIME 32 10949
TEST_TIME 33 10960
TEST_TIME 34 10980
TEST_TIME 35 10930
TEST_TIME 36 10802
TEST_TIME 37 10773
TEST_TIME 38 10623
TEST_TIME 39 10533
TEST_TIME 40 10454
TEST_TIME 41 10398
TEST_TIME 42 10177
TEST_TIME 43 10129
TEST_TIME 44 10155
TEST_TIME 45 10103
TEST_TIME 46 10027
TEST_TIME 47 9902
TEST_TIME 48 9844
TEST_TIME 49 9772
TEST_TIME 50 9744
TEST_TIME 51 9819
TEST_TIME 52 9711
TEST_TIME 53 9613
TEST_TIME 54 9592
TEST_TIME 55 9600
TEST_TIME 56 9582
TEST_TIME 57 9556
TEST_TIME 58 9548
TEST_TIME 59 9544
TEST_TIME 60 9494
TEST_TIME 61 9605
TEST_TIME 62 9629
TEST_TIME 63 9632
TEST_TIME 64 9625
TEST_TIME 65 9673
TEST_TIME 66 9799
TEST_TIME 67 9731
TEST_TIME 68 9892
TEST_TIME 69 9935
TEST_TIME 70 9913
TEST_TIME 71 9872
TEST_TIME 72 9780
TEST_TIME 73 9772
TEST_TIME 74 9688
TEST_TIME 75 9694
TEST_TIME 76 9713
TEST_TIME 77 9714
TEST_TIME 78 9647
TEST_TIME 79 9603
TEST_TIME 80 9560
TEST_TIME 81 9587
TEST_TIME 82 9533
TEST_TIME 83 9666
TEST_TIME 84 9648
TEST_TIME 85 9614
TEST_TIME 86 9570
TEST_TIME 87 9629
TEST_TIME 88 9609
TEST_TIME 89 9667
TEST_TIME 90 9652
TEST_TIME 91 9668
TEST_TIME 92 9693
TEST_TIME 93 9709
TEST_TIME 94 9754
TEST_TIME 95 9769
TEST_TIME 96 9778
TEST_TIME 97 9720
TEST_TIME 98 9799
TEST_TIME 99 9773
TEST_TIME 100 9734
TEST_TIME 101 9698
TEST_TIME 102 9738
TEST_TIME 103 9663
TEST_TIME 104 9669
TEST_TIME 105 9665
TEST_TIME 106 9674
TEST_TIME 107 9639
TEST_TIME 108 9643
TEST_TIME 109 9573
TEST_TIME 110 9542
TEST_TIME 111 9574
TEST_TIME 112 9590
TEST_TIME 113 9567
TEST_TIME 114 9540
TEST_TIME 115 9607
TEST_TIME 116 9656
TEST_TIME 117 9677
TEST_TIME 118 9686
TEST_TIME 119 9659
TEST_TIME 120 9596

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-04-2022 01:39:22 to 26-04-2022 01:39:25.

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 1586269 1586269 0 0.00 0 20695 6 0 0
doc_list_biud 1586269 1586269 0 0.00 0 20758 27 0 0
sp_add_invoice_to_stock 5111 5002 109 2.13 26 170333 18461 0 109
sp_cancel_adding_invoice 5957 5957 0 0.00 4 59092 783 0 0
sp_cancel_client_order 1715 1715 0 0.00 4 12190 347 0 0
sp_cancel_customer_reserve 591804 591804 0 0.00 1 20761 56 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 4175 81 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 3 632 48 0 0
sp_cancel_supplier_invoice 6546 6539 7 0.10 1 19591 837 0 7
sp_cancel_supplier_order 416 416 0 0.00 7 1026130 102839 0 0
sp_cancel_write_off 10426 10426 0 0.00 1 18090 124 0 0
sp_client_order 20479 20479 0 0.00 3 18005 351 0 0
sp_customer_reserve 472181 472181 0 0.00 0 31770 179 0 0
sp_fill_shopping_cart 31408 31408 0 0.00 1 30525 42 0 0
sp_fill_shopping_cart_clo_res 472169 472169 0 0.00 0 18592 12 0 0
sp_get_clo_for_invoice 4990 4990 0 0.00 0 22503 304 0 0
sp_kill_cost_storno 17500 17500 0 0.00 0 8205 57 0 0
sp_kill_qstorno_ret_qs2qd 1200989 1200989 0 0.00 0 20753 31 0 0
sp_kill_qty_storno 631760 631760 0 0.00 0 20758 66 0 0
sp_lock_selected_doc 20625 20625 0 0.00 0 6984 4 0 0
sp_make_cost_storno 27859 27859 0 0.00 0 20605 52 0 0
sp_multiply_rows_for_pdistr 27859 27859 0 0.00 0 9416 25 0 0
sp_multiply_rows_for_qdistr 20479 20479 0 0.00 0 14926 135 0 0
sp_pay_from_customer 7052 7052 0 0.00 4 15957 110 0 0
sp_pay_to_supplier 5904 5904 0 0.00 5 14250 112 0 0
sp_payment_common 12956 12956 0 0.00 2 15944 27 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 11841 317 0 0
sp_qd_handle_on_invoice_upd_sts 10947 10947 0 0.00 0 20733 326 0 0
sp_qd_handle_on_reserve_upd_sts 9913 9913 0 0.00 0 14504 56 0 0
sp_reserve_write_off 9913 9913 0 0.00 7 24385 156 0 0
sp_supplier_invoice 5389 5389 0 0.00 6 79212 3329 0 0
sp_supplier_order 5562 5562 0 0.00 5 27378 1267 0 0
srv_aggregate_perf_data 110 109 1 0.90 2733 147637 16360 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1821 124655 46615 0 0
srv_find_qd_qs_mism 1101989 1101989 0 0.00 0 19121 5 0 0
srv_make_invnt_saldo 573 573 0 0.00 39 50299 1224 0 0
srv_make_money_saldo 770 770 0 0.00 1 5140 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 96680 338074 252793 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5002 5002 0 0.00 0 12361 185 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 6398 85 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 3117 11 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 4825 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 604 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 3325 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 1750 19 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 30527 1658 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 3069 12 0 0
t$perf-norm:sp_client_order 20479 20479 0 0.00 0 15107 19 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 3594 7 0 0
t$perf-norm:sp_pay_from_customer 7052 7052 0 0.00 0 5284 9 0 0
t$perf-norm:sp_pay_to_supplier 5904 5904 0 0.00 0 5161 8 0 0
t$perf-norm:sp_reserve_write_off 9913 9913 0 0.00 0 7260 12 0 0
t$perf-norm:sp_supplier_invoice 5389 5389 0 0.00 0 8418 20 0 0
t$perf-norm:sp_supplier_order 5562 5562 0 0.00 0 5352 18 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 477 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 7 0 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 2171 27 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 1442 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 9 0 0
v_add_invoice_to_stock 10196 9978 218 2.13 0 10985 21 0 218
v_all_customers 37170 37170 0 0.00 0 97 1 0 0
v_all_suppliers 30410 30410 0 0.00 0 20 1 0 0
v_all_wares 40958 40958 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 4882 16 0 0
v_cancel_client_order 3430 3430 0 0.00 0 3587 7 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 2245 10 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 1389 4 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 199 2 0 0
v_cancel_supplier_invoice 806 792 14 1.73 0 1195 18 0 14
v_cancel_supplier_order 832 832 0 0.00 0 690 5 0 0
v_cancel_write_off 1966 1966 0 0.00 0 5019 18 0 0
v_max_id_clo_ord 5561 5561 0 0.00 0 1054 1 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 1495 9 0 0
v_max_id_ord_sup 5368 5368 0 0.00 0 4589 4 0 0
v_max_non_paid_invoice 1628 1628 0 0.00 0 6345 9 0 0
v_max_non_paid_realizn 3805 3805 0 0.00 0 3938 4 0 0
v_min_id_clo_ord 5561 5561 0 0.00 0 3734 7 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 162 2 0 0
v_min_id_ord_sup 5368 5368 0 0.00 0 8952 8 0 0
v_min_non_paid_invoice 5904 5904 0 0.00 0 7447 20 0 0
v_min_non_paid_realizn 7052 7052 0 0.00 0 15939 19 0 0
v_our_firm 10282 10282 0 0.00 0 15 1 0 0
v_reserve_write_off 19826 19826 0 0.00 0 4181 6 0 0
x_lock_depdocs_on_canc_invoice 12100 12100 0 0.00 0 12941 117 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 5574 351 0 0
x_make_qstorno_1000_1200 5562 5562 0 0.00 1 27364 1182 0 0
x_make_qstorno_1200_2000 5389 5389 0 0.00 3 76936 3171 0 0
x_make_qstorno_2100_3300 472181 472181 0 0.00 0 25670 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-04-2022 01:39:26 to 26-04-2022 01:39:30.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:17 15 8192000000 8809627248 8824397824 14188144 0.172 4 3 5 0 14382624 77360 117120
23:09:18 29371 8192000000 9888338304 10199769088 970779056 10.594 100 84 168 0 999279168 6168896 22331216
23:10:54 13780 8192000000 9946143968 10268504064 1028602736 11.155 100 93 186 0 1061527504 8651520 24273248
23:12:19 14531 8192000000 9987083392 10320101376 1069985216 11.552 100 85 170 0 1101412288 10168832 21258240
23:13:50 15139 8192000000 10059234736 10409058304 1137571728 12.193 100 86 172 0 1169700032 11998912 20129392
23:15:08 11351 8192000000 10096962192 10457092096 1182964816 12.618 100 83 165 1 1214691296 12762864 18963616
23:16:22 5328 8192000000 10141853216 10513367040 1226219824 13.019 100 86 172 0 1259371552 14013376 19138352
23:17:29 4048 8192000000 10173585712 10551713792 1259224864 13.323 100 81 163 0 1290094560 13176528 17693168
23:18:35 1603 8192000000 10203077760 10585079808 1287185264 13.579 100 90 179 0 1321371936 14259632 19927040
23:19:37 1908 8192000000 10210806160 10596642816 1297441504 13.672 100 80 158 1 1327582944 12855616 17285824
23:20:40 3069 8192000000 10198947664 10606977024 1310315936 13.789 100 47 93 1 1328463008 7589168 10557904
23:21:45 11242 8192000000 10201142608 10617114624 1317191440 13.851 100 16 31 0 1326088880 5558896 3338544
23:22:57 5992 8192000000 10208946224 10629156864 1326612400 13.937 100 19 38 0 1337270112 6077488 4580224
23:24:04 43962 8192000000 10248876800 10647142400 1335546704 14.017 100 57 112 1 1355019584 5889952 13582928
23:25:49 8482 8192000000 10235715056 10652930048 1337616912 14.036 100 27 54 0 1350279872 6516976 6145984
23:26:58 14790 8192000000 10208526752 10629599232 1279787376 13.511 96 15 30 1 1292748720 9872288 3089056
23:28:13 53317 8192000000 10190278640 10604630016 1208472976 12.855 91 27 56 4 1220422480 4911120 7038384
23:30:07 9692 8192000000 10167195984 10572288000 1236760688 13.116 96 22 44 2 1247875696 5929056 5185952
23:31:17 13230 8192000000 10131634288 10525327360 1169625152 12.493 93 25 49 1 1180903504 5560064 5718288
23:32:31 8498 8192000000 10142784096 10545946624 1255532496 13.289 100 27 52 0 1268741888 5745360 7464032
23:33:42 9340 8192000000 10159255584 10564153344 1260920320 13.338 99 20 40 0 1271384832 5518016 4946496
23:34:54 9440 8192000000 10183421328 10592976896 1291495664 13.618 100 24 48 3 1302675264 6088960 5090640
23:36:04 11809 8192000000 10190000288 10606141440 1302173616 13.715 100 17 34 1 1312233040 5669744 4389680
23:37:18 65729 8192000000 10220777264 10626973696 1318132320 13.860 100 30 61 2 1332072448 5529696 8410432
23:39:30 4744 8192000000 10257410192 10664443904 1346382896 14.115 100 60 120 1 1369779680 10056080 13340704
23:40:38 14670 8192000000 10266175008 10687635456 1363141792 14.266 100 42 84 1 1383210352 10454240 9614320
23:42:13 18527 8192000000 10294578480 10707058688 1375541600 14.377 100 60 120 4 1404768080 15499392 13727088
23:43:35 11307 8192000000 10278951488 10705088512 1371490720 14.340 99 32 64 1 1392508064 13012880 8004464
23:44:49 3121 8192000000 10305657168 10719395840 1377477104 14.394 99 59 117 2 1409545968 17019232 15049632
23:45:56 18248 8192000000 10316701040 10740621312 1397108304 14.569 100 46 92 1 1426840000 19080176 10651520
23:47:19 5893 8192000000 10331125072 10756222976 1407590800 14.663 100 64 126 2 1443588192 20384608 15612784
23:48:31 2471 8192000000 10349002352 10763063296 1403778704 14.629 100 73 148 1 1448780880 26740560 18261616
23:49:36 2900 8192000000 10333723936 10749415424 1397564800 14.573 100 84 170 1 1442912192 24682672 20664720
23:50:44 4836 8192000000 10338877504 10760658944 1402259872 14.615 100 64 128 1 1446029248 27961984 15807392
23:51:52 4410 8192000000 10340971760 10757271552 1398364560 14.580 100 63 124 1 1442177232 28544480 15268192
23:53:01 2067 8192000000 10349287888 10761060352 1400059376 14.596 100 84 166 2 1453623056 32560576 21003104
23:54:08 4257 8192000000 10342986000 10751680512 1379452736 14.412 99 85 168 3 1440067568 39497440 21117392
23:55:18 2996 8192000000 10364424464 10780557312 1391564736 14.520 99 71 142 4 1458834672 47619936 19650000
23:56:29 3831 8192000000 10361504528 10767794176 1386174448 14.472 100 86 173 1 1461974480 54064848 21735184
23:57:47 1821 8192000000 10348511824 10747318272 1366645856 14.297 100 92 183 0 1442607536 52772896 23188784
23:58:51 8573 8192000000 10316462912 10738540544 1351105776 14.157 99 40 79 2 1414884800 53101168 10677856
00:00:13 5324 8192000000 10334706912 10753544192 1369723872 14.325 100 48 95 1 1437768512 55777376 12267264
00:01:23 3289 8192000000 10324006656 10742644736 1352226832 14.168 99 39 77 0 1418559040 56039888 10292320
00:02:29 15450 8192000000 10353813472 10778148864 1383862096 14.451 100 41 79 1 1458854048 63229104 11762848
00:04:04 21188 8192000000 10337670368 10765762560 1355165056 14.194 99 31 62 1 1431995952 67634128 9196768
00:05:36 36443 8192000000 10326591680 10772881408 1359214560 14.230 100 23 46 1 1442624416 75751648 7658208
00:07:31 50669 8192000000 10302214688 10740301824 1375306672 14.375 100 14 28 1 1402381840 23736144 3339024
00:09:46 36876 8192000000 10305106240 10747584512 1378953776 14.407 99 21 42 2 1399088384 15695216 4439392
00:11:25 58182 8192000000 10311456704 10773835776 1398097136 14.578 100 12 24 0 1419242768 18325776 2819856
00:13:23 59566 8192000000 10312053616 10764652544 1384434448 14.456 99 21 42 1 1413586880 23767568 5384864
00:15:29 84213 8192000000 10288996096 10734964736 1379435360 14.412 99 21 42 0 1395628960 11355872 4837728
00:18:11 37758 8192000000 10303834464 10760646656 1399438768 14.590 100 24 49 0 1418624352 13975584 5210000
00:20:15 70002 8192000000 10313181408 10761338880 1389273232 14.499 99 44 88 1 1407099104 7909792 9916080
00:22:28 79344 8192000000 10321159200 10767114240 1378164192 14.400 98 22 44 0 1392569232 10328912 4076128
00:24:51 59926 8192000000 10306055264 10764189696 1405838816 14.647 100 16 32 0 1422138256 12562080 3737360
00:27:12 75037 8192000000 10307375312 10764697600 1392652256 14.530 99 13 26 0 1412329280 16320848 3356176
00:29:29 49699 8192000000 10290677648 10761388032 1394065472 14.542 100 7 14 0 1409382000 13512432 1804096
00:31:20 56549 8192000000 10283112656 10745876480 1384762880 14.459 99 7 14 0 1393785232 7413024 1609328
00:33:18 81228 8192000000 10306325504 10775334912 1401011680 14.604 100 11 23 0 1416083616 12463200 2608736
00:35:42 53766 8192000000 10295732064 10759110656 1398074608 14.578 100 11 22 0 1408269920 7902080 2293232
00:37:36 29655 8192000000 10296435120 10753114112 1372190544 14.347 98 7 14 0 1382532448 9071808 1270096
00:39:08 87527 8192000000 10273465344 10729287680 1334312912 14.006 96 4 8 0 1345384112 10268528 802672
00:41:36 72105 8192000000 10301448992 10746527744 1312567648 13.809 94 24 48 0 1334587664 17340480 4679536
00:43:49 67943 8192000000 10272330160 10737909760 1335593216 14.018 97 17 34 2 1357120096 16938368 4588512
00:45:57 60768 8192000000 10241296848 10698469376 1266562672 13.390 94 16 32 1 1286136160 15961456 3612032
00:47:59 71492 8192000000 10245933152 10676609024 1271778688 13.438 96 16 32 2 1295010192 20065552 3165952
00:50:11 75696 8192000000 10223026864 10660585472 1285668352 13.565 97 21 43 1 1303397072 12757344 4971376
00:52:27 62665 8192000000 10245519008 10692210688 1331608176 13.982 99 25 50 0 1353344880 16039728 5696976
00:54:33 84314 8192000000 10244576096 10691588096 1321029536 13.886 99 15 29 0 1342193712 17294976 3869200
00:57:02 79334 8192000000 10239361600 10682908672 1299804240 13.693 97 24 45 4 1321243488 15640112 5799136
00:59:26 66038 8192000000 10273938592 10713513984 1341197360 14.068 99 18 36 1 1359203248 13465568 4540320
01:02:18 78766 8192000000 10254618608 10703294464 1321057728 13.886 98 10 20 0 1335138960 11987616 2093616
01:04:38 60413 8192000000 10277897856 10736300032 1371000112 14.336 100 32 64 1 1398518208 19839584 7678512
01:06:39 63470 8192000000 10270808160 10722705408 1335618832 14.018 97 35 70 0 1356057248 12563984 7874432
01:08:46 19165 8192000000 10293207232 10731057152 1373120544 14.355 100 39 78 1 1393233376 12005008 8107824
01:10:09 94145 8192000000 10309909024 10760228864 1386298592 14.473 100 17 34 0 1403285936 13532672 3454672
01:12:46 124655 8192000000 10318133616 10775740416 1373467632 14.358 98 16 32 0 1393544640 16268000 3809008
01:15:53 57081 8192000000 10311346880 10762772480 1390012640 14.506 99 23 46 0 1406922400 11913808 4995952
01:17:54 99949 8192000000 10302391136 10768973824 1403911216 14.630 100 24 48 0 1419030880 9675392 5444272
01:20:35 83458 8192000000 10316665104 10768928768 1401976560 14.613 100 38 76 3 1420694976 9941616 8776800
01:22:59 77167 8192000000 10286386704 10749423616 1347565056 14.126 96 14 28 0 1365533168 14365824 3602288
01:25:19 65870 8192000000 10297109232 10756022272 1371933056 14.344 98 18 36 0 1389326832 12983616 4410160
01:27:25 86985 8192000000 10284734528 10753703936 1382211296 14.436 99 13 26 0 1396519552 11012064 3296192
01:29:56 74661 8192000000 10287603456 10749759488 1367852128 14.308 98 9 18 0 1382991552 13258400 1881024
01:32:17 14146 8192000000 10313302688 10751049728 1373419280 14.358 98 53 106 1 1397478960 11661232 12398448
01:33:34 103394 8192000000 10284610304 10731855872 1353564192 14.180 98 14 28 0 1376187232 19408480 3214560
01:36:18 42866 8192000000 10268938864 10724311040 1319465456 13.872 96 8 16 4 1335346752 13762640 2118656
01:38:07 11270 8192000000 8991681744 9186942976 157608944 1.887 12 3 5 0 161658224 3420096 629184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-04-2022 01:39:32 to 26-04-2022 01:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 109 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 26-04-2022 01:39:48 to 26-04-2022 01:39:50.

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          240493
MON$OLDEST_ACTIVE               240494
MON$OLDEST_SNAPSHOT             240494
MON$NEXT_TRANSACTION            240494
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                       2035936
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 ---
:1699: 
:1700: mscs-test-161.budzdorov.ru    Tue Apr 26 01:38:31 2022
:1701:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1702: 
--- end of diff output ---

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

26.04.22 01:39:51. 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-26 01:39:53 No severe PSQL-related problems occured
26.04.22 01:39:54. End of report.