Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.06.2022 22: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 15965624 kB
MemAvailable 31276044 kB
Buffers 0 kB
Cached 15717296 kB
SwapCached 2440 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-06-11 22:38:15
DTS_END                         2022-06-11 22:38:15.3000
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/38254
TRN_ID                          243301
ATT_ID                          704
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: 9625.35 23906 1155042
customer order: creation 171.08 333 20530
customer order: refuse 15.20 333 1824
order to supplier: creation 45.52 1257 5462
order to supplier: removal 3.61 100804 433
invoice (draft): creation 47.33 3221 5680
invoice (draft): removal 56.69 745 6803
invoice accept: apply 41.32 18015 4958
invoice accept: cancel 51.48 756 6178
customer reserve: creation 3814.06 178 457687
customer reserve: removal 5065.70 56 607884
realization accept: apply 83.38 173 10006
realization accept: cancel 100.08 101 12010
payment from customer: creation 59.77 109 7172
payment from customer: removal 5.56 89 667
payment to supplier: creation 49.11 113 5893
payment to supplier: removal 3.25 71 390
service: total inventory turnovers 4.72 1205 567
service: total monetary turnovers 6.64 43 797
service: aggregate perf. data 0.81 19896 97
service: refresh index statistics 0.03 330629 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-06-2022 22:39:17 to 11-06-2022 22:39:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4974
TEST_TIME 2 4022
TEST_TIME 3 4378
TEST_TIME 4 4867
TEST_TIME 5 5165
TEST_TIME 6 5232
TEST_TIME 7 5038
TEST_TIME 8 5309
TEST_TIME 9 5750
TEST_TIME 10 5603
TEST_TIME 11 6232
TEST_TIME 12 6300
TEST_TIME 13 6341
TEST_TIME 14 6559
TEST_TIME 15 6893
TEST_TIME 16 7244
TEST_TIME 17 7990
TEST_TIME 18 8223
TEST_TIME 19 8431
TEST_TIME 20 8379
TEST_TIME 21 8376
TEST_TIME 22 8558
TEST_TIME 23 8512
TEST_TIME 24 8713
TEST_TIME 25 8897
TEST_TIME 26 9170
TEST_TIME 27 9099
TEST_TIME 28 9439
TEST_TIME 29 10149
TEST_TIME 30 10406
TEST_TIME 31 10477
TEST_TIME 32 10589
TEST_TIME 33 10743
TEST_TIME 34 10836
TEST_TIME 35 10908
TEST_TIME 36 10822
TEST_TIME 37 10937
TEST_TIME 38 10896
TEST_TIME 39 10651
TEST_TIME 40 10490
TEST_TIME 41 10392
TEST_TIME 42 10527
TEST_TIME 43 10457
TEST_TIME 44 10282
TEST_TIME 45 10249
TEST_TIME 46 10252
TEST_TIME 47 10104
TEST_TIME 48 10250
TEST_TIME 49 10313
TEST_TIME 50 10203
TEST_TIME 51 10175
TEST_TIME 52 10049
TEST_TIME 53 9985
TEST_TIME 54 10012
TEST_TIME 55 9986
TEST_TIME 56 9906
TEST_TIME 57 10063
TEST_TIME 58 10023
TEST_TIME 59 10022
TEST_TIME 60 9942
TEST_TIME 61 9988
TEST_TIME 62 10089
TEST_TIME 63 10018
TEST_TIME 64 10121
TEST_TIME 65 10212
TEST_TIME 66 10196
TEST_TIME 67 10084
TEST_TIME 68 10160
TEST_TIME 69 10256
TEST_TIME 70 10122
TEST_TIME 71 10107
TEST_TIME 72 10197
TEST_TIME 73 10085
TEST_TIME 74 10069
TEST_TIME 75 10064
TEST_TIME 76 9993
TEST_TIME 77 9978
TEST_TIME 78 9920
TEST_TIME 79 9843
TEST_TIME 80 9864
TEST_TIME 81 9807
TEST_TIME 82 9770
TEST_TIME 83 9824
TEST_TIME 84 9834
TEST_TIME 85 9840
TEST_TIME 86 9858
TEST_TIME 87 9855
TEST_TIME 88 9826
TEST_TIME 89 9882
TEST_TIME 90 9899
TEST_TIME 91 9868
TEST_TIME 92 9953
TEST_TIME 93 9991
TEST_TIME 94 10005
TEST_TIME 95 10019
TEST_TIME 96 9982
TEST_TIME 97 9922
TEST_TIME 98 9939
TEST_TIME 99 9884
TEST_TIME 100 9854
TEST_TIME 101 9800
TEST_TIME 102 9798
TEST_TIME 103 9804
TEST_TIME 104 9788
TEST_TIME 105 9757
TEST_TIME 106 9692
TEST_TIME 107 9699
TEST_TIME 108 9638
TEST_TIME 109 9637
TEST_TIME 110 9621
TEST_TIME 111 9629
TEST_TIME 112 9613
TEST_TIME 113 9655
TEST_TIME 114 9636
TEST_TIME 115 9656
TEST_TIME 116 9609
TEST_TIME 117 9637
TEST_TIME 118 9691
TEST_TIME 119 9696
TEST_TIME 120 9626

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-06-2022 22:39:20 to 11-06-2022 22:39:24.

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 1581920 1581920 0 0.00 0 23894 6 0 0
doc_list_biud 1581918 1581918 0 0.00 0 28598 27 0 0
sp_add_invoice_to_stock 5080 4958 122 2.40 37 170594 18015 0 122
sp_cancel_adding_invoice 6178 6178 0 0.00 4 58603 757 0 0
sp_cancel_client_order 1824 1824 0 0.00 4 7994 333 0 0
sp_cancel_customer_reserve 607884 607884 0 0.00 1 28099 56 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 5409 90 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 2 2037 72 0 0
sp_cancel_supplier_invoice 6810 6803 7 0.10 2 28614 746 0 7
sp_cancel_supplier_order 433 433 0 0.00 8 1091355 100804 0 0
sp_cancel_write_off 12010 12010 0 0.00 1 10009 101 0 0
sp_client_order 20530 20530 0 0.00 3 40560 334 0 0
sp_customer_reserve 457687 457687 0 0.00 0 106354 179 0 0
sp_fill_shopping_cart 31667 31667 0 0.00 1 17245 36 0 0
sp_fill_shopping_cart_clo_res 457686 457686 0 0.00 0 20822 13 0 0
sp_get_clo_for_invoice 4957 4957 0 0.00 0 25422 276 0 0
sp_kill_cost_storno 19245 19245 0 0.00 0 14356 55 0 0
sp_kill_qstorno_ret_qs2qd 1235014 1235014 0 0.00 0 28591 30 0 0
sp_kill_qty_storno 650093 650093 0 0.00 0 28595 66 0 0
sp_lock_selected_doc 20851 20851 0 0.00 0 8936 6 0 0
sp_make_cost_storno 28028 28028 0 0.00 0 16058 55 0 0
sp_multiply_rows_for_pdistr 28026 28026 0 0.00 0 16783 31 0 0
sp_multiply_rows_for_qdistr 20530 20530 0 0.00 0 13464 145 0 0
sp_pay_from_customer 7172 7172 0 0.00 5 16788 110 0 0
sp_pay_to_supplier 5893 5893 0 0.00 5 10122 113 0 0
sp_payment_common 13065 13065 0 0.00 1 9856 24 0 0
sp_qd_handle_on_cancel_clo 1824 1824 0 0.00 0 7989 310 0 0
sp_qd_handle_on_invoice_upd_sts 11133 11133 0 0.00 0 21050 336 0 0
sp_qd_handle_on_reserve_upd_sts 10006 10006 0 0.00 0 25388 57 0 0
sp_reserve_write_off 10006 10006 0 0.00 6 25435 174 0 0
sp_supplier_invoice 5680 5680 0 0.00 6 89834 3222 0 0
sp_supplier_order 5462 5462 0 0.00 5 40762 1258 0 0
srv_aggregate_perf_data 97 97 0 0.00 2336 146796 19897 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2487 130607 44353 0 0
srv_find_qd_qs_mism 1108946 1108946 0 0.00 0 23893 5 0 0
srv_make_invnt_saldo 567 567 0 0.00 46 91891 1206 0 0
srv_make_money_saldo 797 797 0 0.00 1 12760 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 239623 444186 330630 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 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 4959 4959 0 0.00 0 9627 167 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 1943 62 0 0
t$perf-norm:sp_cancel_client_order 1824 1824 0 0.00 0 2283 13 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 3056 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 885 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 2130 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1012 19 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 26720 1704 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 1380 5 0 0
t$perf-norm:sp_client_order 20530 20530 0 0.00 0 6584 17 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 1657 12 0 0
t$perf-norm:sp_pay_from_customer 7172 7172 0 0.00 0 4193 8 0 0
t$perf-norm:sp_pay_to_supplier 5893 5893 0 0.00 0 4940 11 0 0
t$perf-norm:sp_reserve_write_off 10006 10006 0 0.00 0 3191 10 0 0
t$perf-norm:sp_supplier_invoice 5680 5680 0 0.00 0 4460 17 0 0
t$perf-norm:sp_supplier_order 5462 5462 0 0.00 0 9451 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 348 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1795 37 0 0
t$perf-norm:srv_make_invnt_saldo 567 567 0 0.00 0 2434 29 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 3202 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10154 9910 244 2.40 0 5194 20 0 244
v_all_customers 37662 37662 0 0.00 0 20 1 0 0
v_all_suppliers 31042 31042 0 0.00 0 19 1 0 0
v_all_wares 41060 41060 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 165 5 0 0
v_cancel_client_order 3648 3648 0 0.00 0 2652 5 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 2659 11 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 1161 5 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 963 5 0 0
v_cancel_supplier_invoice 842 828 14 1.66 0 1708 20 0 14
v_cancel_supplier_order 866 866 0 0.00 0 313 4 0 0
v_cancel_write_off 2044 2044 0 0.00 0 1618 13 0 0
v_max_id_clo_ord 5460 5460 0 0.00 0 1622 2 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 2775 11 0 0
v_max_id_ord_sup 5677 5677 0 0.00 0 3954 4 0 0
v_max_non_paid_invoice 1509 1509 0 0.00 0 276 2 0 0
v_max_non_paid_realizn 3724 3724 0 0.00 0 2879 4 0 0
v_min_id_clo_ord 5460 5460 0 0.00 0 5077 8 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 4124 15 0 0
v_min_id_ord_sup 5677 5677 0 0.00 0 8935 10 0 0
v_min_non_paid_invoice 5893 5893 0 0.00 0 9852 18 0 0
v_min_non_paid_realizn 7172 7172 0 0.00 0 7281 18 0 0
v_our_firm 10294 10294 0 0.00 0 15 1 0 0
v_reserve_write_off 20012 20012 0 0.00 0 3081 4 0 0
x_lock_depdocs_on_canc_invoice 12567 12567 0 0.00 0 14363 110 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 17555 379 0 0
x_make_qstorno_1000_1200 5462 5462 0 0.00 2 40745 1176 0 0
x_make_qstorno_1200_2000 5680 5680 0 0.00 3 89826 3101 0 0
x_make_qstorno_2100_3300 457687 457687 0 0.00 0 106348 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-06-2022 22:39:25 to 11-06-2022 22:39:29.

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
20:08:19 13 8192000000 8798902176 8810233856 5315664 0.064 2 1 2 0 5415264 56816 42784
20:09:19 31324 8192000000 9924642560 10238758912 994866912 10.829 100 84 168 0 1022666656 6366496 21433248
20:10:57 15895 8192000000 9967018112 10293620736 1046257904 11.325 100 84 168 0 1076111920 8770352 21083664
20:12:23 14112 8192000000 9995123344 10333048832 1081130192 11.658 100 80 160 0 1110796496 9605200 20061104
20:13:39 8649 8192000000 10036033152 10387210240 1129844864 12.120 100 82 164 0 1160183808 10914240 19424704
20:14:55 8000 8192000000 10087775168 10448945152 1172952384 12.524 100 73 144 2 1201005664 11450144 16603136
20:16:04 5342 8192000000 10130435056 10500444160 1215562432 12.921 100 79 158 1 1246999072 13640448 17796192
20:17:12 9136 8192000000 10162484704 10541375488 1251982480 13.256 100 78 156 3 1281756208 12803744 16969984
20:18:22 1492 8192000000 10200272352 10581147648 1280917968 13.521 100 90 180 3 1316089856 15201136 19970752
20:19:24 1863 8192000000 10215820176 10596835328 1295743776 13.657 100 88 176 0 1327393840 11399856 20250208
20:20:28 6715 8192000000 10188174880 10598633472 1304442752 13.736 100 35 69 0 1318803824 6131648 8229424
20:21:37 4728 8192000000 10227635120 10622476288 1308360624 13.771 100 64 128 1 1329474912 5684784 15429504
20:22:43 8295 8192000000 10206921200 10622324736 1313689248 13.820 100 17 34 1 1323955920 6463248 3803424
20:23:54 9715 8192000000 10211721584 10629582848 1323672192 13.910 100 18 36 1 1334058768 5439712 4946864
20:25:06 47228 8192000000 10214800608 10638516224 1329705936 13.964 100 38 76 0 1343724672 4984592 9034144
20:26:56 6352 8192000000 10212483248 10628182016 1318297616 13.861 100 38 76 0 1339535376 12306144 8931616
20:28:04 8223 8192000000 10180866112 10603159552 1295980048 13.659 99 28 54 1 1312894656 11021712 5892896
20:29:13 53836 8192000000 10180729872 10585055232 1263357904 13.361 97 41 78 2 1278196880 4772144 10066832
20:31:15 7765 8192000000 10116914704 10507706368 1167324368 12.472 93 31 62 2 1184425488 10147904 6953216
20:32:25 7257 8192000000 10136704640 10536796160 1247771872 13.218 100 29 58 0 1261280416 6566800 6941744
20:33:35 6471 8192000000 10167334112 10572980224 1278160336 13.496 100 16 32 0 1287528288 5814208 3553744
20:34:45 21712 8192000000 10195997616 10585886720 1277631200 13.491 99 68 140 1 1299017360 5256368 16129792
20:36:07 9632 8192000000 10191602656 10599792640 1296225792 13.661 100 22 43 2 1306542544 5691232 4625520
20:37:20 10585 8192000000 10188727680 10604978176 1302780352 13.721 100 14 28 0 1311104112 5117536 3206224
20:38:32 18202 8192000000 10230199872 10639179776 1328110384 13.950 100 40 81 0 1344643520 6587456 9945680
20:40:08 20488 8192000000 10245573120 10657894400 1342213872 14.077 100 54 108 1 1363340544 8810048 12316624
20:41:58 19219 8192000000 10246577456 10663018496 1321071216 13.886 97 42 84 1 1340767008 9997360 9698432
20:43:35 13161 8192000000 10279732576 10693804032 1367236048 14.302 100 58 115 1 1394559184 14224128 13099008
20:45:02 11622 8192000000 10297181072 10708557824 1364452048 14.277 99 55 110 0 1397251152 20060160 12738944
20:46:20 6559 8192000000 10306076144 10725322752 1386977136 14.479 100 58 115 4 1420189344 18800976 14411232
20:47:33 5217 8192000000 10322972640 10739089408 1392309040 14.526 100 55 109 1 1426226384 20946896 12970448
20:48:46 9976 8192000000 10318082016 10732158976 1387193360 14.481 100 68 136 0 1429144544 25860144 16091040
20:50:00 5758 8192000000 10344730592 10756874240 1401522112 14.609 100 84 167 0 1446373616 24550096 20301408
20:51:13 8291 8192000000 10316698400 10733846528 1375145344 14.373 99 66 132 0 1416278880 25265664 15867872
20:52:30 2597 8192000000 10337350976 10754707456 1395380512 14.554 100 64 128 1 1444786960 32982000 16424448
20:53:37 6840 8192000000 10303877120 10712399872 1353476800 14.179 100 62 122 2 1410361216 41008112 15876304
20:54:49 6114 8192000000 10330138864 10752950272 1377937792 14.398 100 62 125 3 1441547168 47122736 16486640
20:56:09 3094 8192000000 10328781632 10732269568 1363747392 14.271 99 93 186 0 1419600176 33220896 22631888
20:57:16 2487 8192000000 10341782176 10746798080 1378704496 14.405 100 82 164 0 1435555728 35845680 21005552
20:58:24 3863 8192000000 10313798640 10727256064 1335980160 14.021 97 61 119 2 1391376032 40427472 14968400
20:59:37 3316 8192000000 10334338928 10747367424 1377407456 14.393 100 63 125 2 1434365616 41373456 15584704
21:00:43 4284 8192000000 10328890784 10756136960 1382515632 14.439 100 33 65 0 1434463504 42898592 9049280
21:01:54 8198 8192000000 10333279808 10757038080 1361960128 14.255 98 37 74 0 1425808112 53682208 10165776
21:03:12 19569 8192000000 10334359824 10772447232 1381506704 14.430 100 34 68 0 1447402624 56143968 9751952
21:04:36 17935 8192000000 10347849120 10775334912 1385540208 14.466 100 34 69 2 1437072576 42641472 8890896
21:05:55 14304 8192000000 10337929552 10767572992 1373107440 14.355 100 34 69 0 1436119312 52592112 10419760
21:07:11 37681 8192000000 10299869472 10759663616 1356518160 14.206 99 22 44 1 1413069440 50271520 6279760
21:08:51 30152 8192000000 10290701056 10728509440 1365557824 14.287 100 13 26 0 1402878080 34304976 3015280
21:10:23 41298 8192000000 10300453264 10738774016 1371677840 14.342 99 16 32 1 1391747056 16207456 3861760
21:12:05 92581 8192000000 10280800640 10728071168 1352616544 14.171 98 9 18 0 1369443056 14978800 1847712
21:14:46 70581 8192000000 10308628320 10767429632 1370056352 14.328 99 7 14 0 1390952352 19281168 1614832
21:17:41 81787 8192000000 10323305456 10758762496 1395037872 14.551 100 45 88 0 1411021248 6582560 9400816
21:20:08 85435 8192000000 10305273392 10761961472 1400494080 14.599 100 31 60 0 1422453568 15153200 6806288
21:22:36 50713 8192000000 10296461376 10737238016 1362266496 14.258 97 24 47 0 1374760208 7311344 5182368
21:24:28 43414 8192000000 10310724048 10766651392 1386448304 14.474 99 18 37 1 1403142496 12083616 4610576
21:26:13 59633 8192000000 10292701568 10769780736 1378228400 14.401 99 21 42 0 1403006256 19814336 4963520
21:28:15 40284 8192000000 10289744816 10750365696 1376309568 14.384 99 27 53 1 1395408912 13592816 5506528
21:29:57 34970 8192000000 10290042928 10754285568 1387667904 14.485 100 9 18 1 1401212624 11800192 1744528
21:31:32 78146 8192000000 10271370480 10747269120 1366442240 14.295 98 10 20 0 1382272416 13229504 2600672
21:33:54 48219 8192000000 10296768512 10758017024 1375723440 14.378 99 14 28 0 1393957216 15005152 3228624
21:35:48 54623 8192000000 10302427040 10758332416 1331729168 13.983 95 13 26 1 1343146480 8920992 2496320
21:37:47 59191 8192000000 10293040720 10746396672 1340394576 14.061 97 15 30 0 1359572976 15952384 3226016
21:40:37 39567 8192000000 10297217696 10751307776 1361178912 14.248 99 12 24 0 1371986800 8420944 2386944
21:42:18 89654 8192000000 10277247376 10726678528 1302097648 13.714 94 6 12 0 1328662064 24976624 1587792
21:45:02 23919 8192000000 10321301696 10762248192 1367926288 14.308 98 40 80 0 1394605872 16826656 9852928
21:46:27 68145 8192000000 10284675600 10740580352 1328569104 13.954 96 16 33 1 1357339296 24929456 3840736
21:48:38 123384 8192000000 10270925696 10742759424 1351842416 14.164 98 27 54 1 1371005296 13133840 6029040
21:52:55 41778 8192000000 10247658256 10708701184 1337107920 14.031 99 26 51 0 1351468560 8956784 5403856
21:54:55 51785 8192000000 10250180960 10705858560 1323490592 13.908 98 13 26 0 1340673872 14182848 3000432
21:56:46 61109 8192000000 10261816640 10719760384 1351513760 14.161 99 14 27 0 1367214416 12637632 3063024
21:58:48 33041 8192000000 10249082048 10706235392 1323133280 13.905 97 24 48 0 1338778128 10305712 5339136
22:00:22 63727 8192000000 10284375232 10741977088 1368604368 14.315 100 15 30 0 1386795552 14680880 3510304
22:02:26 55044 8192000000 10294165056 10743574528 1371344944 14.339 100 20 40 0 1384742528 8606416 4791168
22:04:24 58767 8192000000 10293403312 10758225920 1387908672 14.487 100 12 23 1 1400286784 9836384 2541728
22:06:39 81779 8192000000 10292004816 10750459904 1350119344 14.149 98 20 40 1 1366150624 11667440 4363840
22:09:02 115634 8192000000 10302555536 10760933376 1351718704 14.163 97 9 18 0 1368739968 15420880 1600384
22:11:59 93227 8192000000 10283926464 10743648256 1321203520 13.888 95 11 22 1 1337480048 14177712 2098816
22:14:35 104229 8192000000 10300038176 10759704576 1382465104 14.439 99 26 52 2 1398706880 10452000 5789776
22:18:39 67849 8192000000 10289900288 10753769472 1376828000 14.388 98 15 29 0 1388630128 8690656 3111472
22:20:47 49624 8192000000 10293281168 10770419712 1394379632 14.545 100 11 23 0 1407289440 10392976 2516832
22:22:37 39655 8192000000 10280477088 10738536448 1363557344 14.269 98 25 50 1 1378237248 8867936 5811968
22:24:21 59078 8192000000 10292150304 10744406016 1370922320 14.335 99 21 42 0 1384906960 8648656 5335984
22:26:25 44859 8192000000 10282859152 10738425856 1367751456 14.307 99 9 18 1 1388096720 18457616 1887648
22:28:10 72229 8192000000 10271305728 10728112128 1347708096 14.127 98 8 15 1 1360987840 11638720 1641024
22:30:27 130607 8192000000 10279364288 10732634112 1302282640 13.716 94 14 28 0 1320184048 15078096 2823312
22:33:37 19739 8192000000 10268184752 10712629248 1332311328 13.988 98 28 56 2 1346792432 8679648 5801456
22:34:59 58966 8192000000 10287475616 10745434112 1356868112 14.209 99 25 50 1 1380112800 17726560 5518128
22:37:03 71358 8192000000 10268277984 10708738048 1291389408 13.617 94 27 54 2 1311181008 13775136 6016464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-06-2022 22:39:31 to 11-06-2022 22:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 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:01, from 11-06-2022 22:39:47 to 11-06-2022 22:39:48.

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          243985
MON$OLDEST_ACTIVE               243986
MON$OLDEST_SNAPSHOT             243986
MON$NEXT_TRANSACTION            243986
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                       2068000
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:400: 
:401: mscs-test-161.budzdorov.ru    Sat Jun 11 22:38:36 2022
:402:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:403: 
--- end of diff output ---

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

11.06.22 22:39:49. 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-06-11 22:39:52 No severe PSQL-related problems occured
11.06.22 22:39:53. End of report.