Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.06.2022 07:48
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 16256228 kB
MemAvailable 31344692 kB
Buffers 0 kB
Cached 15485640 kB
SwapCached 2436 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.2
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-06-09 07:47:28
DTS_END                         2022-06-09 07:47:28.1750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40868
TRN_ID                          214041
ATT_ID                          604
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: 8465.38 23794 1015846
customer order: creation 166.90 491 20028
customer order: refuse 13.74 421 1649
order to supplier: creation 43.87 1419 5264
order to supplier: removal 3.23 163300 388
invoice (draft): creation 43.25 3380 5190
invoice (draft): removal 47.52 973 5702
invoice accept: apply 39.70 26693 4764
invoice accept: cancel 43.09 1198 5171
customer reserve: creation 3639.93 257 436792
customer reserve: removal 4143.09 101 497171
realization accept: apply 79.79 295 9575
realization accept: cancel 77.07 164 9248
payment from customer: creation 57.12 182 6854
payment from customer: removal 5.29 155 635
payment to supplier: creation 46.53 190 5584
payment to supplier: removal 3.30 142 396
service: total inventory turnovers 3.64 4158 437
service: total monetary turnovers 7.41 380 889
service: aggregate perf. data 0.87 15006 104
service: refresh index statistics 0.04 256967 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-06-2022 07:49:18 to 09-06-2022 07:49:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7950
TEST_TIME 2 7277
TEST_TIME 3 7135
TEST_TIME 4 6293
TEST_TIME 5 6388
TEST_TIME 6 6265
TEST_TIME 7 6368
TEST_TIME 8 6410
TEST_TIME 9 6498
TEST_TIME 10 6904
TEST_TIME 11 6920
TEST_TIME 12 6913
TEST_TIME 13 6854
TEST_TIME 14 6867
TEST_TIME 15 6998
TEST_TIME 16 7179
TEST_TIME 17 7194
TEST_TIME 18 7280
TEST_TIME 19 7509
TEST_TIME 20 7721
TEST_TIME 21 8213
TEST_TIME 22 8531
TEST_TIME 23 8692
TEST_TIME 24 8847
TEST_TIME 25 9116
TEST_TIME 26 8973
TEST_TIME 27 9342
TEST_TIME 28 9663
TEST_TIME 29 9998
TEST_TIME 30 10181
TEST_TIME 31 10456
TEST_TIME 32 10444
TEST_TIME 33 10734
TEST_TIME 34 10609
TEST_TIME 35 10387
TEST_TIME 36 10270
TEST_TIME 37 10373
TEST_TIME 38 10305
TEST_TIME 39 10285
TEST_TIME 40 10176
TEST_TIME 41 10080
TEST_TIME 42 9973
TEST_TIME 43 9832
TEST_TIME 44 9697
TEST_TIME 45 9632
TEST_TIME 46 9545
TEST_TIME 47 9479
TEST_TIME 48 9427
TEST_TIME 49 9386
TEST_TIME 50 9236
TEST_TIME 51 9230
TEST_TIME 52 9194
TEST_TIME 53 9112
TEST_TIME 54 9047
TEST_TIME 55 8960
TEST_TIME 56 8829
TEST_TIME 57 8805
TEST_TIME 58 8899
TEST_TIME 59 8947
TEST_TIME 60 9018
TEST_TIME 61 8948
TEST_TIME 62 9005
TEST_TIME 63 8967
TEST_TIME 64 9043
TEST_TIME 65 9047
TEST_TIME 66 8975
TEST_TIME 67 9040
TEST_TIME 68 9316
TEST_TIME 69 9305
TEST_TIME 70 9242
TEST_TIME 71 9233
TEST_TIME 72 9203
TEST_TIME 73 9122
TEST_TIME 74 9035
TEST_TIME 75 8996
TEST_TIME 76 8943
TEST_TIME 77 8876
TEST_TIME 78 8856
TEST_TIME 79 8844
TEST_TIME 80 8837
TEST_TIME 81 8850
TEST_TIME 82 8864
TEST_TIME 83 8890
TEST_TIME 84 8843
TEST_TIME 85 8826
TEST_TIME 86 8818
TEST_TIME 87 8898
TEST_TIME 88 8942
TEST_TIME 89 8912
TEST_TIME 90 8931
TEST_TIME 91 8928
TEST_TIME 92 8947
TEST_TIME 93 9067
TEST_TIME 94 9019
TEST_TIME 95 8957
TEST_TIME 96 8896
TEST_TIME 97 8886
TEST_TIME 98 8832
TEST_TIME 99 8807
TEST_TIME 100 8793
TEST_TIME 101 8748
TEST_TIME 102 8746
TEST_TIME 103 8732
TEST_TIME 104 8700
TEST_TIME 105 8692
TEST_TIME 106 8676
TEST_TIME 107 8707
TEST_TIME 108 8693
TEST_TIME 109 8733
TEST_TIME 110 8749
TEST_TIME 111 8760
TEST_TIME 112 8779
TEST_TIME 113 8729
TEST_TIME 114 8714
TEST_TIME 115 8687
TEST_TIME 116 8637
TEST_TIME 117 8597
TEST_TIME 118 8563
TEST_TIME 119 8538
TEST_TIME 120 8489

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-06-2022 07:49:22 to 09-06-2022 07:49:25.

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 1419190 1419190 0 0.00 0 22264 13 0 0
doc_list_biud 1419185 1419185 0 0.00 0 46393 42 0 0
sp_add_invoice_to_stock 4921 4764 157 3.19 48 247880 26694 0 157
sp_cancel_adding_invoice 5171 5171 0 0.00 8 63540 1199 0 0
sp_cancel_client_order 1649 1649 0 0.00 5 14723 422 0 0
sp_cancel_customer_reserve 497171 497171 0 0.00 1 29560 101 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 3 10135 156 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 4 6104 142 0 0
sp_cancel_supplier_invoice 5713 5702 11 0.19 2 46406 974 0 11
sp_cancel_supplier_order 388 388 0 0.00 14 1264954 163300 0 0
sp_cancel_write_off 9248 9248 0 0.00 2 17283 164 0 0
sp_client_order 20028 20028 0 0.00 6 20214 492 0 0
sp_customer_reserve 436792 436792 0 0.00 1 57049 257 0 0
sp_fill_shopping_cart 30469 30469 0 0.00 2 23604 86 0 0
sp_fill_shopping_cart_clo_res 436756 436756 0 0.00 0 17118 23 0 0
sp_get_clo_for_invoice 4717 4717 0 0.00 0 21069 320 0 0
sp_kill_cost_storno 15450 15450 0 0.00 0 17279 72 0 0
sp_kill_qstorno_ret_qs2qd 1009680 1009680 0 0.00 0 46391 45 0 0
sp_kill_qty_storno 533619 533619 0 0.00 0 46392 102 0 0
sp_lock_selected_doc 19860 19860 0 0.00 0 11362 9 0 0
sp_make_cost_storno 26730 26730 0 0.00 0 22257 82 0 0
sp_multiply_rows_for_pdistr 26729 26729 0 0.00 0 8999 38 0 0
sp_multiply_rows_for_qdistr 20028 20028 0 0.00 0 11823 189 0 0
sp_pay_from_customer 6854 6854 0 0.00 6 15851 183 0 0
sp_pay_to_supplier 5584 5584 0 0.00 7 15450 191 0 0
sp_payment_common 12438 12438 0 0.00 2 8504 45 0 0
sp_qd_handle_on_cancel_clo 1649 1649 0 0.00 0 14673 349 0 0
sp_qd_handle_on_invoice_upd_sts 9887 9887 0 0.00 0 43305 531 0 0
sp_qd_handle_on_reserve_upd_sts 9574 9574 0 0.00 0 16615 67 0 0
sp_reserve_write_off 9575 9575 0 0.00 8 26973 295 0 0
sp_supplier_invoice 5190 5190 0 0.00 11 110410 3380 0 0
sp_supplier_order 5264 5264 0 0.00 8 95885 1419 0 0
srv_aggregate_perf_data 104 104 0 0.00 2154 169987 15006 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 972 115966 39314 0 0
srv_find_qd_qs_mism 969685 969685 0 0.00 0 17528 10 0 0
srv_make_invnt_saldo 437 437 0 0.00 39 67333 4158 0 0
srv_make_money_saldo 889 889 0 0.00 1 18133 380 0 0
srv_recalc_idx_stat 5 5 0 0.00 85835 440561 256967 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4768 4768 0 0.00 1 14381 941 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 1 5709 405 0 0
t$perf-norm:sp_cancel_client_order 1649 1649 0 0.00 0 4273 28 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 3684 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 883 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 1563 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1698 31 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 102853 6545 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 6395 26 0 0
t$perf-norm:sp_client_order 20029 20029 0 0.00 0 5594 37 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 9138 32 0 0
t$perf-norm:sp_pay_from_customer 6854 6854 0 0.00 0 11287 22 0 0
t$perf-norm:sp_pay_to_supplier 5584 5584 0 0.00 0 8003 21 0 0
t$perf-norm:sp_reserve_write_off 9575 9575 0 0.00 0 9502 25 0 0
t$perf-norm:sp_supplier_invoice 5190 5190 0 0.00 0 3560 32 0 0
t$perf-norm:sp_supplier_order 5264 5264 0 0.00 0 2481 32 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 507 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 439 11 0 0
t$perf-norm:srv_make_invnt_saldo 437 437 0 0.00 0 724 12 0 0
t$perf-norm:srv_make_money_saldo 889 889 0 0.00 0 7296 17 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 176 70 0 0
v_add_invoice_to_stock 9738 9424 314 3.22 0 7442 26 0 314
v_all_customers 36838 36838 0 0.00 0 147 4 0 0
v_all_suppliers 29152 29152 0 0.00 0 74 4 0 0
v_all_wares 40046 40046 0 0.00 0 107 4 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 3948 18 0 0
v_cancel_client_order 3298 3298 0 0.00 0 1470 9 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 1288 13 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 810 11 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 2177 17 0 0
v_cancel_supplier_invoice 820 798 22 2.68 0 2468 25 0 22
v_cancel_supplier_order 776 776 0 0.00 0 222 8 0 0
v_cancel_write_off 2000 2000 0 0.00 0 4165 24 0 0
v_max_id_clo_ord 5262 5262 0 0.00 0 7978 7 0 0
v_max_id_clo_res 963 963 0 0.00 0 4494 15 0 0
v_max_id_ord_sup 5184 5184 0 0.00 0 5383 8 0 0
v_max_non_paid_invoice 1454 1454 0 0.00 0 993 7 0 0
v_max_non_paid_realizn 3318 3318 0 0.00 0 1011 7 0 0
v_min_id_clo_ord 5262 5262 0 0.00 0 8110 9 0 0
v_min_id_clo_res 963 963 0 0.00 0 1140 8 0 0
v_min_id_ord_sup 5184 5184 0 0.00 0 7227 13 0 0
v_min_non_paid_invoice 5584 5584 0 0.00 0 6381 15 0 0
v_min_non_paid_realizn 6854 6854 0 0.00 0 8491 21 0 0
v_our_firm 10280 10280 0 0.00 0 105 4 0 0
v_reserve_write_off 19146 19146 0 0.00 0 4670 11 0 0
x_lock_depdocs_on_canc_invoice 10474 10474 0 0.00 0 14693 122 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 18692 379 0 0
x_make_qstorno_1000_1200 5264 5264 0 0.00 3 95852 1244 0 0
x_make_qstorno_1200_2000 5190 5190 0 0.00 5 94898 3140 0 0
x_make_qstorno_2100_3300 436790 436790 0 0.00 0 57008 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-06-2022 07:49:26 to 09-06-2022 07:49:30.

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
05:17:31 2 8192000000 8796818912 8807305216 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:32 21704 8192000000 9713987488 10013822976 786926976 8.764 100 80 160 1 813440368 6772784 19740608
05:20:02 21157 8192000000 9771955648 10082791424 842867552 9.329 100 89 178 0 876209568 11890464 21451552
05:21:28 18203 8192000000 9797268672 10118504448 867796912 9.578 100 85 170 1 906993488 19651216 19545360
05:22:51 11605 8192000000 9864612240 10198118400 919346192 10.090 100 86 171 1 970638016 32296832 18994992
05:24:06 6413 8192000000 9922871952 10269298688 958920992 10.478 100 85 169 0 1041614656 64711280 17982384
05:25:15 2343 8192000000 9965485280 10318729216 989537200 10.777 100 88 172 0 1094098896 86716368 17845328
05:26:21 2281 8192000000 9991449680 10352328704 1020046560 11.072 100 91 175 0 1112171632 74620640 17504432
05:27:25 977 8192000000 10004681536 10375770112 1043046176 11.294 100 93 178 1 1127809552 66920208 17843168
05:28:27 1270 8192000000 10055836496 10434883584 1056403680 11.422 100 97 189 1 1176814496 102320304 18090512
05:29:30 1118 8192000000 10041760368 10419957760 1064947008 11.504 100 95 183 0 1156918448 72453168 19518272
05:30:33 907 8192000000 10033776400 10415226880 1071681520 11.568 100 90 171 1 1153283184 63787680 17813984
05:31:35 1099 8192000000 10055414848 10439950336 1073320320 11.584 100 96 189 0 1180020992 87558288 19142384
05:32:38 1244 8192000000 10042659952 10424778752 1078574592 11.634 100 98 172 5 1163762048 66346512 18840944
05:33:40 1403 8192000000 10048560896 10432761856 1075871552 11.608 100 95 181 1 1168089136 73680592 18536992
05:34:43 884 8192000000 10047048672 10433658880 1082666256 11.673 100 91 171 4 1169578736 69111264 17801216
05:35:45 1078 8192000000 10044096448 10434236416 1093378656 11.775 100 93 185 2 1166614224 55692208 17543360
05:36:48 1210 8192000000 10060469312 10447708160 1096652144 11.806 100 98 194 0 1180856672 64602736 19601792
05:37:50 1972 8192000000 10047217104 10441093120 1091976192 11.761 100 77 148 0 1172038832 65003104 15059536
05:38:53 1376 8192000000 10040661984 10430242816 1087942160 11.723 100 73 141 0 1159317376 56152992 15222224
05:39:56 1105 8192000000 10033004064 10421194752 1073830912 11.589 100 82 149 5 1157388608 69564432 13993264
05:40:58 1095 8192000000 10021261520 10401656832 1070628320 11.558 100 93 185 0 1139122816 49241824 19252672
05:42:00 1113 8192000000 10039868640 10422480896 1056900304 11.427 100 89 171 1 1161924768 86619152 18405312
05:43:02 1734 8192000000 10026371696 10401001472 1058467056 11.442 100 96 182 0 1149376576 73023136 17886384
05:44:05 1331 8192000000 10005208416 10379280384 1042778048 11.291 99 95 185 0 1126287712 64791280 18718384
05:45:07 1594 8192000000 9982124240 10355589120 1037233536 11.238 100 77 145 3 1101733952 51383904 13116512
05:46:10 10834 8192000000 9965614336 10350948352 1016142416 11.035 98 49 94 1 1079456672 53863456 9450800
05:47:23 805 8192000000 9970641008 10342068224 1028894656 11.158 98 84 165 0 1092424784 46633632 16896496
05:48:29 1447 8192000000 10022125568 10398093312 1061403776 11.470 100 92 178 2 1145082432 64667968 19010688
05:49:35 1169 8192000000 10065932416 10446442496 1087563840 11.719 100 96 185 1 1185055120 77812448 19678832
05:50:39 3560 8192000000 10078278544 10462732288 1102353312 11.860 100 73 144 0 1191239824 72401744 16484768
05:51:46 3225 8192000000 10097318672 10494128128 1115685344 11.986 100 60 118 0 1218539232 90106928 12746960
05:52:52 1763 8192000000 10141396416 10530246656 1121785808 12.044 100 86 164 0 1256968960 116978160 18204992
05:53:56 1865 8192000000 10182067456 10585690112 1136636640 12.184 100 85 167 2 1303635056 148196048 18802368
05:55:03 8469 8192000000 10221928800 10627760128 1149879520 12.308 100 72 141 0 1341806944 176679536 15247888
05:56:14 3255 8192000000 10256960480 10660544512 1160838880 12.411 100 85 169 1 1372070352 192341600 18889872
05:57:24 979 8192000000 10297059472 10698186752 1172517536 12.520 100 97 187 0 1408114896 215099728 20497632
05:58:30 1157 8192000000 10324026112 10724851712 1177694096 12.569 100 94 185 1 1430420544 233052784 19673664
05:59:34 1027 8192000000 10358811168 10758832128 1180826192 12.598 100 91 181 0 1467348544 265214944 21307408
06:00:39 1455 8192000000 10378567376 10789826560 1185284400 12.639 100 92 179 0 1487377408 281393856 20699152
06:01:44 1072 8192000000 10411128832 10829754368 1183996896 12.627 100 97 179 0 1525054656 320479840 20577920
06:02:50 1343 8192000000 10418567728 10837377024 1179202736 12.583 100 94 183 0 1526877584 326600704 21074144
06:03:55 4421 8192000000 10446988400 10856386560 1188445520 12.669 100 89 175 2 1557970528 349139808 20385200
06:05:01 999 8192000000 10509604048 10932105216 1191136976 12.694 100 97 189 0 1615692080 399701408 24853696
06:06:05 1108 8192000000 10503725984 10934591488 1194787232 12.728 100 95 185 0 1611237168 393492144 22957792
06:07:09 963 8192000000 10431746848 10903834624 1185282176 12.639 100 94 186 2 1534812112 327749584 21780352
06:08:13 1097 8192000000 10468704288 10956189696 1189290416 12.677 100 98 191 1 1573159248 361150048 22718784
06:09:16 8730 8192000000 10416003680 10881912832 1175751536 12.551 100 58 112 0 1522991680 333156688 14083456
06:10:29 2748 8192000000 10394408512 10824810496 1173790368 12.532 100 72 139 0 1495531440 305493648 16247424
06:11:35 6384 8192000000 10430464304 10871640064 1181757584 12.607 100 56 110 1 1540316064 344240656 14317824
06:12:44 11570 8192000000 10413128848 10885079040 1184726832 12.634 100 31 62 0 1520048768 327209216 8112720
06:14:10 27917 8192000000 10370793552 10852831232 1132128864 12.141 97 30 60 1 1458084832 317651296 8304672
06:15:44 20433 8192000000 10321847408 10763628544 1127996832 12.102 97 37 68 1 1401003952 264136720 8870400
06:17:06 34558 8192000000 10244037072 10686132224 1127281440 12.096 98 24 48 2 1353122688 220297712 5543536
06:18:59 44289 8192000000 10149250640 10624409600 1123978944 12.065 98 31 61 1 1235288672 104574288 6735440
06:20:48 114196 8192000000 10158466432 10594013184 1144235920 12.255 100 65 122 2 1276158464 119252400 12670144
06:23:45 61020 8192000000 10128406656 10643804160 1137016304 12.187 99 30 60 2 1264586192 121234224 6335664
06:25:49 85814 8192000000 10075837840 10576695296 1124417824 12.069 98 13 27 1 1159244624 32590976 2235824
06:28:16 59169 8192000000 10058965040 10533789696 1119372512 12.021 98 16 31 3 1171759760 48365952 4021296
06:30:21 28937 8192000000 10072876048 10505134080 1119916880 12.026 98 35 70 1 1158343680 30758640 7668160
06:31:50 86216 8192000000 10090290864 10558795776 1121467072 12.041 98 21 42 0 1206215584 80129008 4619504
06:34:43 99906 8192000000 10051671728 10512482304 1098931424 11.828 97 36 67 2 1145493440 37985600 8576416
06:37:26 6898 8192000000 10058127776 10482110464 1108003184 11.914 98 72 132 5 1147511296 25881680 13626432
06:38:33 34521 8192000000 10093627440 10550120448 1145149296 12.264 100 34 68 1 1227459024 75709696 6600032
06:40:10 78470 8192000000 10102839520 10549354496 1104865200 11.884 97 18 36 1 1168303072 59439456 3998416
06:42:28 93362 8192000000 10140587392 10592243712 1132596544 12.146 100 23 46 1 1230907536 93167664 5143328
06:45:03 38276 8192000000 10092300016 10568912896 1131620160 12.137 100 12 23 0 1220051296 85714336 2716800
06:46:42 59623 8192000000 10109655088 10577092608 1127399168 12.097 99 15 29 1 1182911712 52077264 3435280
06:48:56 55473 8192000000 10143400912 10623885312 1149363872 12.304 100 30 61 1 1253973424 98223616 6385936
06:50:52 41318 8192000000 10149474416 10594152448 1127641440 12.099 98 26 52 2 1223624768 90017104 5966224
06:52:34 52979 8192000000 10176178880 10621808640 1135200064 12.170 98 22 44 0 1228598512 88580384 4818064
06:54:40 86302 8192000000 10118598000 10688847872 1144059024 12.254 99 20 40 0 1314269200 165195552 5014624
06:57:07 12789 8192000000 10072534256 10510196736 1119895392 12.026 98 43 77 0 1153950864 26202416 7853056
06:58:21 24979 8192000000 10131080624 10580094976 1141287744 12.228 100 61 116 2 1224837744 71613152 11936848
06:59:48 89841 8192000000 10071718368 10510020608 1120491488 12.032 98 28 55 1 1155403072 28827024 6084560
07:02:20 52834 8192000000 10095850128 10545385472 1141214192 12.227 99 29 54 0 1208188304 62018736 4955376
07:05:09 47559 8192000000 10146939168 10630557696 1147449824 12.286 100 12 24 1 1245025856 94642000 2934032
07:06:57 55797 8192000000 10106612960 10596237312 1137454688 12.192 99 9 18 0 1217475680 78018480 2002512
07:09:01 47959 8192000000 10084001952 10545565696 1140968112 12.225 100 19 36 2 1198344608 53794784 3581712
07:10:54 35760 8192000000 10072660288 10505248768 1126270368 12.086 98 35 67 1 1145777744 12808816 6698560
07:12:31 115966 8192000000 10173113872 10695999488 1156375152 12.369 100 15 30 0 1262131856 102166912 3589792
07:15:36 32321 8192000000 10081450864 10515992576 1126462608 12.088 98 24 49 1 1158413984 27120960 4830416
07:17:12 60237 8192000000 10127713584 10586181632 1119841600 12.025 98 27 54 1 1190477312 65198288 5437424
07:19:13 97092 8192000000 10087198896 10657226752 1114298096 11.973 98 14 28 0 1217507936 100358704 2851136
07:22:01 106695 8192000000 10055202784 10492743680 1128113008 12.104 99 31 59 2 1156199120 22403520 5682592
07:24:52 67276 8192000000 10072140576 10533138432 1123542592 12.060 99 6 12 0 1161136672 36545440 1048640
07:27:00 76970 8192000000 10055352864 10502066176 1118044144 12.009 98 17 32 1 1158172752 36822160 3306448
07:29:25 15158 8192000000 10047911872 10493284352 1111406800 11.946 99 42 83 0 1143765440 23286432 9072208
07:30:44 46088 8192000000 10104268448 10548154368 1131851488 12.139 99 30 59 2 1184451488 46502640 6097360
07:32:39 30597 8192000000 10130624048 10568671232 1120756544 12.034 99 29 58 0 1219180304 91976784 6446976
07:34:11 61866 8192000000 10106305712 10536841216 1094849360 11.789 98 25 50 0 1201248256 100985696 5413200
07:36:16 59289 8192000000 10098707520 10566021120 1111083088 11.943 99 25 51 0 1192777776 76076160 5618528
07:38:17 8055 8192000000 10111407984 10558013440 1123639056 12.061 100 45 89 0 1198583072 66171712 8772304
07:39:31 107984 8192000000 10075084064 10506108928 1125293296 12.077 100 40 78 0 1190166640 56848480 8024864
07:42:22 84754 8192000000 10047470400 10510610432 1114522448 11.975 98 15 30 0 1166164192 48470208 3171536
07:45:01 20361 8192000000 10078931056 10554015744 1125618368 12.080 99 11 22 0 1176026560 48021872 2386320
07:46:22 74731 8192000000 9188703952 9515134976 325201440 3.818 29 4 8 0 340231104 14208016 821648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-06-2022 07:49:32 to 09-06-2022 07:49:48.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-06-2022 07:49:48 to 09-06-2022 07:49:50.

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          214340
MON$OLDEST_ACTIVE               214341
MON$OLDEST_SNAPSHOT             214341
MON$NEXT_TRANSACTION            214341
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                       2049504
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2775 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2775 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2775 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2775 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Jun  9 07:48:07 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

09.06.22 07:49:51. 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-06-09 07:49:54 No severe PSQL-related problems occured
09.06.22 07:49:55. End of report.