Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.03.2022 16: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 16610780 kB
MemAvailable 31589080 kB
Buffers 0 kB
Cached 15082840 kB
SwapCached 2184 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-03-30 16:38:15
DTS_END                         2022-03-30 16:38:15.8270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33638
TRN_ID                          240821
ATT_ID                          634
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: 9264.62 21214 1111754
customer order: creation 171.48 383 20577
customer order: refuse 14.71 363 1765
order to supplier: creation 46.71 1307 5605
order to supplier: removal 3.47 104715 416
invoice (draft): creation 46.63 3446 5595
invoice (draft): removal 54.43 827 6531
invoice accept: apply 40.66 17192 4879
invoice accept: cancel 48.48 822 5817
customer reserve: creation 3747.73 170 449727
customer reserve: removal 4791.59 59 574991
realization accept: apply 84.52 216 10142
realization accept: cancel 84.99 108 10199
payment from customer: creation 58.22 142 6986
payment from customer: removal 5.75 117 690
payment to supplier: creation 49.13 155 5896
payment to supplier: removal 3.47 61 416
service: total inventory turnovers 4.80 995 576
service: total monetary turnovers 6.85 20 822
service: aggregate perf. data 1.00 15527 120
service: refresh index statistics 0.03 277645 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-03-2022 16:39:21 to 30-03-2022 16:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2668
TEST_TIME 2 4778
TEST_TIME 3 4642
TEST_TIME 4 4715
TEST_TIME 5 5049
TEST_TIME 6 4883
TEST_TIME 7 4488
TEST_TIME 8 4823
TEST_TIME 9 5074
TEST_TIME 10 5016
TEST_TIME 11 5076
TEST_TIME 12 5096
TEST_TIME 13 5605
TEST_TIME 14 5592
TEST_TIME 15 6289
TEST_TIME 16 6669
TEST_TIME 17 7388
TEST_TIME 18 7516
TEST_TIME 19 7837
TEST_TIME 20 7838
TEST_TIME 21 8198
TEST_TIME 22 8758
TEST_TIME 23 9031
TEST_TIME 24 9175
TEST_TIME 25 9835
TEST_TIME 26 10889
TEST_TIME 27 10796
TEST_TIME 28 10451
TEST_TIME 29 10437
TEST_TIME 30 10437
TEST_TIME 31 10237
TEST_TIME 32 10258
TEST_TIME 33 10153
TEST_TIME 34 10048
TEST_TIME 35 9913
TEST_TIME 36 9977
TEST_TIME 37 10195
TEST_TIME 38 10064
TEST_TIME 39 10041
TEST_TIME 40 10002
TEST_TIME 41 9912
TEST_TIME 42 9966
TEST_TIME 43 9949
TEST_TIME 44 9955
TEST_TIME 45 9917
TEST_TIME 46 9970
TEST_TIME 47 9824
TEST_TIME 48 9648
TEST_TIME 49 9638
TEST_TIME 50 9664
TEST_TIME 51 9603
TEST_TIME 52 9577
TEST_TIME 53 9499
TEST_TIME 54 9505
TEST_TIME 55 9542
TEST_TIME 56 9577
TEST_TIME 57 9509
TEST_TIME 58 9383
TEST_TIME 59 9347
TEST_TIME 60 9398
TEST_TIME 61 9421
TEST_TIME 62 9410
TEST_TIME 63 9424
TEST_TIME 64 9442
TEST_TIME 65 9458
TEST_TIME 66 9530
TEST_TIME 67 9554
TEST_TIME 68 9602
TEST_TIME 69 9578
TEST_TIME 70 9701
TEST_TIME 71 9667
TEST_TIME 72 9625
TEST_TIME 73 9622
TEST_TIME 74 9849
TEST_TIME 75 9796
TEST_TIME 76 9762
TEST_TIME 77 9673
TEST_TIME 78 9653
TEST_TIME 79 9617
TEST_TIME 80 9585
TEST_TIME 81 9513
TEST_TIME 82 9447
TEST_TIME 83 9526
TEST_TIME 84 9467
TEST_TIME 85 9444
TEST_TIME 86 9424
TEST_TIME 87 9377
TEST_TIME 88 9395
TEST_TIME 89 9347
TEST_TIME 90 9304
TEST_TIME 91 9307
TEST_TIME 92 9294
TEST_TIME 93 9281
TEST_TIME 94 9309
TEST_TIME 95 9304
TEST_TIME 96 9316
TEST_TIME 97 9335
TEST_TIME 98 9305
TEST_TIME 99 9375
TEST_TIME 100 9335
TEST_TIME 101 9346
TEST_TIME 102 9347
TEST_TIME 103 9384
TEST_TIME 104 9403
TEST_TIME 105 9426
TEST_TIME 106 9476
TEST_TIME 107 9480
TEST_TIME 108 9399
TEST_TIME 109 9383
TEST_TIME 110 9359
TEST_TIME 111 9328
TEST_TIME 112 9276
TEST_TIME 113 9320
TEST_TIME 114 9269
TEST_TIME 115 9242
TEST_TIME 116 9305
TEST_TIME 117 9273
TEST_TIME 118 9309
TEST_TIME 119 9333
TEST_TIME 120 9269

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-03-2022 16:39:25 to 30-03-2022 16:39:28.

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 1530504 1530504 0 0.00 0 29179 6 0 0
doc_list_biud 1530500 1530500 0 0.00 0 36254 29 0 0
sp_add_invoice_to_stock 4984 4879 105 2.10 25 186462 17192 0 105
sp_cancel_adding_invoice 5817 5817 0 0.00 3 58416 823 0 0
sp_cancel_client_order 1765 1765 0 0.00 4 22027 363 0 0
sp_cancel_customer_reserve 574991 574991 0 0.00 1 31261 59 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 10012 117 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 2935 61 0 0
sp_cancel_supplier_invoice 6541 6531 10 0.15 2 20299 827 0 10
sp_cancel_supplier_order 416 416 0 0.00 9 1287859 104715 0 0
sp_cancel_write_off 10199 10199 0 0.00 1 13386 108 0 0
sp_client_order 20577 20577 0 0.00 3 35165 384 0 0
sp_customer_reserve 449727 449727 0 0.00 0 90721 171 0 0
sp_fill_shopping_cart 31771 31771 0 0.00 1 14868 43 0 0
sp_fill_shopping_cart_clo_res 449713 449713 0 0.00 0 10923 12 0 0
sp_get_clo_for_invoice 4865 4865 0 0.00 0 24304 255 0 0
sp_kill_cost_storno 17122 17122 0 0.00 0 7712 55 0 0
sp_kill_qstorno_ret_qs2qd 1167128 1167128 0 0.00 0 36253 32 0 0
sp_kill_qty_storno 614726 614726 0 0.00 0 36254 71 0 0
sp_lock_selected_doc 20809 20809 0 0.00 0 9983 7 0 0
sp_make_cost_storno 27889 27889 0 0.00 0 29171 59 0 0
sp_multiply_rows_for_pdistr 27885 27885 0 0.00 0 8216 33 0 0
sp_multiply_rows_for_qdistr 20577 20577 0 0.00 0 14209 144 0 0
sp_pay_from_customer 6986 6986 0 0.00 4 31091 142 0 0
sp_pay_to_supplier 5896 5896 0 0.00 4 33667 155 0 0
sp_payment_common 12878 12878 0 0.00 2 8051 38 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 22017 332 0 0
sp_qd_handle_on_invoice_upd_sts 10682 10682 0 0.00 0 28984 363 0 0
sp_qd_handle_on_reserve_upd_sts 10142 10142 0 0.00 0 26009 80 0 0
sp_reserve_write_off 10142 10142 0 0.00 5 26332 217 0 0
sp_supplier_invoice 5595 5595 0 0.00 7 88112 3447 0 0
sp_supplier_order 5605 5605 0 0.00 5 73577 1308 0 0
srv_aggregate_perf_data 121 120 1 0.82 2882 122493 15528 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2426 131934 47861 0 0
srv_find_qd_qs_mism 1065627 1065627 0 0.00 0 15245 5 0 0
srv_make_invnt_saldo 576 576 0 0.00 43 29429 996 0 0
srv_make_money_saldo 822 822 0 0.00 1 457 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 146957 526119 277645 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 4880 4880 0 0.00 0 19233 168 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 3100 95 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 4505 14 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 1314 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 4991 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 995 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1807 25 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 17974 1490 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 5404 14 0 0
t$perf-norm:sp_client_order 20577 20577 0 0.00 0 17864 18 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 1042 5 0 0
t$perf-norm:sp_pay_from_customer 6986 6986 0 0.00 0 8964 8 0 0
t$perf-norm:sp_pay_to_supplier 5896 5896 0 0.00 0 10058 11 0 0
t$perf-norm:sp_reserve_write_off 10143 10143 0 0.00 0 10101 10 0 0
t$perf-norm:sp_supplier_invoice 5595 5595 0 0.00 0 7335 18 0 0
t$perf-norm:sp_supplier_order 5605 5605 0 0.00 0 5368 16 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 1490 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 661 11 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 745 18 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 8442 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 216 59 0 0
v_add_invoice_to_stock 9934 9724 210 2.11 0 7670 19 0 210
v_all_customers 37372 37372 0 0.00 0 21 0 0 0
v_all_suppliers 31012 31012 0 0.00 0 22 1 0 0
v_all_wares 41154 41154 0 0.00 0 60 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1662 8 0 0
v_cancel_client_order 3530 3530 0 0.00 0 4870 5 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 1617 6 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 592 4 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 271 2 0 0
v_cancel_supplier_invoice 854 834 20 2.34 0 2717 16 0 20
v_cancel_supplier_order 832 832 0 0.00 0 2980 12 0 0
v_cancel_write_off 1980 1980 0 0.00 0 4353 18 0 0
v_max_id_clo_ord 5603 5603 0 0.00 0 766 1 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 2160 16 0 0
v_max_id_ord_sup 5589 5589 0 0.00 0 2570 3 0 0
v_max_non_paid_invoice 1579 1579 0 0.00 0 3187 5 0 0
v_max_non_paid_realizn 3765 3765 0 0.00 0 7674 8 0 0
v_min_id_clo_ord 5603 5603 0 0.00 0 6154 6 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 1043 3 0 0
v_min_id_ord_sup 5589 5589 0 0.00 0 4163 8 0 0
v_min_non_paid_invoice 5893 5893 0 0.00 0 8045 29 0 0
v_min_non_paid_realizn 6985 6985 0 0.00 0 7569 28 0 0
v_our_firm 10222 10222 0 0.00 0 14 1 0 0
v_reserve_write_off 20284 20284 0 0.00 0 5570 6 0 0
x_lock_depdocs_on_canc_invoice 11931 11931 0 0.00 0 14714 121 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 9736 325 0 0
x_make_qstorno_1000_1200 5605 5605 0 0.00 1 73567 1224 0 0
x_make_qstorno_1200_2000 5595 5595 0 0.00 2 76795 3293 0 0
x_make_qstorno_2100_3300 449727 449727 0 0.00 0 90720 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-03-2022 16:39:29 to 30-03-2022 16:39:33.

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
14:08:19 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:20 26901 8192000000 9904560336 10214924288 986050032 10.743 100 88 176 0 1015226944 6388880 22788032
14:10:53 20605 8192000000 9950413856 10269605888 1032868832 11.196 100 92 185 0 1065266256 8541120 23856304
14:12:16 9604 8192000000 9968521376 10299154432 1055477136 11.413 100 87 174 0 1087030208 9655312 21897760
14:13:40 15340 8192000000 10027202800 10375692288 1112101680 11.952 100 75 150 1 1141083936 10985632 17996624
14:15:00 10269 8192000000 10082954080 10443587584 1169402672 12.491 100 75 152 0 1199601200 12835200 17363328
14:16:12 4592 8192000000 10125355584 10495205376 1219911200 12.961 100 74 148 2 1249445616 13054992 16479424
14:17:20 3390 8192000000 10159923184 10538274816 1245653648 13.198 100 76 152 0 1276561984 14039504 16868832
14:18:24 2073 8192000000 10204201680 10588651520 1287304896 13.580 100 92 182 3 1321391792 13081024 21005872
14:19:27 5411 8192000000 10207631152 10605408256 1303565360 13.728 100 55 110 1 1327237856 11834688 11837808
14:20:34 4663 8192000000 10203432096 10609070080 1308121552 13.769 100 35 70 0 1323022096 7155808 7744736
14:21:39 7965 8192000000 10197748848 10615091200 1316418624 13.844 100 17 32 1 1326469536 6418192 3632720
14:22:50 32388 8192000000 10225408640 10639466496 1327648208 13.946 100 39 79 1 1343035312 6491472 8895632
14:24:28 5801 8192000000 10229876576 10648535040 1337166272 14.032 100 31 62 0 1350705696 6199856 7339568
14:25:38 8130 8192000000 10236341904 10655408128 1339720576 14.055 100 30 59 1 1353691328 6352304 7618448
14:26:47 5234 8192000000 10241471056 10661433344 1344696320 14.100 100 21 42 2 1355782416 5836576 5249520
14:27:57 22572 8192000000 10261581216 10658406400 1330560016 13.972 99 78 159 0 1357433792 6824560 20049216
14:29:19 6783 8192000000 10186375952 10588839936 1267519232 13.399 98 24 47 1 1278600304 5672032 5409040
14:30:27 10023 8192000000 10119049408 10511208448 1171953712 12.515 94 31 63 1 1184969376 5940128 7075536
14:31:39 67150 8192000000 10127064816 10528710656 1189985776 12.683 95 27 54 2 1200894512 5298704 5610032
14:33:48 9075 8192000000 10169249152 10570264576 1268763568 13.410 100 34 68 3 1283270480 7116048 7390864
14:34:58 7735 8192000000 10168316592 10583621632 1282236720 13.533 100 20 40 1 1293472544 6774576 4461248
14:36:09 16423 8192000000 10190306944 10596667392 1296158192 13.660 100 25 50 0 1307829136 5156576 6514368
14:37:26 16337 8192000000 10194898192 10606710784 1301204928 13.706 100 41 81 1 1320739872 8821760 10713184
14:38:46 20321 8192000000 10227650656 10633682944 1324067232 13.914 100 43 86 2 1340690096 6623568 9999296
14:40:13 6165 8192000000 10261892480 10667327488 1341360624 14.070 100 60 120 1 1370966960 15564944 14041392
14:41:27 20725 8192000000 10279389632 10692681728 1360697264 14.244 100 59 118 2 1392316496 18354160 13265072
14:42:53 24577 8192000000 10286473616 10709254144 1372000192 14.345 100 52 104 0 1406485568 22628464 11856912
14:44:29 38216 8192000000 10294786224 10721435648 1380903648 14.425 100 29 58 0 1409682176 21331952 7446576
14:46:13 12526 8192000000 10327110224 10752077824 1393966608 14.541 100 52 104 1 1442404736 35493024 12945104
14:47:29 10375 8192000000 10333479200 10758131712 1401819296 14.611 100 46 92 1 1446547280 33636224 11091760
14:48:45 3459 8192000000 10363882672 10782662656 1417886640 14.754 100 65 128 0 1470593568 36999552 15707376
14:49:51 5960 8192000000 10373281040 10789445632 1413777216 14.717 99 78 155 1 1475496448 42035216 19684016
14:51:03 2426 8192000000 10382089296 10793795584 1405600240 14.645 98 75 151 0 1474678064 50131456 18946368
14:52:11 4353 8192000000 10388274496 10808377344 1424400912 14.812 100 54 107 2 1492730848 53775600 14554336
14:53:19 4738 8192000000 10376098928 10784550912 1384035296 14.453 99 83 165 4 1467780432 62506784 21238352
14:54:28 4981 8192000000 10369706736 10778906624 1393080464 14.533 100 79 154 0 1470789744 57551760 20157520
14:55:38 7403 8192000000 10356442192 10782212096 1397523008 14.573 100 49 98 1 1463641632 53557200 12561424
14:56:49 8487 8192000000 10342770528 10762227712 1350773648 14.154 97 51 103 1 1420272496 56288896 13209952
14:58:02 4356 8192000000 10343336944 10755133440 1372874224 14.353 99 58 116 1 1442502624 54237568 15390832
14:59:10 3741 8192000000 10363867552 10777600000 1379669760 14.414 100 68 134 1 1463752816 66969136 17113920
15:00:15 17856 8192000000 10334660816 10769391616 1378955024 14.407 100 39 78 0 1453841264 63773792 11112448
15:01:39 35859 8192000000 10325041648 10764136448 1373178032 14.356 99 24 48 1 1430051760 49837952 7035776
15:03:29 21508 8192000000 10310126480 10758213632 1357912720 14.219 100 16 32 1 1418568304 55502240 5153344
15:04:52 25210 8192000000 10268249152 10705141760 1350082608 14.148 99 20 40 0 1369042176 13694640 5264928
15:06:18 56445 8192000000 10263458160 10716434432 1345033664 14.103 99 14 28 1 1364408032 16097616 3276752
15:09:04 66158 8192000000 10286885408 10729508864 1368647040 14.315 100 11 22 2 1386355776 14764160 2944576
15:11:16 50215 8192000000 10262373264 10705055744 1317645120 13.855 96 23 46 2 1331334080 8208000 5480960
15:13:06 67774 8192000000 10295210096 10741850112 1354654240 14.189 98 9 18 1 1374331792 17611184 2066368
15:15:14 93571 8192000000 10283048752 10725548032 1324603504 13.918 96 15 30 0 1337890384 10475536 2811344
15:17:56 58683 8192000000 10304923280 10756194304 1384816640 14.460 100 19 38 1 1400456368 11492080 4147648
15:20:09 68857 8192000000 10293461168 10740703232 1359603280 14.234 98 8 16 1 1370198720 9121056 1474384
15:22:31 92616 8192000000 10317100320 10775375872 1403152720 14.623 100 10 20 1 1415802608 10457872 2192016
15:25:12 104126 8192000000 10315635776 10760806400 1392678256 14.530 99 37 72 2 1409132448 6414256 10039936
15:28:08 27155 8192000000 10310946512 10746560512 1377041984 14.390 99 50 99 1 1398741536 10628464 11071088
15:29:37 15657 8192000000 10295968720 10746986496 1388897792 14.496 100 33 66 1 1404676896 8664464 7114640
15:31:04 36750 8192000000 10316880112 10763747328 1390462688 14.510 99 33 66 0 1407482208 9168000 7851520
15:33:15 47929 8192000000 10303878496 10766753792 1398178160 14.579 100 20 40 1 1417754192 14462064 5113968
15:35:11 52157 8192000000 10270466528 10731548672 1353354960 14.178 98 8 16 0 1365143120 9917536 1870624
15:37:04 71863 8192000000 10277044608 10742120448 1367058656 14.301 99 9 18 1 1381332880 12143904 2130320
15:39:17 90853 8192000000 10265338656 10717163520 1297041520 13.668 94 11 22 0 1308764432 9353408 2369504
15:41:48 58407 8192000000 10296629408 10745311232 1354797040 14.191 98 22 44 0 1367444224 7775760 4871424
15:43:52 50336 8192000000 10280570368 10738835456 1330315520 13.970 96 13 26 1 1346841920 13028992 3497408
15:45:52 51496 8192000000 10269213392 10722213888 1294705136 13.647 94 21 42 0 1312028352 11997872 5325344
15:47:44 107864 8192000000 10264520016 10718179328 1306052256 13.750 95 17 34 0 1326106208 15839680 4214272
15:50:37 57781 8192000000 10289593040 10729037824 1325695104 13.928 97 18 36 0 1354239168 24083296 4460768
15:53:01 131934 8192000000 10266524800 10720161792 1321085152 13.887 97 21 42 0 1344524848 18487760 4951936
15:56:17 18191 8192000000 10257733616 10698838016 1343996208 14.093 100 28 55 1 1363641536 12592096 7053232
15:57:40 71412 8192000000 10261104288 10720989184 1346879664 14.119 99 23 46 1 1364727840 12557584 5290592
15:59:53 86538 8192000000 10266913824 10741010432 1355693696 14.199 99 15 30 1 1377808576 18177616 3937264
16:02:20 69918 8192000000 10260105072 10717028352 1336609216 14.027 98 14 28 0 1347945888 8170160 3166512
16:04:43 78347 8192000000 10270309872 10727038976 1358155856 14.221 99 17 34 0 1372617808 10873920 3588032
16:07:14 64674 8192000000 10285192400 10745905152 1384925040 14.461 100 16 32 0 1400337648 12045424 3367184
16:09:19 75207 8192000000 10285378480 10755608576 1380342992 14.420 99 8 16 0 1391959744 10023088 1593664
16:11:55 68859 8192000000 10293832544 10763382784 1378211712 14.401 99 17 34 1 1395676944 13411328 4053904
16:14:13 67909 8192000000 10298609408 10752647168 1376720016 14.387 99 26 52 1 1392292112 9901664 5670432
16:16:26 40735 8192000000 10312350240 10762665984 1372962400 14.354 98 28 54 0 1388064704 9417072 5685232
16:18:10 19313 8192000000 10302262832 10751041536 1368020112 14.309 98 21 42 1 1379497632 7011760 4465760
16:19:34 84012 8192000000 10305315504 10754174976 1348743568 14.136 97 18 36 0 1362846592 10081136 4021888
16:21:59 78355 8192000000 10313529312 10760237056 1350801648 14.155 97 24 48 1 1369634352 13430320 5402384
16:24:29 50981 8192000000 10293598960 10757050368 1357702832 14.217 98 13 25 1 1380581984 19417808 3461344
16:26:20 110469 8192000000 10265204688 10724712448 1328612464 13.955 96 19 38 1 1344307968 11088400 4607104
16:29:11 76279 8192000000 10233105584 10685767680 1262965552 13.357 92 13 26 0 1281927872 15873008 3089312
16:31:28 60935 8192000000 10277482656 10731380736 1367187632 14.302 100 16 32 0 1383927808 12839984 3900192
16:33:34 84923 8192000000 10276378880 10746892288 1366480912 14.296 99 13 26 0 1386407920 16483312 3443696
16:36:04 43261 8192000000 10278190128 10743463936 1353543472 14.179 98 18 36 0 1373074544 15157280 4373792
16:37:55 22933 8192000000 9685516112 10042855424 812815136 9.026 60 21 37 2 822505488 5330752 4359600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-03-2022 16:39:35 to 30-03-2022 16:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 105 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-03-2022 16:39:51 to 30-03-2022 16:39:53.

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          240709
MON$OLDEST_ACTIVE               241081
MON$OLDEST_SNAPSHOT             241081
MON$NEXT_TRANSACTION            241081
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                       2040352
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:718: 
:719: mscs-test-161.budzdorov.ru    Wed Mar 30 16:38:21 2022
:720:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:721: 
:722: 
:723: mscs-test-161.budzdorov.ru    Wed Mar 30 16:38:21 2022
:724:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:725: 
:726: 
:727: mscs-test-161.budzdorov.ru    Wed Mar 30 16:38:21 2022
:728:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:729: 
--- end of diff output ---

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

30.03.22 16:39:54. 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-03-30 16:39:56 No severe PSQL-related problems occured
30.03.22 16:39:57. End of report.