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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15260516 kB
MemAvailable 30428056 kB
Buffers 0 kB
Cached 16457616 kB
SwapCached 3172 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-17 19:38:44
DTS_END                         2023-03-17 19:38:44.9740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38756
TRN_ID                          251785
ATT_ID                          744
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: 9362.62 22185 1123514
customer order: creation 189.69 341 22763
customer order: refuse 16.68 339 2001
order to supplier: creation 49.64 1230 5957
order to supplier: removal 3.45 94556 414
invoice (draft): creation 50.80 3071 6096
invoice (draft): removal 50.43 772 6051
invoice accept: apply 45.64 17450 5477
invoice accept: cancel 45.15 844 5418
customer reserve: creation 4160.59 174 499271
customer reserve: removal 4430.52 58 531663
realization accept: apply 92.15 175 11058
realization accept: cancel 84.83 111 10180
payment from customer: creation 66.18 98 7941
payment from customer: removal 6.43 84 771
payment to supplier: creation 53.38 108 6405
payment to supplier: removal 3.77 88 452
service: total inventory turnovers 5.08 1049 610
service: total monetary turnovers 7.32 27 878
service: aggregate perf. data 0.87 23424 104
service: refresh index statistics 0.03 299710 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-03-2023 19:40:12 to 17-03-2023 19:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3639
TEST_TIME 2 4925
TEST_TIME 3 5287
TEST_TIME 4 5899
TEST_TIME 5 5506
TEST_TIME 6 5394
TEST_TIME 7 5331
TEST_TIME 8 5431
TEST_TIME 9 6259
TEST_TIME 10 7007
TEST_TIME 11 7208
TEST_TIME 12 7136
TEST_TIME 13 7349
TEST_TIME 14 7506
TEST_TIME 15 7540
TEST_TIME 16 8132
TEST_TIME 17 8066
TEST_TIME 18 8287
TEST_TIME 19 8574
TEST_TIME 20 8706
TEST_TIME 21 9116
TEST_TIME 22 9238
TEST_TIME 23 9239
TEST_TIME 24 9583
TEST_TIME 25 9754
TEST_TIME 26 10050
TEST_TIME 27 9980
TEST_TIME 28 10393
TEST_TIME 29 10730
TEST_TIME 30 10569
TEST_TIME 31 10339
TEST_TIME 32 10204
TEST_TIME 33 10338
TEST_TIME 34 10322
TEST_TIME 35 10332
TEST_TIME 36 10210
TEST_TIME 37 10171
TEST_TIME 38 10123
TEST_TIME 39 10012
TEST_TIME 40 10040
TEST_TIME 41 9927
TEST_TIME 42 10061
TEST_TIME 43 9924
TEST_TIME 44 9944
TEST_TIME 45 9858
TEST_TIME 46 9970
TEST_TIME 47 9861
TEST_TIME 48 9900
TEST_TIME 49 9917
TEST_TIME 50 9886
TEST_TIME 51 9876
TEST_TIME 52 9976
TEST_TIME 53 9978
TEST_TIME 54 9979
TEST_TIME 55 9919
TEST_TIME 56 9952
TEST_TIME 57 9990
TEST_TIME 58 10165
TEST_TIME 59 10089
TEST_TIME 60 10079
TEST_TIME 61 10093
TEST_TIME 62 10143
TEST_TIME 63 10212
TEST_TIME 64 10094
TEST_TIME 65 10020
TEST_TIME 66 10059
TEST_TIME 67 9958
TEST_TIME 68 9960
TEST_TIME 69 9911
TEST_TIME 70 9887
TEST_TIME 71 9840
TEST_TIME 72 9777
TEST_TIME 73 9751
TEST_TIME 74 9762
TEST_TIME 75 9680
TEST_TIME 76 9741
TEST_TIME 77 9696
TEST_TIME 78 9675
TEST_TIME 79 9611
TEST_TIME 80 9628
TEST_TIME 81 9604
TEST_TIME 82 9625
TEST_TIME 83 9690
TEST_TIME 84 9680
TEST_TIME 85 9710
TEST_TIME 86 9667
TEST_TIME 87 9710
TEST_TIME 88 9767
TEST_TIME 89 9747
TEST_TIME 90 9739
TEST_TIME 91 9665
TEST_TIME 92 9619
TEST_TIME 93 9598
TEST_TIME 94 9569
TEST_TIME 95 9527
TEST_TIME 96 9512
TEST_TIME 97 9490
TEST_TIME 98 9485
TEST_TIME 99 9502
TEST_TIME 100 9499
TEST_TIME 101 9477
TEST_TIME 102 9515
TEST_TIME 103 9551
TEST_TIME 104 9536
TEST_TIME 105 9584
TEST_TIME 106 9590
TEST_TIME 107 9605
TEST_TIME 108 9586
TEST_TIME 109 9626
TEST_TIME 110 9589
TEST_TIME 111 9527
TEST_TIME 112 9519
TEST_TIME 113 9501
TEST_TIME 114 9469
TEST_TIME 115 9431
TEST_TIME 116 9412
TEST_TIME 117 9374
TEST_TIME 118 9430
TEST_TIME 119 9402
TEST_TIME 120 9367

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-03-2023 19:40:15 to 17-03-2023 19:40:19.

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 1585216 1585216 0 0.00 0 15374 7 0 0
doc_list_biud 1585216 1585216 0 0.00 0 69536 25 0 0
sp_add_invoice_to_stock 5626 5477 149 2.64 39 183273 17450 0 149
sp_cancel_adding_invoice 5418 5418 0 0.00 5 74552 844 0 0
sp_cancel_client_order 2001 2001 0 0.00 4 18116 340 0 0
sp_cancel_customer_reserve 531663 531663 0 0.00 0 69537 58 0 0
sp_cancel_pay_from_customer 771 771 0 0.00 3 5558 85 0 0
sp_cancel_pay_to_supplier 452 452 0 0.00 2 6704 89 0 0
sp_cancel_supplier_invoice 6062 6051 11 0.18 1 18174 772 0 11
sp_cancel_supplier_order 414 414 0 0.00 7 779317 94557 0 0
sp_cancel_write_off 10180 10180 0 0.00 1 17600 112 0 0
sp_client_order 22763 22763 0 0.00 3 65219 341 0 0
sp_customer_reserve 499271 499271 0 0.00 0 73084 174 0 0
sp_fill_shopping_cart 34796 34796 0 0.00 1 12067 37 0 0
sp_fill_shopping_cart_clo_res 499260 499260 0 0.00 0 22058 14 0 0
sp_get_clo_for_invoice 5466 5466 0 0.00 0 40105 321 0 0
sp_kill_cost_storno 16821 16821 0 0.00 0 8305 54 0 0
sp_kill_qstorno_ret_qs2qd 1079971 1079971 0 0.00 0 69534 31 0 0
sp_kill_qty_storno 572251 572251 0 0.00 0 69536 68 0 0
sp_lock_selected_doc 22965 22965 0 0.00 0 8361 6 0 0
sp_make_cost_storno 30870 30870 0 0.00 0 12311 46 0 0
sp_multiply_rows_for_pdistr 30870 30870 0 0.00 0 9065 25 0 0
sp_multiply_rows_for_qdistr 22763 22763 0 0.00 0 65210 155 0 0
sp_pay_from_customer 7941 7941 0 0.00 4 9559 98 0 0
sp_pay_to_supplier 6405 6405 0 0.00 3 10135 109 0 0
sp_payment_common 14346 14346 0 0.00 1 9671 27 0 0
sp_qd_handle_on_cancel_clo 2001 2001 0 0.00 0 18107 316 0 0
sp_qd_handle_on_invoice_upd_sts 10884 10884 0 0.00 0 24512 314 0 0
sp_qd_handle_on_reserve_upd_sts 11058 11058 0 0.00 0 14551 62 0 0
sp_reserve_write_off 11058 11058 0 0.00 6 14983 175 0 0
sp_supplier_invoice 6096 6096 0 0.00 4 124960 3072 0 0
sp_supplier_order 5957 5957 0 0.00 5 34382 1230 0 0
srv_aggregate_perf_data 104 104 0 0.00 2087 675709 23425 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 880 126675 37518 0 0
srv_find_qd_qs_mism 1070960 1070960 0 0.00 0 15373 6 0 0
srv_make_invnt_saldo 610 610 0 0.00 32 48993 1049 0 0
srv_make_money_saldo 878 878 0 0.00 1 750 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 184571 469259 299711 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5477 5477 0 0.00 0 11648 176 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 4329 86 0 0
t$perf-norm:sp_cancel_client_order 2001 2001 0 0.00 0 3312 14 0 0
t$perf-norm:sp_cancel_customer_reserve 785 785 0 0.00 0 4812 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 771 771 0 0.00 0 3744 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 452 452 0 0.00 0 565 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 1513 11 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 21750 1822 0 0
t$perf-norm:sp_cancel_write_off 1130 1130 0 0.00 0 875 6 0 0
t$perf-norm:sp_client_order 22763 22763 0 0.00 0 10608 19 0 0
t$perf-norm:sp_customer_reserve 1131 1131 0 0.00 0 2105 9 0 0
t$perf-norm:sp_pay_from_customer 7941 7941 0 0.00 0 7045 12 0 0
t$perf-norm:sp_pay_to_supplier 6405 6405 0 0.00 0 9086 13 0 0
t$perf-norm:sp_reserve_write_off 11058 11058 0 0.00 0 9739 12 0 0
t$perf-norm:sp_supplier_invoice 6096 6096 0 0.00 0 6982 22 0 0
t$perf-norm:sp_supplier_order 5957 5957 0 0.00 0 8800 16 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 56 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 610 610 0 0.00 0 2275 21 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 4069 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 11230 10932 298 2.65 0 5463 19 0 298
v_all_customers 41382 41382 0 0.00 0 18 1 0 0
v_all_suppliers 33052 33052 0 0.00 0 81 1 0 0
v_all_wares 45526 45526 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 221 6 0 0
v_cancel_client_order 4002 4002 0 0.00 0 2453 5 0 0
v_cancel_customer_prepayment 1542 1542 0 0.00 0 990 4 0 0
v_cancel_customer_reserve 1570 1570 0 0.00 0 2398 5 0 0
v_cancel_payment_to_supplier 904 904 0 0.00 0 2369 10 0 0
v_cancel_supplier_invoice 898 876 22 2.44 0 2130 16 0 22
v_cancel_supplier_order 828 828 0 0.00 0 415 4 0 0
v_cancel_write_off 2260 2260 0 0.00 0 1262 13 0 0
v_max_id_clo_ord 5954 5954 0 0.00 0 1592 3 0 0
v_max_id_clo_res 1131 1131 0 0.00 0 2410 15 0 0
v_max_id_ord_sup 6079 6079 0 0.00 0 6926 3 0 0
v_max_non_paid_invoice 1912 1912 0 0.00 0 786 3 0 0
v_max_non_paid_realizn 4373 4373 0 0.00 0 4514 5 0 0
v_min_id_clo_ord 5954 5954 0 0.00 0 3545 7 0 0
v_min_id_clo_res 1131 1131 0 0.00 0 1555 4 0 0
v_min_id_ord_sup 6079 6079 0 0.00 0 8204 11 0 0
v_min_non_paid_invoice 6405 6405 0 0.00 0 9665 19 0 0
v_min_non_paid_realizn 7941 7941 0 0.00 0 9081 19 0 0
v_our_firm 11280 11280 0 0.00 0 18 1 0 0
v_reserve_write_off 22116 22116 0 0.00 0 2878 6 0 0
x_lock_depdocs_on_canc_invoice 11031 11031 0 0.00 0 14052 130 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 14440 414 0 0
x_make_qstorno_1000_1200 5957 5957 0 0.00 1 34375 1148 0 0
x_make_qstorno_1200_2000 6096 6096 0 0.00 2 124737 2939 0 0
x_make_qstorno_2100_3300 499271 499271 0 0.00 0 73081 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-03-2023 19:40:20 to 17-03-2023 19:40:24.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:48 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:50 19990 8192000000 9878772336 10185297920 965493536 10.543 100 84 168 0 993704272 5915424 22295312
17:11:14 22135 8192000000 9929922464 10243420160 1009084592 10.967 100 88 176 1 1040154960 7821104 23249264
17:12:52 10255 8192000000 9949708848 10276265984 1038225840 11.248 100 87 174 0 1069753072 9100928 22426304
17:14:07 7823 8192000000 10000713696 10340749312 1088918704 11.732 100 79 158 1 1118311360 9998080 19394576
17:15:22 8299 8192000000 10063447248 10417700864 1145637712 12.269 100 82 162 0 1177138752 12351424 19149616
17:16:41 4772 8192000000 10102733840 10467983360 1194488416 12.725 100 76 150 0 1223386464 11849152 17048896
17:17:48 2107 8192000000 10148051808 10520305664 1235062512 13.101 100 83 166 0 1267328496 13847088 18418896
17:18:52 2339 8192000000 10164980224 10544668672 1257101808 13.303 100 76 150 0 1286409360 12794096 16513456
17:19:56 1019 8192000000 10202110096 10587353088 1289273984 13.598 100 86 172 0 1321962912 13982768 18706160
17:20:57 3282 8192000000 10199246768 10597711872 1297592336 13.673 100 49 96 0 1317948080 9599744 10756000
17:22:03 4888 8192000000 10198056576 10603020288 1306039472 13.750 100 44 88 1 1324021872 8649744 9332656
17:23:09 8118 8192000000 10207587168 10620952576 1316097504 13.841 100 33 68 0 1331080432 7892240 7090688
17:24:20 29046 8192000000 10210591856 10630971392 1321218784 13.888 100 26 52 0 1332641440 6103392 5319264
17:25:53 7584 8192000000 10210866512 10634825728 1325699136 13.928 100 22 43 3 1337564448 5846416 6018896
17:27:03 10769 8192000000 10214817616 10640441344 1329966528 13.967 100 17 31 0 1339009024 5770464 3272032
17:28:15 56082 8192000000 10232339088 10649440256 1311338080 13.798 98 36 72 0 1325617488 5379984 8899424
17:30:15 7235 8192000000 10194560464 10607665152 1264412384 13.370 96 21 42 0 1278390800 9323088 4655328
17:31:22 9685 8192000000 10153747568 10561961984 1237799040 13.126 97 17 35 0 1246655040 5409712 3446288
17:32:34 10890 8192000000 10105843984 10496229376 1170382576 12.500 94 21 42 0 1181297600 6295744 4619280
17:33:46 30466 8192000000 10117086032 10519367680 1190298368 12.686 95 23 46 2 1200350544 5377600 4674576
17:35:19 3765 8192000000 10165284304 10574524416 1275841920 13.475 100 21 42 0 1286385360 5316336 5227104
17:36:26 11847 8192000000 10187649808 10595561472 1292945280 13.631 100 24 48 2 1303643072 5059504 5638288
17:37:42 5882 8192000000 10188533696 10605654016 1303072672 13.723 100 22 43 2 1313823152 6289632 4460848
17:38:51 58536 8192000000 10223946864 10638688256 1331084560 13.977 100 34 66 0 1348571936 10260160 7227216
17:40:55 3113 8192000000 10260263776 10670804992 1353590800 14.180 100 59 116 0 1376685616 10209536 12885280
17:42:07 18929 8192000000 10265873888 10693021696 1368852192 14.317 100 44 88 0 1390900896 11954160 10094544
17:43:31 12372 8192000000 10286576992 10706731008 1378746864 14.405 100 45 90 0 1399577728 10498288 10332576
17:44:48 16151 8192000000 10293186528 10718195712 1388567584 14.493 100 42 82 0 1410097840 11697648 9832608
17:46:12 2391 8192000000 10310115712 10733051904 1397400448 14.572 100 69 127 0 1426126144 13617008 15108688
17:47:20 2072 8192000000 10333345840 10750873600 1404216064 14.633 100 73 143 1 1438220240 17531824 16472352
17:48:27 1855 8192000000 10332512096 10752708608 1407287808 14.660 100 71 141 1 1442175360 18289840 16597712
17:49:33 2279 8192000000 10306751168 10732158976 1383825504 14.451 99 56 108 0 1413912208 17136896 12949808
17:50:45 4831 8192000000 10331195776 10748026880 1397970928 14.577 100 75 148 0 1440951552 25080416 17900208
17:51:54 7870 8192000000 10331760816 10750279680 1380869712 14.424 98 70 139 0 1423673072 25965504 16837856
17:53:09 12929 8192000000 10346478496 10767384576 1391708368 14.521 99 65 130 0 1443134832 35021120 16405344
17:54:28 1401 8192000000 10323841504 10735767552 1377197424 14.391 99 70 140 3 1431789920 36289568 18302928
17:55:34 4710 8192000000 10340580832 10758979584 1402186944 14.614 100 57 113 1 1446219760 29662592 14370224
17:56:44 2062 8192000000 10336363968 10746712064 1381540272 14.430 100 80 156 1 1437255408 35866432 19848704
17:57:54 1473 8192000000 10309703168 10709929984 1352155552 14.167 100 86 174 2 1407366816 32548224 22663040
17:59:03 4666 8192000000 10316232112 10731646976 1367945056 14.309 100 57 111 0 1420369744 38451936 13972752
18:00:16 13871 8192000000 10300643856 10722881536 1361123664 14.247 99 38 77 0 1403808752 32287136 10397952
18:01:35 12158 8192000000 10306330432 10733080576 1369996464 14.327 100 32 64 0 1414837440 36137584 8703392
18:03:09 3028 8192000000 10311789888 10730921984 1369496272 14.323 100 58 118 1 1414287824 28765168 16026384
18:04:20 26401 8192000000 10301144768 10742042624 1373871296 14.362 100 17 34 0 1407993424 29324848 4797280
18:06:03 29486 8192000000 10290342672 10731687936 1351807104 14.164 99 18 36 0 1392169920 35010784 5352032
18:07:33 21249 8192000000 10256966272 10679762944 1328436688 13.953 98 23 44 0 1339677712 5751136 5489888
18:08:56 90170 8192000000 10285482144 10723655680 1353485968 14.179 98 27 55 2 1371056432 10942784 6627680
18:11:27 35710 8192000000 10265882400 10707238912 1352227760 14.168 99 28 56 1 1367555056 8686096 6641200
18:13:08 20137 8192000000 10276364976 10717028352 1374197056 14.365 100 51 102 0 1394826944 8810560 11819328
18:14:30 38804 8192000000 10286074576 10733371392 1382238304 14.437 100 25 50 1 1400835840 12468432 6129104
18:16:09 41771 8192000000 10272883760 10722750464 1353647920 14.180 98 17 34 0 1367267536 9814496 3805120
18:17:53 878 8192000000 10309751232 10731134976 1382444816 14.438 100 69 136 3 1409055104 9947776 16662512
18:19:00 22944 8192000000 10307671584 10747445248 1390564880 14.511 100 30 59 0 1409080240 11445712 7069648
18:20:24 34765 8192000000 10301055056 10759888896 1399749104 14.593 100 22 43 0 1420722304 16058688 4914512
18:22:06 57170 8192000000 10312756528 10772856832 1381536160 14.430 98 22 43 1 1403841616 16985104 5320352
18:24:04 36589 8192000000 10314952736 10771877888 1409926112 14.683 100 28 57 0 1435136960 17746256 7464592
18:26:49 84841 8192000000 10308795008 10772717568 1404945616 14.639 100 13 26 0 1422692656 14599952 3147088
18:29:17 44976 8192000000 10309742576 10759548928 1376287152 14.383 98 28 57 0 1393660288 11308608 6064528
18:31:09 56959 8192000000 10294199792 10751139840 1364449520 14.277 97 11 20 1 1378069200 10618576 3001104
18:33:10 35404 8192000000 10298333664 10738245632 1335040352 14.013 96 27 54 1 1350671824 9530304 6101168
18:34:47 16272 8192000000 10307350304 10747838464 1373810992 14.361 99 39 77 0 1395562288 13353616 8397680
18:36:12 101076 8192000000 10275306432 10715987968 1284264864 13.552 93 13 26 0 1305737328 18826288 2646176
18:38:54 18948 8192000000 10270842480 10708480000 1347416960 14.124 98 32 62 0 1363750656 9782688 6551008
18:40:14 35406 8192000000 10279957936 10731339776 1356656112 14.207 99 16 32 0 1379713344 19422944 3634288
18:41:49 126675 8192000000 10279940016 10728747008 1344413760 14.097 98 24 48 0 1368065056 18446800 5204496
18:45:11 42915 8192000000 10279325920 10731143168 1364281488 14.276 99 21 42 0 1388361040 19190208 4889344
18:46:56 44232 8192000000 10273978256 10723991552 1372130112 14.346 100 14 28 0 1387536128 11608816 3797200
18:49:12 34313 8192000000 10253724064 10694320128 1329174848 13.960 97 30 60 0 1347413968 10969984 7269136
18:51:10 39498 8192000000 10255211520 10709487616 1356466752 14.206 100 12 23 0 1372149120 12875712 2806656
18:52:50 57127 8192000000 10261644608 10703540224 1356028944 14.202 100 19 38 0 1376461424 15695760 4736720
18:54:47 17439 8192000000 10237655392 10700873728 1318911264 13.867 97 11 22 0 1332217024 10888880 2416880
18:56:09 53450 8192000000 10241530656 10693885952 1328208064 13.951 98 8 16 0 1336275184 6624128 1442992
18:58:12 25637 8192000000 10268730368 10702819328 1349784000 14.146 99 47 93 0 1369976048 8768528 11423520
18:59:45 23356 8192000000 10271818432 10711130112 1338261088 14.042 99 36 72 0 1361111664 15110528 7740048
19:01:09 87312 8192000000 10263854704 10713444352 1349239024 14.141 99 14 28 0 1366043856 13521136 3283696
19:03:48 26444 8192000000 10276441248 10715082752 1361956080 14.255 100 21 42 0 1374212144 7981152 4274912
19:05:17 70030 8192000000 10272890656 10729656320 1356709296 14.208 99 32 64 0 1382601824 19268240 6624288
19:07:59 30800 8192000000 10279837456 10719285248 1349250048 14.141 97 23 46 0 1361467456 7222960 4994448
19:10:25 82672 8192000000 10285498640 10744057856 1376073936 14.381 99 17 34 0 1392122272 11983888 4064448
19:12:48 27885 8192000000 10272288640 10736340992 1376068192 14.381 99 7 14 0 1384049136 6894048 1086896
19:14:16 74514 8192000000 10299214816 10756653056 1396338384 14.562 100 16 32 0 1406018496 6240480 3439632
19:16:39 39831 8192000000 10285646224 10744393728 1360415840 14.241 97 8 16 0 1369646224 7761408 1468976
19:18:19 54868 8192000000 10313098048 10769051648 1387570528 14.484 99 26 50 0 1410534624 17603472 5360624
19:20:15 62655 8192000000 10286178800 10742325248 1339620464 14.054 96 26 51 0 1356854192 11488032 5745696
19:22:19 63767 8192000000 10263400464 10712543232 1268250208 13.406 92 11 23 0 1284108176 13214832 2643136
19:24:27 58948 8192000000 10282785456 10726240256 1350627536 14.153 98 23 45 0 1368571216 12322048 5621632
19:26:26 58116 8192000000 10264817408 10716545024 1315891296 13.839 96 22 45 1 1329859968 8950176 5018496
19:28:29 111496 8192000000 10242087024 10690793472 1301848336 13.712 96 17 34 0 1316318880 10547568 3922976
19:31:22 45436 8192000000 10249963520 10705383424 1346628960 14.117 99 21 42 0 1359684288 8272880 4782448
19:33:09 89684 8192000000 10261579872 10716008448 1323518928 13.909 97 22 44 0 1343097184 14646192 4932064
19:35:42 69577 8192000000 10250504016 10714206208 1347556432 14.125 99 20 41 1 1362854448 10239152 5058864
19:38:24 33190 8192000000 9067708144 9296801792 237077280 2.812 18 4 8 0 241727376 3798816 851280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-03-2023 19:40:26 to 17-03-2023 19:40:42.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-03-2023 19:40:43 to 17-03-2023 19:40:45.

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          252089
MON$OLDEST_ACTIVE               252090
MON$OLDEST_SNAPSHOT             252090
MON$NEXT_TRANSACTION            252090
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                       2029984
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:391: 
:392: mscs-test-161.budzdorov.ru    Fri Mar 17 19:39:05 2023
:393:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:394: 
--- end of diff output ---

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

17.03.23 19:40:46. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-03-17 19:40:48 No severe PSQL-related problems occured
17.03.23 19:40:50. End of report.