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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16277712 kB
MemAvailable 31163000 kB
Buffers 0 kB
Cached 15404712 kB
SwapCached 2492 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-28 01:38:28
DTS_END                         2022-06-28 01:38:28.1810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60314
TRN_ID                          240825
ATT_ID                          711
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: 9302.92 18891 1116350
customer order: creation 172.53 382 20703
customer order: refuse 14.65 337 1758
order to supplier: creation 46.52 1295 5582
order to supplier: removal 3.38 112950 406
invoice (draft): creation 45.58 2991 5469
invoice (draft): removal 53.24 822 6389
invoice accept: apply 41.73 17908 5007
invoice accept: cancel 47.85 725 5742
customer reserve: creation 3881.20 175 465744
customer reserve: removal 4701.31 65 564157
realization accept: apply 82.99 179 9959
realization accept: cancel 80.90 116 9708
payment from customer: creation 60.02 97 7202
payment from customer: removal 5.45 87 654
payment to supplier: creation 50.50 128 6060
payment to supplier: removal 3.30 62 396
service: total inventory turnovers 4.43 1089 531
service: total monetary turnovers 6.39 24 767
service: aggregate perf. data 0.93 13105 112
service: refresh index statistics 0.03 225282 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-06-2022 01:39:21 to 28-06-2022 01:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6960
TEST_TIME 2 6370
TEST_TIME 3 6879
TEST_TIME 4 7346
TEST_TIME 5 6520
TEST_TIME 6 6157
TEST_TIME 7 5995
TEST_TIME 8 6074
TEST_TIME 9 5971
TEST_TIME 10 5981
TEST_TIME 11 6326
TEST_TIME 12 6405
TEST_TIME 13 6278
TEST_TIME 14 7044
TEST_TIME 15 6970
TEST_TIME 16 6958
TEST_TIME 17 7233
TEST_TIME 18 7580
TEST_TIME 19 7754
TEST_TIME 20 8091
TEST_TIME 21 8676
TEST_TIME 22 8952
TEST_TIME 23 9051
TEST_TIME 24 9086
TEST_TIME 25 9761
TEST_TIME 26 10736
TEST_TIME 27 10959
TEST_TIME 28 10851
TEST_TIME 29 10599
TEST_TIME 30 10523
TEST_TIME 31 10352
TEST_TIME 32 10301
TEST_TIME 33 10120
TEST_TIME 34 9956
TEST_TIME 35 9897
TEST_TIME 36 10033
TEST_TIME 37 9967
TEST_TIME 38 9840
TEST_TIME 39 9622
TEST_TIME 40 9712
TEST_TIME 41 9633
TEST_TIME 42 9517
TEST_TIME 43 9429
TEST_TIME 44 9282
TEST_TIME 45 9351
TEST_TIME 46 9377
TEST_TIME 47 9329
TEST_TIME 48 9298
TEST_TIME 49 9312
TEST_TIME 50 9402
TEST_TIME 51 9470
TEST_TIME 52 9469
TEST_TIME 53 9408
TEST_TIME 54 9606
TEST_TIME 55 9656
TEST_TIME 56 9661
TEST_TIME 57 9643
TEST_TIME 58 9637
TEST_TIME 59 9523
TEST_TIME 60 9560
TEST_TIME 61 9445
TEST_TIME 62 9422
TEST_TIME 63 9384
TEST_TIME 64 9355
TEST_TIME 65 9321
TEST_TIME 66 9285
TEST_TIME 67 9271
TEST_TIME 68 9270
TEST_TIME 69 9229
TEST_TIME 70 9351
TEST_TIME 71 9474
TEST_TIME 72 9527
TEST_TIME 73 9506
TEST_TIME 74 9620
TEST_TIME 75 9632
TEST_TIME 76 9610
TEST_TIME 77 9594
TEST_TIME 78 9543
TEST_TIME 79 9439
TEST_TIME 80 9463
TEST_TIME 81 9426
TEST_TIME 82 9421
TEST_TIME 83 9337
TEST_TIME 84 9375
TEST_TIME 85 9296
TEST_TIME 86 9303
TEST_TIME 87 9279
TEST_TIME 88 9248
TEST_TIME 89 9199
TEST_TIME 90 9216
TEST_TIME 91 9261
TEST_TIME 92 9246
TEST_TIME 93 9313
TEST_TIME 94 9302
TEST_TIME 95 9315
TEST_TIME 96 9285
TEST_TIME 97 9368
TEST_TIME 98 9342
TEST_TIME 99 9371
TEST_TIME 100 9413
TEST_TIME 101 9431
TEST_TIME 102 9465
TEST_TIME 103 9392
TEST_TIME 104 9367
TEST_TIME 105 9335
TEST_TIME 106 9327
TEST_TIME 107 9332
TEST_TIME 108 9287
TEST_TIME 109 9249
TEST_TIME 110 9258
TEST_TIME 111 9193
TEST_TIME 112 9229
TEST_TIME 113 9240
TEST_TIME 114 9241
TEST_TIME 115 9250
TEST_TIME 116 9248
TEST_TIME 117 9241
TEST_TIME 118 9347
TEST_TIME 119 9317
TEST_TIME 120 9315

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-06-2022 01:39:24 to 28-06-2022 01:39:28.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1547824 1547824 0 0.00 0 25854 6 0 0
doc_list_biud 1547822 1547822 0 0.00 0 59690 30 0 0
sp_add_invoice_to_stock 5137 5007 130 2.53 23 168630 17909 0 130
sp_cancel_adding_invoice 5742 5742 0 0.00 4 47347 726 0 0
sp_cancel_client_order 1758 1758 0 0.00 4 9998 337 0 0
sp_cancel_customer_reserve 564157 564157 0 0.00 1 59691 65 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 7441 88 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 3364 63 0 0
sp_cancel_supplier_invoice 6395 6389 6 0.09 2 21865 822 0 6
sp_cancel_supplier_order 406 406 0 0.00 7 1085804 112951 0 0
sp_cancel_write_off 9708 9708 0 0.00 1 8197 117 0 0
sp_client_order 20703 20703 0 0.00 3 27655 383 0 0
sp_customer_reserve 465744 465744 0 0.00 0 89142 176 0 0
sp_fill_shopping_cart 31754 31754 0 0.00 1 13288 40 0 0
sp_fill_shopping_cart_clo_res 465718 465718 0 0.00 0 13111 14 0 0
sp_get_clo_for_invoice 4981 4981 0 0.00 0 40933 313 0 0
sp_kill_cost_storno 16500 16500 0 0.00 0 8191 52 0 0
sp_kill_qstorno_ret_qs2qd 1144817 1144817 0 0.00 0 57905 35 0 0
sp_kill_qty_storno 603100 603100 0 0.00 0 59688 75 0 0
sp_lock_selected_doc 20679 20679 0 0.00 0 9753 6 0 0
sp_make_cost_storno 28202 28202 0 0.00 0 21221 47 0 0
sp_multiply_rows_for_pdistr 28202 28202 0 0.00 0 25843 32 0 0
sp_multiply_rows_for_qdistr 20703 20703 0 0.00 0 18808 179 0 0
sp_pay_from_customer 7202 7202 0 0.00 4 8067 98 0 0
sp_pay_to_supplier 6060 6060 0 0.00 4 25858 128 0 0
sp_payment_common 13262 13262 0 0.00 1 6184 24 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 9982 310 0 0
sp_qd_handle_on_invoice_upd_sts 10723 10723 0 0.00 0 26861 321 0 0
sp_qd_handle_on_reserve_upd_sts 9959 9959 0 0.00 0 19771 70 0 0
sp_reserve_write_off 9959 9959 0 0.00 6 21234 179 0 0
sp_supplier_invoice 5469 5469 0 0.00 6 74426 2992 0 0
sp_supplier_order 5582 5582 0 0.00 5 36687 1296 0 0
srv_aggregate_perf_data 112 112 0 0.00 2578 134338 13105 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2149 112490 46134 0 0
srv_find_qd_qs_mism 1068310 1068310 0 0.00 0 15578 5 0 0
srv_make_invnt_saldo 531 531 0 0.00 36 66706 1089 0 0
srv_make_money_saldo 767 767 0 0.00 1 3066 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 109471 333438 225283 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5007 5007 0 0.00 0 12387 177 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 5626 89 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 2707 14 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 946 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 3710 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 100 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 4690 31 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 34218 1543 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 811 8 0 0
t$perf-norm:sp_client_order 20703 20703 0 0.00 0 18378 19 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 3717 11 0 0
t$perf-norm:sp_pay_from_customer 7202 7202 0 0.00 0 9989 14 0 0
t$perf-norm:sp_pay_to_supplier 6060 6060 0 0.00 0 15475 15 0 0
t$perf-norm:sp_reserve_write_off 9959 9959 0 0.00 0 14239 14 0 0
t$perf-norm:sp_supplier_invoice 5469 5469 0 0.00 0 10541 24 0 0
t$perf-norm:sp_supplier_order 5582 5582 0 0.00 0 8925 18 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 409 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 28 1 0 0
t$perf-norm:srv_make_invnt_saldo 531 531 0 0.00 0 1788 21 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 3657 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10222 9962 260 2.54 0 6283 17 0 260
v_all_customers 37676 37676 0 0.00 0 17 0 0 0
v_all_suppliers 30892 30892 0 0.00 0 22 1 0 0
v_all_wares 41406 41406 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1117 7 0 0
v_cancel_client_order 3516 3516 0 0.00 0 1875 4 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 1966 7 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 3119 6 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 1667 8 0 0
v_cancel_supplier_invoice 844 832 12 1.42 0 901 13 0 12
v_cancel_supplier_order 812 812 0 0.00 0 2503 12 0 0
v_cancel_write_off 2016 2016 0 0.00 0 2915 14 0 0
v_max_id_clo_ord 5582 5582 0 0.00 0 3628 2 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 2202 11 0 0
v_max_id_ord_sup 5469 5469 0 0.00 0 6763 6 0 0
v_max_non_paid_invoice 1665 1665 0 0.00 0 851 3 0 0
v_max_non_paid_realizn 3840 3840 0 0.00 0 618 2 0 0
v_min_id_clo_ord 5582 5582 0 0.00 0 6523 7 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 5300 11 0 0
v_min_id_ord_sup 5469 5469 0 0.00 0 2631 4 0 0
v_min_non_paid_invoice 6060 6060 0 0.00 0 5547 19 0 0
v_min_non_paid_realizn 7202 7202 0 0.00 0 6141 18 0 0
v_our_firm 10454 10454 0 0.00 0 18 1 0 0
v_reserve_write_off 19918 19918 0 0.00 0 3142 5 0 0
x_lock_depdocs_on_canc_invoice 11715 11715 0 0.00 0 29736 120 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 10071 372 0 0
x_make_qstorno_1000_1200 5582 5582 0 0.00 1 36682 1215 0 0
x_make_qstorno_1200_2000 5469 5469 0 0.00 3 62360 2849 0 0
x_make_qstorno_2100_3300 465744 465744 0 0.00 0 89138 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-06-2022 01:39:29 to 28-06-2022 01:39:33.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:31 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:32 29663 8192000000 9920869136 10235121664 1000773168 10.886 100 89 177 0 1030391072 6594000 23023904
23:11:08 14640 8192000000 9963683552 10291326976 1045158128 11.314 100 89 178 1 1076828320 8719488 22950704
23:12:28 14225 8192000000 10007068576 10343342080 1085319552 11.698 100 83 166 0 1116412896 10710208 20383136
23:13:45 11056 8192000000 10032581504 10381467648 1118788560 12.016 100 80 160 0 1149431456 11727520 18915376
23:15:01 6658 8192000000 10086343536 10448920576 1179582992 12.586 100 80 160 1 1209838112 12020512 18234608
23:16:10 7392 8192000000 10130187328 10496172032 1211786624 12.886 100 78 156 0 1243276672 13767040 17723008
23:17:19 3121 8192000000 10169810176 10547822592 1257346576 13.306 100 92 185 0 1291740752 13653680 20740496
23:18:24 1542 8192000000 10204264608 10584956928 1287000576 13.577 100 79 157 0 1317480176 12352416 18127184
23:19:27 27625 8192000000 10213394640 10605551616 1308267728 13.770 100 67 134 2 1332373568 7336208 16769632
23:20:55 6278 8192000000 10204988496 10616352768 1312118912 13.805 100 25 50 0 1328409312 10870304 5420096
23:22:03 9081 8192000000 10202767824 10624221184 1322903744 13.903 100 20 40 1 1334354384 6511056 4939584
23:23:15 8208 8192000000 10218932560 10631954432 1328062192 13.950 100 30 60 1 1342132992 6972704 7098096
23:24:24 53237 8192000000 10239729856 10645868544 1334280560 14.006 100 46 90 1 1350607760 5502656 10824544
23:26:18 3351 8192000000 10231090672 10648969216 1326705408 13.937 99 32 64 0 1341135440 6798832 7631200
23:27:22 4216 8192000000 10220244192 10635702272 1305142464 13.742 98 20 40 0 1319900080 9854576 4903040
23:28:27 53337 8192000000 10186503360 10601336832 1250308384 13.241 95 23 46 1 1261554704 5864608 5381712
23:30:21 7737 8192000000 10132232544 10530955264 1217634512 12.940 95 26 52 1 1229690240 5584496 6471232
23:31:29 6187 8192000000 10163515232 10569035776 1271687840 13.437 100 29 58 0 1285009696 6153376 7168480
23:32:36 48952 8192000000 10153442432 10553352192 1247497792 13.215 98 30 57 3 1259174512 4259360 7417360
23:34:31 4183 8192000000 10179992016 10585800704 1285328784 13.562 100 35 70 0 1300193248 7224416 7640048
23:35:36 13714 8192000000 10195199424 10608922624 1299863824 13.694 100 20 40 1 1310728432 5947664 4916944
23:36:50 10708 8192000000 10207215632 10623107072 1315633456 13.837 100 20 40 0 1326597920 6740912 4223552
23:38:03 29009 8192000000 10226260624 10637570048 1325778432 13.929 100 27 54 0 1337537360 6061248 5697680
23:39:44 7784 8192000000 10268227056 10681339904 1352674464 14.172 100 62 124 0 1378865408 13011712 13179232
23:40:56 5890 8192000000 10287236832 10698530816 1366333856 14.294 100 65 130 1 1398261984 17073488 14854640
23:42:09 17490 8192000000 10292081568 10716872704 1378024144 14.399 100 45 90 4 1410378992 21578864 10775984
23:43:32 25576 8192000000 10309508448 10735644672 1391869312 14.523 100 42 85 3 1423436400 21194800 10372288
23:45:00 29360 8192000000 10329946736 10758098944 1404869136 14.638 100 36 72 0 1439920960 25698096 9353728
23:46:42 17029 8192000000 10336945184 10765971456 1411486592 14.697 100 51 101 4 1451337200 27417824 12432784
23:48:09 3347 8192000000 10361364624 10784436224 1422398032 14.794 100 75 147 2 1473411504 33557184 17456288
23:49:15 6577 8192000000 10361608640 10785300480 1417317712 14.749 100 57 114 0 1469353008 37619232 14416064
23:50:30 7902 8192000000 10359589440 10785878016 1403710176 14.628 99 61 121 2 1462085088 43159168 15215744
23:51:43 4319 8192000000 10377184912 10795376640 1414295920 14.722 100 75 148 1 1482567824 49573824 18698080
23:52:52 9630 8192000000 10361856704 10780360704 1405917888 14.648 100 56 110 1 1465210720 43781664 15511168
23:54:10 2736 8192000000 10380359824 10787991552 1410032960 14.684 100 84 165 1 1482221232 51484816 20703456
23:55:22 2556 8192000000 10396352320 10812424192 1421099760 14.782 100 86 170 1 1503657520 61195920 21361840
23:56:29 3179 8192000000 10391390592 10803843072 1415972496 14.737 100 78 157 0 1500518352 64080128 20465728
23:57:37 9280 8192000000 10386330464 10810032128 1419503376 14.768 100 50 101 0 1496813696 63031056 14279264
23:58:54 7528 8192000000 10385276048 10814541824 1416126560 14.738 100 52 104 1 1493195184 63042560 14026064
00:00:06 17259 8192000000 10354950336 10794209280 1384918896 14.461 98 31 62 0 1446153264 52842016 8392352
00:01:25 18695 8192000000 10336522096 10776883200 1393566448 14.538 100 22 44 0 1445119664 45365488 6187728
00:02:47 44128 8192000000 10321505024 10766651392 1362298992 14.258 99 19 38 1 1427881248 59382832 6199424
00:04:33 34456 8192000000 10277091392 10731728896 1344904864 14.102 99 18 36 1 1384972336 35540176 4527296
00:06:21 96889 8192000000 10264506736 10692251648 1284980352 13.558 95 21 42 0 1297685920 7717184 4988384
00:08:59 47144 8192000000 10276372000 10714296320 1365807920 14.289 100 49 97 0 1385696656 9156704 10732032
00:10:48 44303 8192000000 10263188432 10714542080 1349344752 14.142 98 8 16 1 1364296320 13197856 1753712
00:12:33 67094 8192000000 10284065952 10738515968 1367017040 14.300 99 14 28 1 1383669536 13210592 3441904
00:14:44 37249 8192000000 10274214384 10729537536 1362313616 14.258 99 15 30 0 1373921216 7851200 3756400
00:16:23 61468 8192000000 10272608848 10728824832 1356661216 14.207 98 16 32 0 1369077312 7922112 4493984
00:18:25 97702 8192000000 10281979312 10740596736 1351619648 14.162 98 12 24 1 1363576928 9066768 2890512
00:21:05 48475 8192000000 10276117920 10738266112 1381758528 14.432 100 9 18 0 1395568240 11948864 1860848
00:22:59 68964 8192000000 10278341120 10749845504 1369128272 14.319 99 6 12 1 1386229728 14924016 2177440
00:25:08 24951 8192000000 10284642640 10731175936 1374651312 14.369 100 34 68 0 1390048656 7488272 7909072
00:26:34 57379 8192000000 10295264896 10746105856 1364494304 14.278 98 27 54 1 1382608688 11869296 6245088
00:28:32 60787 8192000000 10301794720 10761048064 1393047728 14.533 100 12 24 1 1415802000 19993168 2761104
00:30:34 60883 8192000000 10308292816 10755334144 1362215088 14.257 97 23 46 1 1379410288 11773680 5421520
00:32:54 64010 8192000000 10284174688 10744377344 1343756496 14.091 97 17 34 0 1359764288 11978464 4029328
00:35:08 46391 8192000000 10291695312 10734628864 1316869376 13.848 95 26 52 1 1333441072 11110064 5461632
00:37:06 95664 8192000000 10269763424 10724868096 1348365392 14.133 98 25 50 0 1366375520 12144528 5865600
00:39:43 41755 8192000000 10283199472 10731286528 1363097856 14.265 99 31 62 1 1377782128 7856832 6827440
00:41:39 63708 8192000000 10274279952 10743181312 1359991216 14.237 98 7 14 0 1368131904 6689856 1450832
00:43:44 28858 8192000000 10292684480 10744393728 1376692016 14.387 99 33 66 2 1395688112 10914960 8081136
00:45:18 44434 8192000000 10310842832 10751680512 1363577904 14.269 98 41 82 2 1392739568 18287504 10874160
00:47:09 51026 8192000000 10318907136 10767540224 1376140304 14.382 98 25 50 0 1402449136 20299952 6008880
00:49:00 77568 8192000000 10298868032 10769702912 1377949568 14.398 99 9 18 1 1401400912 21121296 2330048
00:51:27 27302 8192000000 10290786304 10731704320 1368821040 14.316 99 33 66 1 1391624896 15278176 7525680
00:53:59 69751 8192000000 10302840336 10743042048 1339132928 14.050 97 19 38 1 1354342976 11070384 4139664
00:56:09 91717 8192000000 10295084400 10747924480 1386186976 14.472 100 19 38 0 1408125792 17605104 4333712
00:58:47 44754 8192000000 10291157680 10740461568 1345508896 14.107 97 15 30 2 1363179024 13958112 3712016
01:00:33 60456 8192000000 10269606624 10737963008 1360925504 14.246 99 5 10 0 1376056608 13954720 1176384
01:02:39 50228 8192000000 10285016288 10739957760 1370629616 14.333 99 20 39 0 1382157616 6814640 4713360
01:04:44 74916 8192000000 10271318432 10737954816 1345929840 14.111 97 11 22 0 1357531344 9006816 2594688
01:07:34 40953 8192000000 10286408128 10736357376 1358529536 14.224 98 28 56 1 1374808288 9936064 6342688
01:09:22 51966 8192000000 10292137328 10746417152 1360386656 14.241 98 16 32 0 1373600272 9801328 3412288
01:11:15 65706 8192000000 10303670016 10756837376 1378170576 14.400 99 17 34 2 1389764992 8428144 3166272
01:13:25 75697 8192000000 10315485616 10762534912 1380128560 14.418 99 24 48 1 1397730832 12459072 5143200
01:15:44 108011 8192000000 10308244672 10751717376 1321887984 13.894 95 10 20 1 1340152944 16023968 2240992
01:18:37 112489 8192000000 10293878320 10748628992 1348567712 14.135 97 16 32 1 1363705776 11517504 3620560
01:21:30 101593 8192000000 10289710176 10734424064 1350476992 14.152 97 19 38 0 1366404592 11580064 4347536
01:24:13 2149 8192000000 10295379488 10738245632 1375542368 14.377 99 49 99 1 1395106016 8033504 11530144
01:25:16 62230 8192000000 10268567584 10735902720 1351431520 14.160 98 7 14 1 1365576752 12445216 1700016
01:27:18 64188 8192000000 10246560592 10705301504 1284647472 13.555 93 9 18 0 1295807008 8393088 2766448
01:29:23 57052 8192000000 10260037024 10716372992 1341267712 14.069 98 15 30 0 1355588624 10743008 3577904
01:31:22 52985 8192000000 10265801296 10725744640 1343549232 14.089 98 13 26 0 1359278736 12542656 3186848
01:33:16 39258 8192000000 10275082848 10721894400 1367680832 14.306 100 22 45 0 1382417648 9178528 5558288
01:34:57 65995 8192000000 10279482384 10731769856 1328981824 13.958 96 15 30 0 1351187376 18694256 3511296
01:37:07 88837 8192000000 9186493024 9446342656 332410000 3.899 25 4 8 0 337178688 3918368 850320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 28-06-2022 01:39:35 to 28-06-2022 01:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-06-2022 01:39:50 to 28-06-2022 01:39:52.

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          241107
MON$OLDEST_ACTIVE               241108
MON$OLDEST_SNAPSHOT             241108
MON$NEXT_TRANSACTION            241108
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                       2018496
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:561: 
:562: mscs-test-161.budzdorov.ru    Tue Jun 28 01:38:37 2022
:563:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:564: 
--- end of diff output ---

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

28.06.22 01:39:53. 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-28 01:39:56 No severe PSQL-related problems occured
28.06.22 01:39:57. End of report.