Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.01.2022 04: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 16698964 kB
MemAvailable 31342476 kB
Buffers 20 kB
Cached 14972824 kB
SwapCached 1204 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.1
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-01-02 04:37:10
DTS_END                         2022-01-02 04:37:10.4830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42578
TRN_ID                          238544
ATT_ID                          705
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: 8306.43 22492 996772
customer order: creation 170.68 402 20481
customer order: refuse 14.69 339 1763
order to supplier: creation 45.38 1361 5446
order to supplier: removal 3.12 90785 374
invoice (draft): creation 45.60 3651 5472
invoice (draft): removal 44.38 832 5326
invoice accept: apply 41.68 19412 5001
invoice accept: cancel 39.91 885 4789
customer reserve: creation 3731.63 199 447796
customer reserve: removal 3886.23 58 466347
realization accept: apply 82.89 167 9947
realization accept: cancel 73.02 119 8762
payment from customer: creation 59.43 113 7131
payment from customer: removal 5.89 82 707
payment to supplier: creation 48.17 137 5780
payment to supplier: removal 3.04 57 365
service: total inventory turnovers 3.79 3147 455
service: total monetary turnovers 6.04 458 725
service: aggregate perf. data 0.84 17273 101
service: refresh index statistics 0.03 310358 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4216
TEST_TIME 2 4133
TEST_TIME 3 5336
TEST_TIME 4 4999
TEST_TIME 5 5380
TEST_TIME 6 5095
TEST_TIME 7 5038
TEST_TIME 8 5129
TEST_TIME 9 5285
TEST_TIME 10 5466
TEST_TIME 11 6013
TEST_TIME 12 6188
TEST_TIME 13 6346
TEST_TIME 14 6545
TEST_TIME 15 7547
TEST_TIME 16 7779
TEST_TIME 17 8205
TEST_TIME 18 8469
TEST_TIME 19 8659
TEST_TIME 20 8927
TEST_TIME 21 9324
TEST_TIME 22 9348
TEST_TIME 23 9497
TEST_TIME 24 9775
TEST_TIME 25 10444
TEST_TIME 26 10243
TEST_TIME 27 10149
TEST_TIME 28 10029
TEST_TIME 29 9882
TEST_TIME 30 9705
TEST_TIME 31 9600
TEST_TIME 32 9419
TEST_TIME 33 9273
TEST_TIME 34 9123
TEST_TIME 35 8997
TEST_TIME 36 9100
TEST_TIME 37 8931
TEST_TIME 38 8843
TEST_TIME 39 8802
TEST_TIME 40 8795
TEST_TIME 41 8684
TEST_TIME 42 8647
TEST_TIME 43 8674
TEST_TIME 44 8720
TEST_TIME 45 8814
TEST_TIME 46 8750
TEST_TIME 47 8767
TEST_TIME 48 8900
TEST_TIME 49 8863
TEST_TIME 50 9047
TEST_TIME 51 9070
TEST_TIME 52 9001
TEST_TIME 53 9017
TEST_TIME 54 8929
TEST_TIME 55 8879
TEST_TIME 56 8770
TEST_TIME 57 8735
TEST_TIME 58 8721
TEST_TIME 59 8719
TEST_TIME 60 8655
TEST_TIME 61 8617
TEST_TIME 62 8656
TEST_TIME 63 8636
TEST_TIME 64 8635
TEST_TIME 65 8705
TEST_TIME 66 8706
TEST_TIME 67 8715
TEST_TIME 68 8788
TEST_TIME 69 8781
TEST_TIME 70 8819
TEST_TIME 71 8796
TEST_TIME 72 8794
TEST_TIME 73 8849
TEST_TIME 74 8860
TEST_TIME 75 8846
TEST_TIME 76 8785
TEST_TIME 77 8717
TEST_TIME 78 8678
TEST_TIME 79 8658
TEST_TIME 80 8629
TEST_TIME 81 8579
TEST_TIME 82 8494
TEST_TIME 83 8519
TEST_TIME 84 8486
TEST_TIME 85 8527
TEST_TIME 86 8509
TEST_TIME 87 8526
TEST_TIME 88 8543
TEST_TIME 89 8545
TEST_TIME 90 8545
TEST_TIME 91 8606
TEST_TIME 92 8582
TEST_TIME 93 8573
TEST_TIME 94 8710
TEST_TIME 95 8718
TEST_TIME 96 8648
TEST_TIME 97 8566
TEST_TIME 98 8569
TEST_TIME 99 8537
TEST_TIME 100 8482
TEST_TIME 101 8414
TEST_TIME 102 8386
TEST_TIME 103 8390
TEST_TIME 104 8351
TEST_TIME 105 8425
TEST_TIME 106 8411
TEST_TIME 107 8434
TEST_TIME 108 8397
TEST_TIME 109 8421
TEST_TIME 110 8524
TEST_TIME 111 8482
TEST_TIME 112 8522
TEST_TIME 113 8538
TEST_TIME 114 8524
TEST_TIME 115 8529
TEST_TIME 116 8481
TEST_TIME 117 8437
TEST_TIME 118 8421
TEST_TIME 119 8373
TEST_TIME 120 8309

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-01-2022 04:39:08 to 02-01-2022 04:39:11.

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 1410649 1410649 0 0.00 0 27881 7 0 0
doc_list_biud 1410649 1410649 0 0.00 0 78160 25 0 0
sp_add_invoice_to_stock 5147 5001 146 2.83 34 200826 19413 0 146
sp_cancel_adding_invoice 4789 4789 0 0.00 3 102865 885 0 0
sp_cancel_client_order 1763 1763 0 0.00 4 19225 339 0 0
sp_cancel_customer_reserve 466347 466347 0 0.00 1 60129 59 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 9442 83 0 0
sp_cancel_pay_to_supplier 365 365 0 0.00 2 1812 58 0 0
sp_cancel_supplier_invoice 5336 5326 10 0.18 1 78210 832 0 10
sp_cancel_supplier_order 374 374 0 0.00 10 853310 90786 0 0
sp_cancel_write_off 8762 8762 0 0.00 1 21292 120 0 0
sp_client_order 20481 20481 0 0.00 4 60233 402 0 0
sp_customer_reserve 447796 447796 0 0.00 0 65437 199 0 0
sp_fill_shopping_cart 31395 31395 0 0.00 1 25465 51 0 0
sp_fill_shopping_cart_clo_res 447792 447792 0 0.00 0 17169 16 0 0
sp_get_clo_for_invoice 4996 4996 0 0.00 0 19415 380 0 0
sp_kill_cost_storno 14623 14623 0 0.00 0 9122 50 0 0
sp_kill_qstorno_ret_qs2qd 947156 947156 0 0.00 0 78158 32 0 0
sp_kill_qty_storno 502304 502304 0 0.00 0 78160 70 0 0
sp_lock_selected_doc 20569 20569 0 0.00 0 9438 6 0 0
sp_make_cost_storno 27854 27854 0 0.00 0 15694 49 0 0
sp_multiply_rows_for_pdistr 27854 27854 0 0.00 0 18183 30 0 0
sp_multiply_rows_for_qdistr 20481 20481 0 0.00 0 20268 191 0 0
sp_pay_from_customer 7131 7131 0 0.00 4 24063 113 0 0
sp_pay_to_supplier 5780 5780 0 0.00 4 37584 137 0 0
sp_payment_common 12911 12911 0 0.00 1 7199 21 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 19035 291 0 0
sp_qd_handle_on_invoice_upd_sts 9785 9785 0 0.00 0 27221 328 0 0
sp_qd_handle_on_reserve_upd_sts 9947 9947 0 0.00 0 14205 55 0 0
sp_reserve_write_off 9947 9947 0 0.00 6 34153 167 0 0
sp_supplier_invoice 5472 5472 0 0.00 7 93142 3651 0 0
sp_supplier_order 5446 5446 0 0.00 6 30491 1361 0 0
srv_aggregate_perf_data 101 101 0 0.00 2801 245641 17274 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2031 152838 50969 0 0
srv_find_qd_qs_mism 949489 949489 0 0.00 0 9267 7 0 0
srv_make_invnt_saldo 455 455 0 0.00 52 96411 3148 0 0
srv_make_money_saldo 725 725 0 0.00 1 14122 459 0 0
srv_recalc_idx_stat 4 4 0 0.00 287821 334600 310359 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5001 5001 0 0.00 0 14267 163 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 10682 167 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 9336 18 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 1527 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 294 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 365 365 0 0.00 0 36 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 527 13 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 18903 1210 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 3880 10 0 0
t$perf-norm:sp_client_order 20481 20481 0 0.00 0 9988 18 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 1400 4 0 0
t$perf-norm:sp_pay_from_customer 7131 7131 0 0.00 0 8694 9 0 0
t$perf-norm:sp_pay_to_supplier 5780 5780 0 0.00 0 5043 8 0 0
t$perf-norm:sp_reserve_write_off 9947 9947 0 0.00 0 10051 14 0 0
t$perf-norm:sp_supplier_invoice 5472 5472 0 0.00 0 8558 26 0 0
t$perf-norm:sp_supplier_order 5446 5446 0 0.00 0 6525 15 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 11 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 191 6 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 3951 13 0 0
t$perf-norm:srv_make_money_saldo 725 725 0 0.00 0 3134 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10282 9990 292 2.83 0 4573 15 0 292
v_all_customers 37334 37334 0 0.00 0 93 1 0 0
v_all_suppliers 29686 29686 0 0.00 0 21 1 0 0
v_all_wares 40962 40962 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 2342 12 0 0
v_cancel_client_order 3526 3526 0 0.00 0 18969 11 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 877 5 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 1712 6 0 0
v_cancel_payment_to_supplier 730 730 0 0.00 0 933 5 0 0
v_cancel_supplier_invoice 746 726 20 2.68 0 2746 21 0 20
v_cancel_supplier_order 748 748 0 0.00 0 2077 9 0 0
v_cancel_write_off 1978 1978 0 0.00 0 1950 15 0 0
v_max_id_clo_ord 5446 5446 0 0.00 0 4798 2 0 0
v_max_id_clo_res 994 994 0 0.00 0 4398 23 0 0
v_max_id_ord_sup 5468 5468 0 0.00 0 4643 6 0 0
v_max_non_paid_invoice 1851 1851 0 0.00 0 546 2 0 0
v_max_non_paid_realizn 3880 3880 0 0.00 0 1175 3 0 0
v_min_id_clo_ord 5446 5446 0 0.00 0 5055 9 0 0
v_min_id_clo_res 994 994 0 0.00 0 3188 13 0 0
v_min_id_ord_sup 5468 5468 0 0.00 0 8135 13 0 0
v_min_non_paid_invoice 5780 5780 0 0.00 0 7195 15 0 0
v_min_non_paid_realizn 7131 7131 0 0.00 0 6238 14 0 0
v_our_firm 10130 10130 0 0.00 0 14 1 0 0
v_reserve_write_off 19894 19894 0 0.00 0 34089 7 0 0
x_lock_depdocs_on_canc_invoice 9752 9752 0 0.00 0 16000 107 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 9494 316 0 0
x_make_qstorno_1000_1200 5446 5446 0 0.00 2 30342 1283 0 0
x_make_qstorno_1200_2000 5472 5472 0 0.00 3 93013 3441 0 0
x_make_qstorno_2100_3300 447796 447796 0 0.00 0 65436 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2022 04:39:12 to 02-01-2022 04:39:16.

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
02:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:15 24595 8192000000 9734977104 10034442240 812516656 9.023 100 88 176 0 841759376 7887040 21355680
02:09:41 32154 8192000000 9774658832 10084577280 844771616 9.348 100 90 180 0 879031712 13057168 21202928
02:11:23 19526 8192000000 9813765872 10136231936 874741408 9.647 100 77 154 0 919321584 27086448 17493728
02:12:47 10489 8192000000 9875780704 10210471936 923238256 10.128 100 75 150 1 981033072 41345248 16449568
02:14:01 7614 8192000000 9930617120 10275254272 965763872 10.545 100 77 154 0 1037313312 55045840 16503600
02:15:10 7264 8192000000 9964271472 10318790656 1002924208 10.907 100 81 162 1 1072003024 53045024 16033792
02:16:21 3096 8192000000 10001359760 10362081280 1024279424 11.113 100 89 176 0 1115401280 73627200 17494656
02:17:26 1677 8192000000 10039748208 10404478976 1050680048 11.367 100 91 182 0 1145783664 75957088 19146528
02:18:28 15221 8192000000 10044861904 10422562816 1068609584 11.539 100 80 161 1 1158956560 73541440 16805536
02:19:44 6088 8192000000 10003954848 10399608832 1079985760 11.647 100 40 79 0 1129601728 41499040 8116928
02:20:50 10838 8192000000 9988451712 10400710656 1084826976 11.693 100 21 42 2 1120198608 31144512 4227120
02:22:02 10758 8192000000 10014679648 10415566848 1098147232 11.820 100 33 66 0 1136229808 31206128 6876448
02:23:15 10672 8192000000 10023373072 10432110592 1105494992 11.890 100 41 81 0 1139921776 25225664 9201120
02:24:27 22871 8192000000 10022102272 10411376640 1104043632 11.876 100 68 134 2 1127096368 8526752 14525984
02:25:50 4000 8192000000 10041413072 10437029888 1107667856 11.910 100 59 121 1 1147284224 26937216 12679152
02:26:59 5159 8192000000 10016152480 10420240384 1082649504 11.673 99 28 56 0 1116839280 28195808 5993968
02:28:04 10322 8192000000 9977975120 10367078400 1019337840 11.066 95 24 48 1 1058120768 33355120 5427808
02:29:16 40125 8192000000 9945206528 10338734080 925082672 10.146 87 30 60 2 958277072 26554256 6640144
02:30:56 3104 8192000000 9924756368 10302812160 990154064 10.783 95 30 59 1 1023045344 25760208 7131072
02:32:00 6620 8192000000 9940754720 10317791232 1000305824 10.881 98 44 84 2 1031900624 22078496 9516304
02:33:07 7154 8192000000 9956194576 10340851712 1030755248 11.176 99 40 79 2 1054234128 14456400 9022480
02:34:15 24565 8192000000 9967439792 10364256256 1046462064 11.327 100 22 44 0 1070941968 19798224 4681680
02:35:40 9718 8192000000 9964270784 10363764736 1056996352 11.428 100 42 82 1 1081552128 15568992 8986784
02:36:52 12325 8192000000 9981738848 10388783104 1067312832 11.526 100 17 34 3 1095189408 23770112 4106464
02:38:10 11497 8192000000 10050482512 10439786496 1096651968 11.806 100 54 107 0 1159362944 51579904 11131072
02:39:24 40857 8192000000 10066332096 10488492032 1100320720 11.841 100 32 65 1 1178977872 72368736 6288416
02:41:11 13687 8192000000 10049012032 10451038208 1113826144 11.969 100 39 76 1 1149718880 28152192 7740544
02:42:32 8000 8192000000 10083385856 10489638912 1128023872 12.103 100 44 88 3 1196714080 58718752 9971456
02:43:48 11628 8192000000 10151583616 10554564608 1138700432 12.203 100 59 118 0 1254995184 103882224 12412528
02:45:07 3711 8192000000 10194890688 10594242560 1146013008 12.272 100 63 126 1 1301574080 142016224 13544848
02:46:18 6461 8192000000 10211204352 10615541760 1155033168 12.357 100 66 129 0 1316013680 146888960 14091552
02:47:29 7588 8192000000 10249320112 10657988608 1161611840 12.418 100 71 141 1 1347926000 170556928 15757232
02:48:41 2477 8192000000 10260745072 10661154816 1162634336 12.428 100 88 176 2 1364749104 182924256 19190512
02:49:47 7655 8192000000 10254397520 10667638784 1147594848 12.287 100 64 127 1 1364857984 202980368 14282768
02:51:02 2110 8192000000 10306105440 10708811776 1158696640 12.391 99 86 169 1 1397907488 219904368 19306480
02:52:09 2028 8192000000 10309914368 10712358912 1134022288 12.159 99 72 145 1 1407617056 257410128 16184640
02:53:14 2197 8192000000 10285371152 10685632512 1139724832 12.213 100 76 153 2 1382374496 224594912 18054752
02:54:23 9392 8192000000 10237192624 10659266560 1112413440 11.955 99 31 62 2 1353592576 233207888 7971248
02:55:38 11292 8192000000 10230082224 10661523456 1134942384 12.168 100 50 99 0 1352713712 206035296 11736032
02:56:53 16265 8192000000 10236884112 10660450304 1116518848 11.994 99 34 68 1 1341865936 217031888 8315200
02:58:38 24886 8192000000 10209964384 10666479616 1130475024 12.126 99 25 50 0 1327697920 190870736 6352160
03:00:14 23939 8192000000 10183938432 10642501632 1117200576 12.001 100 32 64 5 1309126496 183769072 8156848
03:02:01 29684 8192000000 10132873888 10578558976 1085803184 11.703 99 16 33 0 1262299264 172095392 4400688
03:03:32 82256 8192000000 10024573904 10470334464 1077136784 11.620 97 33 66 0 1115079136 30106224 7836128
03:05:55 69772 8192000000 10032784224 10478243840 1077203792 11.621 97 20 41 1 1108269328 26628944 4436592
03:08:40 62186 8192000000 10069088176 10505150464 1091832192 11.760 98 12 23 0 1156424608 62382080 2210336
03:10:50 152837 8192000000 10086243088 10579333120 1112350784 11.955 98 16 32 0 1194225328 78327408 3547136
03:14:41 35280 8192000000 10068093904 10511704064 1126201296 12.086 100 19 38 0 1189163328 59042064 3919968
03:16:23 30398 8192000000 10093040704 10545856512 1132432448 12.144 100 31 63 0 1195038128 55908032 6697648
03:17:55 74041 8192000000 10094686288 10543013888 1139654256 12.212 100 25 49 1 1189323696 43869888 5799552
03:21:08 53403 8192000000 10117272368 10592403456 1139893680 12.215 99 25 50 1 1217759392 72296672 5569040
03:23:02 36310 8192000000 10129923568 10565033984 1154472048 12.351 100 35 70 1 1220450528 58814224 7164256
03:24:42 70634 8192000000 10149265088 10619965440 1155350240 12.360 100 16 32 0 1236884752 78178368 3356144
03:27:07 78863 8192000000 10086958432 10527588352 1126341616 12.087 97 9 18 0 1172484064 44605376 1537072
03:29:26 90587 8192000000 10093232752 10552545280 1092621440 11.768 94 19 38 0 1140867504 44317712 3928352
03:31:57 91949 8192000000 10063696672 10490945536 1105144896 11.886 97 27 54 0 1134394528 23762976 5486656
03:34:32 53302 8192000000 10138208416 10590752768 1132121552 12.141 99 18 36 1 1214982672 78608304 4252816
03:36:26 50053 8192000000 10087012576 10515361792 1143046400 12.244 100 13 26 1 1188634704 42800112 2788192
03:38:21 87968 8192000000 10061985888 10556919808 1101749872 11.854 96 17 34 0 1173466096 67968672 3747552
03:41:06 55795 8192000000 10088341856 10568593408 1108185808 11.915 98 18 36 1 1174678192 62866688 3625696
03:43:08 52759 8192000000 10109731648 10561372160 1137424000 12.191 100 21 42 0 1211427152 69799136 4204016
03:45:05 40108 8192000000 10040805840 10477502464 1086867936 11.713 97 18 36 0 1119252560 28722048 3662576
03:46:51 80452 8192000000 10095802224 10548174848 1094258752 11.783 98 34 68 0 1176243968 75526208 6459008
03:49:14 48645 8192000000 10073742848 10508812288 1063805152 11.493 96 44 88 0 1116349808 43864032 8680624
03:51:11 57979 8192000000 10070314656 10501246976 1095133440 11.791 99 25 50 0 1137249264 37326608 4789216
03:53:11 116910 8192000000 10058237472 10504941568 1109551760 11.928 99 26 52 0 1148515792 33543664 5420368
03:56:10 81048 8192000000 10026131712 10486161408 1100239344 11.840 98 11 22 0 1150280080 47839328 2201408
03:58:38 66622 8192000000 10055964304 10501951488 1111029216 11.942 99 21 42 0 1146144464 30160368 4954880
04:00:45 51465 8192000000 10064893360 10523389952 1095410816 11.794 98 10 20 1 1157762528 60280960 2070752
04:02:39 40108 8192000000 10090387696 10525814784 1123792208 12.063 100 33 66 0 1176411632 44917120 7702304
04:04:22 74344 8192000000 10042251488 10470309888 1065241408 11.507 95 26 52 0 1113849696 42817248 5791040
04:06:41 65945 8192000000 10095640752 10554195968 1123417008 12.059 100 29 57 2 1193371648 63855024 6099616
04:08:48 72008 8192000000 10078792896 10503901184 1067491056 11.528 94 18 36 0 1105566560 34378176 3697328
04:11:01 107839 8192000000 10066152112 10532622336 1114492896 11.975 98 13 26 0 1187149552 70389920 2266736
04:13:50 91477 8192000000 10087682640 10524262400 1131073200 12.131 99 25 50 3 1177029472 41037312 4918960
04:16:47 56091 8192000000 10065484544 10515070976 1140890096 12.224 100 9 18 0 1172850768 30469056 1491616
04:18:44 77190 8192000000 10110760896 10578812928 1150005168 12.310 100 13 26 1 1211516672 58721584 2789920
04:21:18 89662 8192000000 10072822400 10554073088 1135084832 12.169 98 18 36 0 1216855664 77691168 4079664
04:23:55 53908 8192000000 10081542912 10551767040 1119986800 12.027 97 26 52 3 1180672368 54199728 6485840
04:26:00 111342 8192000000 10118956256 10660167680 1120695984 12.034 97 25 50 0 1228349376 102575920 5077472
04:28:59 41967 8192000000 10094179328 10542419968 1123048832 12.056 98 30 60 0 1189538912 60172464 6317616
04:30:41 56478 8192000000 10078329808 10519642112 1100304240 11.841 97 30 60 0 1155869616 49339952 6225424
04:32:39 12308 8192000000 10047387072 10463432704 1102232368 11.859 100 36 72 0 1127257600 17907520 7117712
04:33:53 86092 8192000000 10036839664 10496311296 1120359680 12.030 100 25 50 0 1173671792 47645104 5667008
04:36:20 65316 8192000000 9809362640 10247512064 913138320 10.028 81 7 14 0 956757072 41946720 1672032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-01-2022 04:39:18 to 02-01-2022 04:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-01-2022 04:39:34 to 02-01-2022 04:39:36.

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          238781
MON$OLDEST_ACTIVE               238782
MON$OLDEST_SNAPSHOT             238782
MON$NEXT_TRANSACTION            238782
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                       2016256
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             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2699 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2699 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2699 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2699 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 ---
--- end of diff output ---

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

02.01.22 04:39:37. 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-01-02 04:39:39 No severe PSQL-related problems occured
02.01.22 04:39:40. End of report.