Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.10.2022 13: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 15241132 kB
MemAvailable 30538944 kB
Buffers 0 kB
Cached 16469152 kB
SwapCached 2112 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-10-07 13:37:23
DTS_END                         2022-10-07 13:37:23.2080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58930
TRN_ID                          221206
ATT_ID                          628
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: 9037.73 22880 1084528
customer order: creation 172.84 436 20741
customer order: refuse 15.25 376 1830
order to supplier: creation 45.69 1226 5483
order to supplier: removal 3.29 171307 395
invoice (draft): creation 46.58 2766 5589
invoice (draft): removal 49.90 909 5988
invoice accept: apply 41.93 24004 5031
invoice accept: cancel 45.38 1243 5446
customer reserve: creation 3918.34 227 470201
customer reserve: removal 4404.88 102 528585
realization accept: apply 82.78 267 9933
realization accept: cancel 82.19 160 9863
payment from customer: creation 59.00 169 7080
payment from customer: removal 5.83 101 699
payment to supplier: creation 48.43 203 5812
payment to supplier: removal 3.13 97 376
service: total inventory turnovers 3.88 3963 465
service: total monetary turnovers 7.32 369 878
service: aggregate perf. data 1.08 14684 129
service: refresh index statistics 0.03 234996 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-10-2022 13:38:50 to 07-10-2022 13:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9015
TEST_TIME 2 6718
TEST_TIME 3 5992
TEST_TIME 4 6518
TEST_TIME 5 6639
TEST_TIME 6 6219
TEST_TIME 7 6306
TEST_TIME 8 6211
TEST_TIME 9 6220
TEST_TIME 10 6352
TEST_TIME 11 6241
TEST_TIME 12 6126
TEST_TIME 13 6225
TEST_TIME 14 6687
TEST_TIME 15 6776
TEST_TIME 16 6889
TEST_TIME 17 6963
TEST_TIME 18 6973
TEST_TIME 19 7398
TEST_TIME 20 7755
TEST_TIME 21 8035
TEST_TIME 22 8578
TEST_TIME 23 8460
TEST_TIME 24 8508
TEST_TIME 25 9073
TEST_TIME 26 9153
TEST_TIME 27 9533
TEST_TIME 28 9595
TEST_TIME 29 10125
TEST_TIME 30 10355
TEST_TIME 31 10885
TEST_TIME 32 10705
TEST_TIME 33 10548
TEST_TIME 34 10562
TEST_TIME 35 10854
TEST_TIME 36 11013
TEST_TIME 37 10834
TEST_TIME 38 10654
TEST_TIME 39 10530
TEST_TIME 40 10500
TEST_TIME 41 10408
TEST_TIME 42 10290
TEST_TIME 43 10189
TEST_TIME 44 10256
TEST_TIME 45 10230
TEST_TIME 46 10153
TEST_TIME 47 10130
TEST_TIME 48 10050
TEST_TIME 49 9952
TEST_TIME 50 9829
TEST_TIME 51 9839
TEST_TIME 52 9706
TEST_TIME 53 9645
TEST_TIME 54 9583
TEST_TIME 55 9577
TEST_TIME 56 9586
TEST_TIME 57 9643
TEST_TIME 58 9578
TEST_TIME 59 9548
TEST_TIME 60 9476
TEST_TIME 61 9381
TEST_TIME 62 9311
TEST_TIME 63 9284
TEST_TIME 64 9413
TEST_TIME 65 9374
TEST_TIME 66 9371
TEST_TIME 67 9414
TEST_TIME 68 9405
TEST_TIME 69 9421
TEST_TIME 70 9482
TEST_TIME 71 9458
TEST_TIME 72 9494
TEST_TIME 73 9530
TEST_TIME 74 9537
TEST_TIME 75 9697
TEST_TIME 76 9705
TEST_TIME 77 9641
TEST_TIME 78 9526
TEST_TIME 79 9486
TEST_TIME 80 9450
TEST_TIME 81 9414
TEST_TIME 82 9385
TEST_TIME 83 9333
TEST_TIME 84 9269
TEST_TIME 85 9305
TEST_TIME 86 9213
TEST_TIME 87 9276
TEST_TIME 88 9243
TEST_TIME 89 9246
TEST_TIME 90 9222
TEST_TIME 91 9216
TEST_TIME 92 9209
TEST_TIME 93 9255
TEST_TIME 94 9274
TEST_TIME 95 9263
TEST_TIME 96 9327
TEST_TIME 97 9274
TEST_TIME 98 9336
TEST_TIME 99 9360
TEST_TIME 100 9396
TEST_TIME 101 9401
TEST_TIME 102 9447
TEST_TIME 103 9373
TEST_TIME 104 9330
TEST_TIME 105 9270
TEST_TIME 106 9247
TEST_TIME 107 9228
TEST_TIME 108 9188
TEST_TIME 109 9199
TEST_TIME 110 9157
TEST_TIME 111 9101
TEST_TIME 112 9074
TEST_TIME 113 9038
TEST_TIME 114 9029
TEST_TIME 115 9043
TEST_TIME 116 9032
TEST_TIME 117 9069
TEST_TIME 118 9126
TEST_TIME 119 9103
TEST_TIME 120 9061

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-10-2022 13:38:53 to 07-10-2022 13:38:57.

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 1517473 1517473 0 0.00 0 30946 13 0 0
doc_list_biud 1517460 1517460 0 0.00 0 28994 41 0 0
sp_add_invoice_to_stock 5151 5031 120 2.32 62 208175 24005 0 120
sp_cancel_adding_invoice 5446 5446 0 0.00 5 82161 1243 0 0
sp_cancel_client_order 1830 1830 0 0.00 5 13487 377 0 0
sp_cancel_customer_reserve 528585 528585 0 0.00 1 29062 103 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 2256 102 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 1269 97 0 0
sp_cancel_supplier_invoice 5994 5988 6 0.10 2 22296 909 0 6
sp_cancel_supplier_order 395 395 0 0.00 21 1466817 171308 0 0
sp_cancel_write_off 9863 9863 0 0.00 2 11290 160 0 0
sp_client_order 20741 20741 0 0.00 6 36863 436 0 0
sp_customer_reserve 470201 470201 0 0.00 0 166136 227 0 0
sp_fill_shopping_cart 31797 31797 0 0.00 1 17877 75 0 0
sp_fill_shopping_cart_clo_res 470175 470175 0 0.00 0 18286 17 0 0
sp_get_clo_for_invoice 4991 4991 0 0.00 0 18069 262 0 0
sp_kill_cost_storno 16384 16384 0 0.00 0 8407 71 0 0
sp_kill_qstorno_ret_qs2qd 1073416 1073416 0 0.00 0 28861 45 0 0
sp_kill_qty_storno 567029 567029 0 0.00 0 28986 102 0 0
sp_lock_selected_doc 20705 20705 0 0.00 0 11089 8 0 0
sp_make_cost_storno 27816 27816 0 0.00 0 17580 82 0 0
sp_multiply_rows_for_pdistr 27814 27814 0 0.00 0 8284 36 0 0
sp_multiply_rows_for_qdistr 20740 20740 0 0.00 0 10410 163 0 0
sp_pay_from_customer 7080 7080 0 0.00 6 17905 170 0 0
sp_pay_to_supplier 5812 5812 0 0.00 6 17457 203 0 0
sp_payment_common 12890 12890 0 0.00 2 8212 48 0 0
sp_qd_handle_on_cancel_clo 1830 1830 0 0.00 1 11768 296 0 0
sp_qd_handle_on_invoice_upd_sts 10436 10436 0 0.00 0 17678 484 0 0
sp_qd_handle_on_reserve_upd_sts 9932 9932 0 0.00 0 18722 70 0 0
sp_reserve_write_off 9933 9933 0 0.00 8 18750 267 0 0
sp_supplier_invoice 5589 5589 0 0.00 9 95318 2766 0 0
sp_supplier_order 5483 5483 0 0.00 7 41794 1226 0 0
srv_aggregate_perf_data 129 129 0 0.00 2472 199653 14684 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 691 154697 35222 0 0
srv_find_qd_qs_mism 1035276 1035276 0 0.00 0 30940 10 0 0
srv_make_invnt_saldo 465 465 0 0.00 38 133685 3964 0 0
srv_make_money_saldo 878 878 0 0.00 1 14630 369 0 0
srv_recalc_idx_stat 4 4 0 0.00 50453 369344 234996 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 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 5036 5036 0 0.00 1 8830 799 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 8270 467 0 0
t$perf-norm:sp_cancel_client_order 1830 1830 0 0.00 0 1164 21 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 879 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 807 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 648 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 345 345 0 0.00 0 1858 35 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 75399 6764 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 1075 17 0 0
t$perf-norm:sp_client_order 20742 20742 0 0.00 0 20473 32 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 818 13 0 0
t$perf-norm:sp_pay_from_customer 7080 7080 0 0.00 0 3687 16 0 0
t$perf-norm:sp_pay_to_supplier 5812 5812 0 0.00 0 6696 18 0 0
t$perf-norm:sp_reserve_write_off 9933 9933 0 0.00 0 3299 19 0 0
t$perf-norm:sp_supplier_invoice 5589 5589 0 0.00 0 6635 28 0 0
t$perf-norm:sp_supplier_order 5483 5483 0 0.00 0 6902 28 0 0
t$perf-norm:srv_aggregate_perf_data 129 129 0 0.00 0 736 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 147 6 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 791 12 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 4951 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 181 68 0 0
v_add_invoice_to_stock 10196 9956 240 2.35 0 5996 24 0 240
v_all_customers 38158 38158 0 0.00 0 78 3 0 0
v_all_suppliers 31100 31100 0 0.00 0 93 4 0 0
v_all_wares 41473 41473 0 0.00 0 197 4 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 2373 19 0 0
v_cancel_client_order 3660 3660 0 0.00 0 3828 14 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 2202 12 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 3758 18 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 455 9 0 0
v_cancel_supplier_invoice 702 690 12 1.70 0 1330 24 0 12
v_cancel_supplier_order 790 790 0 0.00 0 2317 17 0 0
v_cancel_write_off 2018 2018 0 0.00 0 4812 26 0 0
v_max_id_clo_ord 5479 5479 0 0.00 0 211 5 0 0
v_max_id_clo_res 974 974 0 0.00 0 1758 17 0 0
v_max_id_ord_sup 5580 5580 0 0.00 0 2750 6 0 0
v_max_non_paid_invoice 1320 1320 0 0.00 0 68 7 0 0
v_max_non_paid_realizn 3565 3565 0 0.00 0 1664 7 0 0
v_min_id_clo_ord 5479 5479 0 0.00 0 1632 7 0 0
v_min_id_clo_res 974 974 0 0.00 0 1631 8 0 0
v_min_id_ord_sup 5580 5580 0 0.00 0 5702 10 0 0
v_min_non_paid_invoice 5811 5811 0 0.00 0 7048 21 0 0
v_min_non_paid_realizn 7079 7079 0 0.00 0 7548 23 0 0
v_our_firm 10340 10340 0 0.00 0 68 4 0 0
v_reserve_write_off 19864 19864 0 0.00 0 3278 11 0 0
x_lock_depdocs_on_canc_invoice 11089 11089 0 0.00 0 26072 125 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 6713 333 0 0
x_make_qstorno_1000_1200 5483 5483 0 0.00 2 41643 1079 0 0
x_make_qstorno_1200_2000 5589 5589 0 0.00 4 89654 2562 0 0
x_make_qstorno_2100_3300 470201 470201 0 0.00 0 166133 199 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-10-2022 13:38:58 to 07-10-2022 13:39:02.

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
11:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 29620 8192000000 9726498128 10022551552 793537824 8.831 100 90 178 0 822852512 7398912 21915776
11:10:00 23303 8192000000 9760528560 10068160512 831447408 9.214 100 84 168 0 863257168 11525744 20284016
11:11:34 18441 8192000000 9801407792 10120540160 867919984 9.579 100 82 165 0 905083568 18370912 18792672
11:13:05 5635 8192000000 9860520224 10194317312 919647392 10.093 100 84 168 0 975538288 38051040 17839856
11:14:16 2317 8192000000 9921210720 10264391680 956267552 10.452 100 86 171 0 1044219328 70840288 17111488
11:15:21 1486 8192000000 9963906976 10316267520 993133776 10.812 100 88 170 0 1080730016 70011200 17585040
11:16:25 3309 8192000000 9984694912 10344034304 1017028288 11.043 100 91 179 1 1101230048 66598192 17603568
11:17:30 896 8192000000 10003748832 10378801152 1050044912 11.361 100 93 174 1 1124699424 56741216 17913296
11:18:33 950 8192000000 10021851344 10396495872 1059114208 11.448 100 93 175 2 1135564720 59520336 16930176
11:19:37 1053 8192000000 10051230208 10430988288 1065677728 11.511 100 99 188 0 1171667056 86815184 19174144
11:20:39 1293 8192000000 10044759376 10425651200 1073615472 11.587 100 94 171 1 1164204304 73234656 17354176
11:21:42 2257 8192000000 10033920832 10420502528 1076450512 11.614 100 84 165 1 1157468672 64762432 16255728
11:22:46 1178 8192000000 10038941392 10424659968 1080284080 11.650 100 96 181 0 1158158160 59798080 18076000
11:23:48 4341 8192000000 10045679376 10444468224 1085072432 11.696 100 66 131 0 1166608880 68705072 12831376
11:24:52 1052 8192000000 10062440080 10450497536 1089878688 11.742 100 93 178 5 1184351968 76525696 17947584
11:25:54 1182 8192000000 10049132224 10435649536 1095624560 11.796 100 91 176 0 1169370448 55418768 18327120
11:26:56 990 8192000000 10047674832 10438955008 1098920976 11.827 100 86 172 1 1166733328 49849680 17962672
11:27:58 962 8192000000 10070434672 10461220864 1102208544 11.859 100 92 169 3 1191702480 71352480 18141456
11:29:00 1132 8192000000 10071416576 10462244864 1102397968 11.860 100 96 183 1 1194971872 73999104 18574800
11:30:03 1192 8192000000 10064716048 10450247680 1091917056 11.761 100 86 170 1 1185723248 76933200 16872992
11:31:05 1186 8192000000 10013663392 10391224320 1060761552 11.464 100 94 179 0 1136463280 56859392 18842336
11:32:07 1489 8192000000 10021037248 10403823616 1067611968 11.529 100 80 148 2 1138877888 57204352 14061568
11:33:09 1220 8192000000 9965552176 10335150080 1031555712 11.183 100 93 170 2 1089722864 40524000 17643152
11:34:11 1654 8192000000 9982176160 10353762304 1020537024 11.077 99 69 123 4 1106626240 72913072 13176144
11:35:13 1047 8192000000 9984125360 10359005184 1029718576 11.166 100 91 173 3 1109271440 59925104 19627760
11:36:15 1330 8192000000 9997223600 10374135808 1033800528 11.205 100 80 155 3 1123072176 73302240 15969408
11:37:17 1860 8192000000 10001644688 10378203136 1052357376 11.383 100 83 159 0 1123466400 55027904 16081120
11:38:25 17819 8192000000 10024950464 10410000384 1059841648 11.455 100 61 120 1 1150521344 78576928 12102768
11:39:47 1895 8192000000 10035155888 10419748864 1082943488 11.676 100 80 141 2 1156576224 57467824 16164912
11:40:52 1398 8192000000 10098389024 10480664576 1097875184 11.817 100 89 172 1 1220838288 104086864 18876240
11:41:56 1707 8192000000 10111087712 10504179712 1116462224 11.994 100 75 141 3 1234389344 102135040 15792080
11:43:12 2916 8192000000 10155053536 10550448128 1134498640 12.164 100 83 158 0 1269526608 117548096 17479872
11:44:20 10709 8192000000 10204215376 10608275456 1147496096 12.286 100 68 132 1 1328839344 166374496 14968752
11:45:40 7061 8192000000 10249148160 10650304512 1160002064 12.403 100 69 134 1 1360113616 185113136 14998416
11:46:51 2878 8192000000 10306011216 10714251264 1168651856 12.484 100 77 143 0 1413810832 229338304 15820672
11:47:57 1190 8192000000 10329423296 10736795648 1181030144 12.600 100 94 177 0 1438190224 237163904 19996176
11:49:02 1089 8192000000 10387590048 10785882112 1190629120 12.689 100 97 187 1 1497691696 285657376 21405200
11:50:07 677 8192000000 10432763760 10839724032 1189242800 12.676 100 98 192 2 1545670608 334189456 22238352
11:51:11 1455 8192000000 10451532560 10880757760 1198151296 12.759 100 98 189 0 1558597424 338761936 21684192
11:52:15 1828 8192000000 10467665088 10886275072 1202654864 12.801 100 95 186 1 1576613552 352207520 21751168
11:53:20 907 8192000000 10531740400 10940850176 1221211520 12.973 100 99 197 1 1643239264 398287760 23739984
11:54:23 1022 8192000000 10531325568 10935025664 1193316208 12.714 99 89 171 1 1639192272 425905920 19970144
11:55:27 855 8192000000 10576515984 10988826624 1195250928 12.732 100 98 187 0 1685415504 466887280 23277296
11:56:32 1960 8192000000 10560966784 11026976768 1169580368 12.493 98 94 184 1 1659637312 466266128 23790816
11:57:36 3892 8192000000 10517756816 10998849536 1180578480 12.596 100 58 114 0 1629698944 434358416 14762048
11:58:44 847 8192000000 10548782992 11000221696 1184128576 12.629 100 85 167 1 1644819648 440784848 19906224
11:59:49 9950 8192000000 10473542576 10906447872 1173051984 12.525 100 45 90 0 1576145824 391486816 11607024
12:01:03 7539 8192000000 10541669104 10969038848 1180429504 12.594 100 64 126 0 1642225840 445870784 15925552
12:02:13 13050 8192000000 10490554816 10948349952 1166933984 12.468 99 40 78 0 1594796016 417225632 10636400
12:03:27 28291 8192000000 10445347456 10902487040 1161446816 12.417 99 24 48 0 1566553712 398477648 6629248
12:05:04 18918 8192000000 10396593696 10857648128 1147098608 12.282 100 20 40 0 1504396320 351935040 5362672
12:06:52 5736 8192000000 10264455936 10780413952 1150730320 12.316 100 45 90 0 1361106784 199912176 10464288
12:08:04 8969 8192000000 10213907760 10735648768 1161610480 12.418 100 53 101 1 1303316704 130483632 11222592
12:09:14 38106 8192000000 10213645072 10648260608 1165208400 12.452 100 58 112 6 1315437024 138852544 11376080
12:10:59 49248 8192000000 10216288672 10699550720 1158105040 12.386 100 30 52 1 1348658048 183730880 6822128
12:13:36 127379 8192000000 10083177264 10522468352 1090076288 11.743 95 25 46 1 1129201088 34057584 5067216
12:17:11 48489 8192000000 10062097200 10515861504 1131541248 12.136 99 26 51 0 1166842848 30765312 4536288
12:19:07 52816 8192000000 10086647536 10517807104 1118674192 12.014 98 37 70 2 1175825520 48901792 8249536
12:21:08 154697 8192000000 10061770160 10548400128 1078040480 11.629 95 10 18 0 1114448928 34667008 1741440
12:24:43 79425 8192000000 10066935456 10519044096 1096021888 11.800 97 29 56 0 1139693760 38018928 5652944
12:27:09 97015 8192000000 10071311376 10552991744 1132103840 12.141 100 28 52 0 1171731488 34359824 5267824
12:29:49 72484 8192000000 10087393088 10527870976 1127038416 12.093 99 36 72 0 1186616720 52217408 7360896
12:32:04 46373 8192000000 10104822256 10548441088 1144669392 12.259 100 26 45 0 1206321376 57167296 4484688
12:34:00 45011 8192000000 10067432592 10563448832 1131157152 12.132 99 27 54 1 1199809056 62456528 6195376
12:35:49 35621 8192000000 10076097712 10541031424 1141011168 12.225 100 11 22 0 1174856800 31499824 2345808
12:37:25 108291 8192000000 10095788448 10554531840 1100029008 11.838 96 13 26 0 1158337008 55697168 2610832
12:40:15 65332 8192000000 10071859872 10602221568 1136836800 12.186 100 12 23 0 1203327616 64400880 2089936
12:42:26 12506 8192000000 10078875664 10510733312 1133873712 12.158 99 52 96 0 1178354784 34464224 10016848
12:43:39 14828 8192000000 10150546160 10594250752 1124609440 12.071 98 35 68 0 1244666992 113060496 6997056
12:44:54 82181 8192000000 10151639024 10603515904 1117023600 11.999 97 18 36 0 1208181936 87310448 3847888
12:47:19 32293 8192000000 10158700496 10638598144 1143006992 12.244 100 27 54 0 1276693056 127816992 5869072
12:48:53 25055 8192000000 10155789840 10614792192 1116411920 11.993 97 42 82 0 1250602544 125170624 9020000
12:50:19 59511 8192000000 10156826128 10595233792 1082897152 11.675 95 16 32 0 1193055184 106561536 3596496
12:52:20 47541 8192000000 10121842832 10616463360 1130904608 12.130 100 12 24 0 1210165008 76965056 2295344
12:54:08 15304 8192000000 10055096832 10471792640 1099537984 11.833 98 33 66 0 1132518448 26418960 6561504
12:55:42 56275 8192000000 10074060976 10506305536 1110870144 11.941 99 47 92 0 1166029376 44848784 10310448
12:57:43 26413 8192000000 10087087824 10540380160 1128233040 12.105 100 36 68 0 1202814960 67969184 6612736
12:59:12 59828 8192000000 10072314784 10515390464 1119746464 12.025 98 17 33 2 1159669040 35748016 4174560
13:02:30 90017 8192000000 10089018352 10575425536 1112253440 11.954 98 8 16 0 1204079008 89891440 1934128
13:05:56 49162 8192000000 10050570496 10505428992 1087339984 11.717 97 19 38 0 1124830384 33780048 3710352
13:07:45 23823 8192000000 10080001600 10513158144 1105096464 11.886 98 56 107 1 1155294816 39093792 11104560
13:09:13 64831 8192000000 10099716272 10578731008 1104375808 11.879 97 32 63 1 1191060352 80618768 6065776
13:11:19 34560 8192000000 10076812896 10505687040 1117122544 12.000 100 33 66 0 1167106496 43058336 6925616
13:13:12 42344 8192000000 10059713184 10513514496 1114735984 11.977 99 20 40 0 1205342480 86436336 4170160
13:14:59 60364 8192000000 10121288960 10604257280 1123409312 12.059 99 43 86 0 1226859920 93841200 9609408
13:17:18 53142 8192000000 10074788144 10516115456 1096639664 11.806 97 18 34 0 1155257760 55379552 3238544
13:19:28 85119 8192000000 10052052592 10522251264 1133422192 12.154 100 11 22 0 1166476480 30323088 2731200
13:22:07 48744 8192000000 10070869152 10513571840 1125620736 12.080 100 30 60 0 1172093920 40313632 6159552
13:23:57 46317 8192000000 10056136128 10498596864 1106868192 11.903 98 18 36 0 1152503696 42198480 3437024
13:25:43 50613 8192000000 10073354320 10549714944 1139173264 12.208 100 17 34 0 1203821024 60679600 3968160
13:27:36 59163 8192000000 10095078784 10540380160 1125441792 12.078 98 19 38 0 1162128224 32538544 4147888
13:29:42 44344 8192000000 10142520192 10579685376 1119948400 12.027 97 28 54 1 1225804144 99737504 6118240
13:31:29 19271 8192000000 10196710544 10636451840 1137768752 12.195 99 31 62 0 1283942800 139160656 7013392
13:32:51 25605 8192000000 10195531360 10654990336 1142486880 12.239 99 32 63 0 1289087808 139614288 6986640
13:34:17 8935 8192000000 10239084128 10663391232 1148627472 12.297 100 56 112 0 1317327888 156726320 11974096
13:35:32 34062 8192000000 10172379680 10618339328 1130948128 12.130 99 23 46 0 1258493456 122095072 5450256
13:37:07 18367 8192000000 9717158800 10090262528 718952560 8.068 62 30 60 0 861247968 135944816 6350592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-10-2022 13:39:04 to 07-10-2022 13:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 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 07-10-2022 13:39:21 to 07-10-2022 13:39:23.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          221495
MON$OLDEST_ACTIVE               221496
MON$OLDEST_SNAPSHOT             221496
MON$NEXT_TRANSACTION            221496
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-30 07:30:08.6030 GMT
MON$PAGES                       2062496
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2855 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2855 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2855 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2855 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:156: 
:157: mscs-test-161.budzdorov.ru    Fri Oct  7 13:37:54 2022
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
--- end of diff output ---

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

07.10.22 13:39:24. There are 3 logs in /var/tmp/logs-oltp40 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-10-07 13:39:27 No severe PSQL-related problems occured
07.10.22 13:39:28. End of report.