Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.03.2022 22: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 16820940 kB
MemAvailable 31623944 kB
Buffers 0 kB
Cached 14920768 kB
SwapCached 2200 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-31 22:38:12
DTS_END                         2022-03-31 22:38:12.2260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53462
TRN_ID                          239376
ATT_ID                          682
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: 8926.89 19815 1071227
customer order: creation 169.40 348 20328
customer order: refuse 14.28 324 1713
order to supplier: creation 45.16 1292 5419
order to supplier: removal 3.57 88357 428
invoice (draft): creation 45.91 3061 5509
invoice (draft): removal 50.66 701 6079
invoice accept: apply 40.67 17177 4881
invoice accept: cancel 45.73 740 5488
customer reserve: creation 3775.82 170 453098
customer reserve: removal 4442.53 56 533104
realization accept: apply 81.39 168 9767
realization accept: cancel 84.45 105 10134
payment from customer: creation 58.10 115 6972
payment from customer: removal 5.70 57 684
payment to supplier: creation 48.45 118 5814
payment to supplier: removal 3.54 75 425
service: total inventory turnovers 4.44 1095 533
service: total monetary turnovers 6.21 19 745
service: aggregate perf. data 0.85 18164 102
service: refresh index statistics 0.03 264163 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-03-2022 22:39:36 to 31-03-2022 22:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8082
TEST_TIME 2 5011
TEST_TIME 3 5377
TEST_TIME 4 5426
TEST_TIME 5 4934
TEST_TIME 6 4882
TEST_TIME 7 5430
TEST_TIME 8 5190
TEST_TIME 9 5519
TEST_TIME 10 5631
TEST_TIME 11 5563
TEST_TIME 12 5433
TEST_TIME 13 6038
TEST_TIME 14 6124
TEST_TIME 15 6432
TEST_TIME 16 6687
TEST_TIME 17 7263
TEST_TIME 18 8064
TEST_TIME 19 8151
TEST_TIME 20 8273
TEST_TIME 21 8861
TEST_TIME 22 9050
TEST_TIME 23 9697
TEST_TIME 24 10149
TEST_TIME 25 10513
TEST_TIME 26 10281
TEST_TIME 27 10088
TEST_TIME 28 10035
TEST_TIME 29 9985
TEST_TIME 30 10142
TEST_TIME 31 10152
TEST_TIME 32 10065
TEST_TIME 33 9861
TEST_TIME 34 9782
TEST_TIME 35 9677
TEST_TIME 36 9602
TEST_TIME 37 9727
TEST_TIME 38 9544
TEST_TIME 39 9541
TEST_TIME 40 9477
TEST_TIME 41 9460
TEST_TIME 42 9415
TEST_TIME 43 9439
TEST_TIME 44 9392
TEST_TIME 45 9258
TEST_TIME 46 9241
TEST_TIME 47 9186
TEST_TIME 48 9119
TEST_TIME 49 9157
TEST_TIME 50 9104
TEST_TIME 51 9115
TEST_TIME 52 9107
TEST_TIME 53 9237
TEST_TIME 54 9304
TEST_TIME 55 9315
TEST_TIME 56 9294
TEST_TIME 57 9443
TEST_TIME 58 9384
TEST_TIME 59 9397
TEST_TIME 60 9478
TEST_TIME 61 9446
TEST_TIME 62 9411
TEST_TIME 63 9281
TEST_TIME 64 9278
TEST_TIME 65 9233
TEST_TIME 66 9140
TEST_TIME 67 9109
TEST_TIME 68 9037
TEST_TIME 69 8980
TEST_TIME 70 9004
TEST_TIME 71 9033
TEST_TIME 72 9028
TEST_TIME 73 9011
TEST_TIME 74 9076
TEST_TIME 75 9119
TEST_TIME 76 9087
TEST_TIME 77 9109
TEST_TIME 78 9093
TEST_TIME 79 9141
TEST_TIME 80 9230
TEST_TIME 81 9268
TEST_TIME 82 9288
TEST_TIME 83 9256
TEST_TIME 84 9205
TEST_TIME 85 9181
TEST_TIME 86 9151
TEST_TIME 87 9144
TEST_TIME 88 9092
TEST_TIME 89 9061
TEST_TIME 90 9071
TEST_TIME 91 9034
TEST_TIME 92 9007
TEST_TIME 93 8989
TEST_TIME 94 9032
TEST_TIME 95 9045
TEST_TIME 96 9044
TEST_TIME 97 9166
TEST_TIME 98 9152
TEST_TIME 99 9124
TEST_TIME 100 9108
TEST_TIME 101 9157
TEST_TIME 102 9161
TEST_TIME 103 9137
TEST_TIME 104 9146
TEST_TIME 105 9249
TEST_TIME 106 9246
TEST_TIME 107 9176
TEST_TIME 108 9132
TEST_TIME 109 9093
TEST_TIME 110 9095
TEST_TIME 111 9121
TEST_TIME 112 9068
TEST_TIME 113 9081
TEST_TIME 114 9067
TEST_TIME 115 9046
TEST_TIME 116 9009
TEST_TIME 117 8982
TEST_TIME 118 9000
TEST_TIME 119 8976
TEST_TIME 120 8928

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-03-2022 22:39:40 to 31-03-2022 22:39:43.

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 1491759 1491759 0 0.00 0 23150 6 0 0
doc_list_biud 1491759 1491759 0 0.00 0 47876 25 0 0
sp_add_invoice_to_stock 4988 4881 107 2.14 17 141197 17177 0 107
sp_cancel_adding_invoice 5488 5488 0 0.00 5 61164 741 0 0
sp_cancel_client_order 1713 1713 0 0.00 4 14422 324 0 0
sp_cancel_customer_reserve 533104 533104 0 0.00 1 47878 56 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 2246 57 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 2 2796 76 0 0
sp_cancel_supplier_invoice 6086 6079 7 0.11 2 17499 701 0 7
sp_cancel_supplier_order 428 428 0 0.00 4 1083245 88358 0 0
sp_cancel_write_off 10134 10134 0 0.00 1 9354 106 0 0
sp_client_order 20328 20328 0 0.00 3 26099 348 0 0
sp_customer_reserve 453098 453098 0 0.00 0 117947 170 0 0
sp_fill_shopping_cart 31256 31256 0 0.00 1 18429 36 0 0
sp_fill_shopping_cart_clo_res 453092 453092 0 0.00 0 15935 14 0 0
sp_get_clo_for_invoice 4874 4874 0 0.00 0 38047 315 0 0
sp_kill_cost_storno 16731 16731 0 0.00 0 15946 52 0 0
sp_kill_qstorno_ret_qs2qd 1082849 1082849 0 0.00 0 47874 30 0 0
sp_kill_qty_storno 571587 571587 0 0.00 0 47876 65 0 0
sp_lock_selected_doc 20376 20376 0 0.00 0 10303 5 0 0
sp_make_cost_storno 27427 27427 0 0.00 0 23019 50 0 0
sp_multiply_rows_for_pdistr 27427 27427 0 0.00 0 5878 25 0 0
sp_multiply_rows_for_qdistr 20328 20328 0 0.00 0 19754 164 0 0
sp_pay_from_customer 6972 6972 0 0.00 4 15198 116 0 0
sp_pay_to_supplier 5814 5814 0 0.00 5 14143 118 0 0
sp_payment_common 12786 12786 0 0.00 1 14130 32 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 14415 292 0 0
sp_qd_handle_on_invoice_upd_sts 10362 10362 0 0.00 0 17713 314 0 0
sp_qd_handle_on_reserve_upd_sts 9767 9767 0 0.00 0 6860 45 0 0
sp_reserve_write_off 9767 9767 0 0.00 6 11807 169 0 0
sp_supplier_invoice 5509 5509 0 0.00 6 87723 3061 0 0
sp_supplier_order 5419 5419 0 0.00 5 36479 1292 0 0
srv_aggregate_perf_data 102 102 0 0.00 2708 291803 18165 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2877 150996 48712 0 0
srv_find_qd_qs_mism 1024727 1024727 0 0.00 0 16096 5 0 0
srv_make_invnt_saldo 533 533 0 0.00 26 29358 1095 0 0
srv_make_money_saldo 745 745 0 0.00 1 818 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 157918 309612 264164 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 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 4881 4881 0 0.00 0 13758 144 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 5332 77 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 5938 15 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 4218 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 669 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 553 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 563 5 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 23454 1306 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 11709 19 0 0
t$perf-norm:sp_client_order 20328 20328 0 0.00 0 10799 13 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 15325 18 0 0
t$perf-norm:sp_pay_from_customer 6972 6972 0 0.00 0 5688 7 0 0
t$perf-norm:sp_pay_to_supplier 5814 5814 0 0.00 0 13273 14 0 0
t$perf-norm:sp_reserve_write_off 9767 9767 0 0.00 0 5597 9 0 0
t$perf-norm:sp_supplier_invoice 5509 5509 0 0.00 0 5827 16 0 0
t$perf-norm:sp_supplier_order 5419 5419 0 0.00 0 13255 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 190 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 99 3 0 0
t$perf-norm:srv_make_invnt_saldo 533 533 0 0.00 0 873 18 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 775 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9962 9748 214 2.14 0 11711 20 0 214
v_all_customers 37050 37050 0 0.00 0 35 0 0 0
v_all_suppliers 30088 30088 0 0.00 0 35 1 0 0
v_all_wares 40656 40656 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 3780 13 0 0
v_cancel_client_order 3426 3426 0 0.00 0 3210 5 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 1541 3 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 1446 7 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2768 10 0 0
v_cancel_supplier_invoice 814 800 14 1.71 0 2087 21 0 14
v_cancel_supplier_order 856 856 0 0.00 0 4667 14 0 0
v_cancel_write_off 1952 1952 0 0.00 0 5164 15 0 0
v_max_id_clo_ord 5419 5419 0 0.00 0 2390 2 0 0
v_max_id_clo_res 995 995 0 0.00 0 3726 19 0 0
v_max_id_ord_sup 5509 5509 0 0.00 0 4103 5 0 0
v_max_non_paid_invoice 1698 1698 0 0.00 0 307 2 0 0
v_max_non_paid_realizn 3684 3684 0 0.00 0 2069 5 0 0
v_min_id_clo_ord 5419 5419 0 0.00 0 5483 8 0 0
v_min_id_clo_res 995 995 0 0.00 0 15828 32 0 0
v_min_id_ord_sup 5509 5509 0 0.00 0 10996 9 0 0
v_min_non_paid_invoice 5814 5814 0 0.00 0 14126 28 0 0
v_min_non_paid_realizn 6972 6972 0 0.00 0 9030 22 0 0
v_our_firm 10182 10182 0 0.00 0 15 0 0 0
v_reserve_write_off 19534 19534 0 0.00 0 11427 11 0 0
x_lock_depdocs_on_canc_invoice 11167 11167 0 0.00 0 10452 106 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 12159 325 0 0
x_make_qstorno_1000_1200 5419 5419 0 0.00 1 36466 1222 0 0
x_make_qstorno_1200_2000 5509 5509 0 0.00 3 87702 2928 0 0
x_make_qstorno_2100_3300 453098 453098 0 0.00 0 117904 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-03-2022 22:39:44 to 31-03-2022 22:39:48.

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:15 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:16 27865 8192000000 9907004976 10217365504 983219680 10.716 100 90 179 2 1012595952 5838192 23538080
20:10:51 26765 8192000000 9960409472 10285404160 1040976400 11.274 100 87 174 1 1072065648 8841552 22247696
20:12:22 12384 8192000000 9991261376 10324217856 1070204064 11.554 100 78 156 1 1099384688 9789952 19390672
20:13:43 10334 8192000000 10061066944 10411954176 1144518512 12.258 100 82 164 0 1174811824 11191392 19101920
20:14:56 7507 8192000000 10097501520 10458771456 1186234848 12.648 100 86 172 0 1219464192 13447600 19781744
20:16:13 3858 8192000000 10145006752 10511544320 1224298496 13.001 100 82 163 1 1256424320 13858768 18267056
20:17:19 2612 8192000000 10166524816 10538991616 1251147280 13.249 100 90 179 3 1284904640 14582608 19174752
20:18:24 1141 8192000000 10201463168 10581606400 1285871024 13.567 100 93 185 1 1319440512 13549168 20020320
20:19:25 1836 8192000000 10214320512 10595160064 1301869552 13.712 100 92 185 1 1335680064 13022560 20787952
20:20:28 12255 8192000000 10195404592 10605510656 1308612128 13.773 100 26 52 1 1320913024 6309488 5991408
20:21:44 8253 8192000000 10206401264 10621288448 1318241664 13.861 100 30 61 0 1336256656 10918880 7096112
20:22:55 4310 8192000000 10224730688 10635239424 1325917696 13.930 100 37 74 0 1345715744 11357552 8440496
20:24:03 17410 8192000000 10214220896 10633953280 1332875344 13.993 100 19 38 0 1343319968 5873808 4570816
20:25:21 7239 8192000000 10259492480 10656464896 1335596112 14.018 100 70 141 2 1358547328 5651872 17299344
20:26:28 8227 8192000000 10231222080 10652602368 1338833808 14.047 100 29 58 0 1351654688 6486864 6334016
20:27:37 13683 8192000000 10207442640 10627850240 1289943984 13.604 97 14 28 0 1298287856 5398432 2945440
20:28:53 16991 8192000000 10201425232 10605633536 1271558208 13.436 96 21 42 0 1281815088 5050448 5206432
20:30:11 27921 8192000000 10138327200 10540392448 1160286192 12.406 90 22 44 0 1170297808 5098080 4913536
20:31:43 9036 8192000000 10138485920 10538758144 1227272848 13.029 98 33 64 0 1240845232 6406928 7165456
20:32:55 9571 8192000000 10157163584 10559946752 1244743312 13.190 98 25 50 0 1256790928 5527712 6519904
20:34:09 69931 8192000000 10194791712 10594250752 1288584160 13.591 100 38 74 2 1303080496 4916096 9580240
20:36:20 4344 8192000000 10200360320 10607747072 1300159024 13.697 100 32 64 0 1314403776 7013616 7231136
20:37:27 7443 8192000000 10199719760 10619236352 1314708112 13.829 100 19 39 0 1324077136 4963776 4405248
20:38:36 14410 8192000000 10245876864 10657013760 1343892608 14.092 100 47 92 2 1361099648 6906320 10300720
20:39:53 45783 8192000000 10261477344 10677510144 1352935488 14.174 100 59 118 0 1376990224 10042752 14011984
20:41:45 20220 8192000000 10275407088 10697977856 1365237200 14.284 100 40 80 2 1389708944 15131648 9340096
20:43:08 34376 8192000000 10293967664 10715959296 1378075904 14.399 100 44 88 1 1406643760 18729536 9838320
20:44:51 9374 8192000000 10314981920 10736304128 1393355104 14.536 100 49 98 2 1431834912 25678512 12801296
20:46:14 7638 8192000000 10319231808 10740277248 1394207200 14.543 100 47 92 1 1429648688 23921344 11520144
20:47:27 5585 8192000000 10342090304 10769612800 1413780800 14.718 100 49 97 0 1453753456 27925024 12047632
20:48:40 5652 8192000000 10352393200 10774519808 1413618768 14.716 100 56 112 2 1460090528 32466224 14005536
20:49:55 5246 8192000000 10356224928 10777579520 1418669376 14.761 100 65 130 0 1465946400 31698432 15578592
20:51:05 10306 8192000000 10357460160 10777243648 1402302416 14.615 99 64 127 0 1457172240 38712672 16157152
20:52:33 3167 8192000000 10362077568 10774384640 1411159376 14.694 100 70 140 0 1468607424 39850544 17597504
20:53:41 3525 8192000000 10371766544 10792824832 1416795584 14.744 100 65 130 1 1479802768 45754512 17252672
20:54:50 2873 8192000000 10373339056 10784165888 1402052384 14.613 100 84 167 0 1472700096 48941792 21705920
20:55:59 4845 8192000000 10345173024 10753318912 1379778480 14.415 100 57 114 1 1445048832 50348864 14921488
20:57:10 12621 8192000000 10316180256 10746163200 1381094000 14.426 100 39 78 1 1429864496 38662896 10107600
20:58:29 11397 8192000000 10314591344 10738958336 1365243424 14.284 100 36 72 0 1417717408 42659552 9814432
20:59:52 30179 8192000000 10285183136 10728243200 1335220160 14.014 98 19 38 0 1377993872 37375184 5398528
21:01:27 28024 8192000000 10233756176 10666807296 1282331472 13.534 96 19 38 0 1316311792 28241840 5738480
21:03:00 64353 8192000000 10246650416 10684948480 1301367888 13.708 97 12 24 1 1321753424 17687760 2697776
21:05:06 23569 8192000000 10269185296 10699673600 1348043600 14.130 100 22 44 1 1372421968 18782256 5596112
21:06:48 95223 8192000000 10269861408 10695884800 1307920224 13.767 97 31 62 1 1332921328 17632688 7368416
21:09:32 83388 8192000000 10261250560 10707595264 1339235376 14.051 98 12 24 0 1350861952 9176976 2449600
21:12:30 35458 8192000000 10285414784 10712887296 1359227552 14.230 99 21 42 0 1372391648 8463248 4700848
21:14:06 82282 8192000000 10287248816 10748858368 1373586464 14.359 99 19 38 1 1397236720 18717600 4932656
21:16:29 41779 8192000000 10306524112 10752090112 1394997248 14.550 100 19 38 1 1412425376 12788752 4639376
21:18:11 150995 8192000000 10307686464 10764963840 1392699072 14.530 99 19 38 0 1417865696 20448128 4718496
21:21:54 38201 8192000000 10286200352 10744717312 1391829584 14.522 100 17 34 0 1404692672 9097360 3765728
21:23:39 102329 8192000000 10287293024 10756907008 1391584288 14.520 100 8 16 0 1404511600 11148400 1778912
21:26:59 40788 8192000000 10295591920 10757120000 1386548656 14.475 99 13 25 1 1402889296 13368432 2972208
21:28:40 35146 8192000000 10305986608 10759106560 1390167936 14.507 99 32 64 0 1406097568 8337056 7592576
21:30:17 77661 8192000000 10302257728 10767441920 1355548608 14.197 96 14 28 0 1370274560 11413776 3312176
21:32:35 73742 8192000000 10307688176 10765627392 1378171248 14.400 98 22 44 0 1394764736 11682288 4911200
21:35:01 84472 8192000000 10288266224 10757431296 1381152512 14.427 99 27 54 0 1401807984 14619024 6036448
21:37:27 46094 8192000000 10299655296 10761359360 1387697824 14.485 100 21 42 1 1413207520 20979280 4530416
21:39:14 71682 8192000000 10301589296 10745257984 1361623520 14.252 98 15 30 2 1375219664 10547936 3048208
21:41:26 54571 8192000000 10280905760 10732957696 1353638944 14.180 97 36 72 0 1369149744 7975824 7534976
21:43:40 35842 8192000000 10266516912 10726854656 1377408512 14.393 100 7 14 0 1386172080 7272384 1491184
21:46:09 31130 8192000000 10265310096 10720567296 1373470080 14.358 100 19 36 2 1384114592 6260672 4383840
21:47:42 50747 8192000000 10264752880 10726899712 1329529600 13.963 96 16 32 0 1342649904 9408320 3711984
21:50:06 54295 8192000000 10239658576 10687725568 1305066480 13.741 96 20 39 1 1317743056 8505440 4171136
21:52:02 73921 8192000000 10264654688 10706087936 1318717152 13.865 97 20 40 0 1331436352 8139520 4579680
21:54:22 41249 8192000000 10264449200 10706759680 1340807024 14.065 99 37 74 0 1361121216 12055072 8259120
21:56:05 75402 8192000000 10261923264 10719223808 1333793472 14.001 98 21 42 0 1353201072 14856640 4550960
21:58:21 85566 8192000000 10269222112 10729353216 1349028128 14.139 99 21 42 0 1369342736 15705776 4608832
22:01:38 103864 8192000000 10266736176 10715807744 1355062160 14.193 99 19 38 1 1371030928 11770464 4198304
22:04:30 15507 8192000000 10277991136 10729451520 1374074512 14.364 100 17 34 1 1389979808 12524944 3380352
22:05:46 58943 8192000000 10280257824 10741841920 1381972528 14.434 100 7 14 0 1392980720 9569792 1438400
22:07:58 42945 8192000000 10268293904 10725629952 1362613168 14.261 99 12 24 0 1375141328 9786384 2741776
22:09:41 70078 8192000000 10288219664 10747957248 1352098096 14.166 97 15 30 0 1367234144 12153776 2982272
22:11:51 74141 8192000000 10285297856 10744807424 1357757568 14.217 97 14 28 0 1376572192 15467792 3346832
22:14:06 76393 8192000000 10310391184 10761494528 1375609840 14.377 98 25 50 0 1396467248 15001856 5855552
22:16:23 51672 8192000000 10299736016 10757369856 1376613216 14.386 98 9 18 0 1388287936 9826320 1848400
22:18:14 32619 8192000000 10320493728 10761109504 1396538992 14.564 100 45 87 1 1415588672 9597296 9452384
22:19:49 66087 8192000000 10289054128 10743160832 1347054048 14.121 96 18 37 0 1361316656 10570896 3691712
22:22:04 57811 8192000000 10310522128 10769580032 1392144704 14.525 99 18 36 0 1412246112 15981744 4119664
22:24:08 68723 8192000000 10273879776 10721378304 1322507584 13.899 96 9 18 0 1336501984 12158928 1835472
22:26:30 71933 8192000000 10291434608 10739957760 1354676656 14.190 98 28 56 3 1379147488 18058528 6412304
22:28:44 70913 8192000000 10264221648 10732339200 1351725536 14.163 98 19 38 0 1373079504 16683456 4670512
22:31:48 94938 8192000000 10266800800 10725814272 1333406560 13.998 97 14 28 1 1344193040 8015280 2771200
22:34:57 72029 8192000000 10270124368 10731319296 1364397824 14.277 99 12 24 0 1376405216 9108208 2899184
22:37:09 37650 8192000000 10295476208 10741350400 1364694912 14.279 99 29 58 1 1378833184 7371344 6766928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 31-03-2022 22:39:50 to 31-03-2022 22:40:04.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-03-2022 22:40:05 to 31-03-2022 22:40:07.

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          237961
MON$OLDEST_ACTIVE               239492
MON$OLDEST_SNAPSHOT             239492
MON$NEXT_TRANSACTION            239492
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                       2023168
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 ---
:776: 
:777: mscs-test-161.budzdorov.ru    Thu Mar 31 22:38:14 2022
:778:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:779: 
:780: 
:781: mscs-test-161.budzdorov.ru    Thu Mar 31 22:38:19 2022
:782:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:783: 
:784: 
:785: mscs-test-161.budzdorov.ru    Thu Mar 31 22:38:20 2022
:786:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:787: 
--- end of diff output ---

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

31.03.22 22:40:08. 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-31 22:40:11 No severe PSQL-related problems occured
31.03.22 22:40:12. End of report.