Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.01.2023 04:40
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 14679892 kB
MemAvailable 30383652 kB
Buffers 20 kB
Cached 16984996 kB
SwapCached 5568 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 2023-01-04 04:37:24
DTS_END                         2023-01-04 04:37:23.9990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39316
TRN_ID                          219261
ATT_ID                          613
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: 9041.20 25847 1084944
customer order: creation 169.19 481 20303
customer order: refuse 14.68 477 1762
order to supplier: creation 46.34 1346 5561
order to supplier: removal 3.30 177609 396
invoice (draft): creation 45.78 3259 5494
invoice (draft): removal 50.18 1044 6022
invoice accept: apply 42.09 27428 5051
invoice accept: cancel 45.58 1215 5469
customer reserve: creation 3907.78 258 468933
customer reserve: removal 4420.63 102 530475
realization accept: apply 82.92 316 9950
realization accept: cancel 81.79 167 9815
payment from customer: creation 58.77 187 7052
payment from customer: removal 5.82 130 698
payment to supplier: creation 49.88 212 5985
payment to supplier: removal 3.49 162 419
service: total inventory turnovers 3.96 4562 475
service: total monetary turnovers 8.13 380 976
service: aggregate perf. data 0.84 20422 101
service: refresh index statistics 0.06 277192 7

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-01-2023 04:40:26 to 04-01-2023 04:40:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7710
TEST_TIME 2 7050
TEST_TIME 3 7816
TEST_TIME 4 7135
TEST_TIME 5 6770
TEST_TIME 6 6633
TEST_TIME 7 6435
TEST_TIME 8 6691
TEST_TIME 9 6578
TEST_TIME 10 6516
TEST_TIME 11 6609
TEST_TIME 12 6575
TEST_TIME 13 6598
TEST_TIME 14 6762
TEST_TIME 15 6898
TEST_TIME 16 6940
TEST_TIME 17 7008
TEST_TIME 18 7027
TEST_TIME 19 7069
TEST_TIME 20 7162
TEST_TIME 21 7463
TEST_TIME 22 7673
TEST_TIME 23 7731
TEST_TIME 24 7733
TEST_TIME 25 7842
TEST_TIME 26 8206
TEST_TIME 27 8412
TEST_TIME 28 8438
TEST_TIME 29 8350
TEST_TIME 30 8256
TEST_TIME 31 8904
TEST_TIME 32 8929
TEST_TIME 33 9024
TEST_TIME 34 9247
TEST_TIME 35 9303
TEST_TIME 36 9258
TEST_TIME 37 9599
TEST_TIME 38 9978
TEST_TIME 39 10225
TEST_TIME 40 10366
TEST_TIME 41 10329
TEST_TIME 42 10467
TEST_TIME 43 10461
TEST_TIME 44 10453
TEST_TIME 45 10266
TEST_TIME 46 10397
TEST_TIME 47 10297
TEST_TIME 48 10348
TEST_TIME 49 10277
TEST_TIME 50 10276
TEST_TIME 51 10243
TEST_TIME 52 10127
TEST_TIME 53 9980
TEST_TIME 54 9898
TEST_TIME 55 9822
TEST_TIME 56 9709
TEST_TIME 57 9627
TEST_TIME 58 9622
TEST_TIME 59 9562
TEST_TIME 60 9560
TEST_TIME 61 9615
TEST_TIME 62 9653
TEST_TIME 63 9637
TEST_TIME 64 9570
TEST_TIME 65 9549
TEST_TIME 66 9468
TEST_TIME 67 9471
TEST_TIME 68 9449
TEST_TIME 69 9419
TEST_TIME 70 9363
TEST_TIME 71 9347
TEST_TIME 72 9306
TEST_TIME 73 9327
TEST_TIME 74 9277
TEST_TIME 75 9282
TEST_TIME 76 9381
TEST_TIME 77 9405
TEST_TIME 78 9412
TEST_TIME 79 9405
TEST_TIME 80 9364
TEST_TIME 81 9391
TEST_TIME 82 9401
TEST_TIME 83 9585
TEST_TIME 84 9557
TEST_TIME 85 9518
TEST_TIME 86 9433
TEST_TIME 87 9456
TEST_TIME 88 9394
TEST_TIME 89 9345
TEST_TIME 90 9337
TEST_TIME 91 9317
TEST_TIME 92 9332
TEST_TIME 93 9307
TEST_TIME 94 9277
TEST_TIME 95 9213
TEST_TIME 96 9223
TEST_TIME 97 9168
TEST_TIME 98 9177
TEST_TIME 99 9130
TEST_TIME 100 9132
TEST_TIME 101 9117
TEST_TIME 102 9129
TEST_TIME 103 9119
TEST_TIME 104 9124
TEST_TIME 105 9147
TEST_TIME 106 9161
TEST_TIME 107 9241
TEST_TIME 108 9289
TEST_TIME 109 9245
TEST_TIME 110 9258
TEST_TIME 111 9252
TEST_TIME 112 9317
TEST_TIME 113 9270
TEST_TIME 114 9298
TEST_TIME 115 9230
TEST_TIME 116 9228
TEST_TIME 117 9170
TEST_TIME 118 9162
TEST_TIME 119 9108
TEST_TIME 120 9061

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-01-2023 04:40:29 to 04-01-2023 04:40:33.

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 1516967 1516967 0 0.00 0 17695 14 0 0
doc_list_biud 1516963 1516963 0 0.00 0 31902 42 0 0
sp_add_invoice_to_stock 5157 5051 106 2.05 43 168326 27429 0 106
sp_cancel_adding_invoice 5469 5469 0 0.00 6 63302 1215 0 0
sp_cancel_client_order 1762 1762 0 0.00 6 11725 478 0 0
sp_cancel_customer_reserve 530475 530475 0 0.00 1 31906 102 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 1881 130 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 4 6919 162 0 0
sp_cancel_supplier_invoice 6029 6022 7 0.11 3 19754 1045 0 7
sp_cancel_supplier_order 396 396 0 0.00 8 1669769 177609 0 0
sp_cancel_write_off 9815 9815 0 0.00 2 9647 167 0 0
sp_client_order 20303 20303 0 0.00 5 21254 481 0 0
sp_customer_reserve 468933 468933 0 0.00 0 90062 258 0 0
sp_fill_shopping_cart 31341 31341 0 0.00 1 12843 89 0 0
sp_fill_shopping_cart_clo_res 468911 468911 0 0.00 0 16182 19 0 0
sp_get_clo_for_invoice 5023 5023 0 0.00 0 26912 264 0 0
sp_kill_cost_storno 16401 16401 0 0.00 0 10219 79 0 0
sp_kill_qstorno_ret_qs2qd 1077183 1077183 0 0.00 0 31853 44 0 0
sp_kill_qty_storno 568912 568912 0 0.00 0 31901 103 0 0
sp_lock_selected_doc 20697 20697 0 0.00 0 6673 7 0 0
sp_make_cost_storno 28010 28010 0 0.00 0 15834 99 0 0
sp_multiply_rows_for_pdistr 28010 28010 0 0.00 0 6821 39 0 0
sp_multiply_rows_for_qdistr 20303 20303 0 0.00 0 10938 176 0 0
sp_pay_from_customer 7052 7052 0 0.00 5 10971 187 0 0
sp_pay_to_supplier 5985 5985 0 0.00 7 13307 213 0 0
sp_payment_common 13036 13036 0 0.00 2 13300 54 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 0 11699 393 0 0
sp_qd_handle_on_invoice_upd_sts 10492 10492 0 0.00 0 24386 580 0 0
sp_qd_handle_on_reserve_upd_sts 9950 9950 0 0.00 0 15804 72 0 0
sp_reserve_write_off 9950 9950 0 0.00 9 17715 317 0 0
sp_supplier_invoice 5494 5494 0 0.00 9 130611 3259 0 0
sp_supplier_order 5561 5561 0 0.00 9 46321 1347 0 0
srv_aggregate_perf_data 101 101 0 0.00 3047 209950 20423 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 821 130500 32707 0 0
srv_find_qd_qs_mism 1035874 1035874 0 0.00 0 13119 10 0 0
srv_make_invnt_saldo 475 475 0 0.00 90 97835 4562 0 0
srv_make_money_saldo 976 976 0 0.00 1 11817 380 0 0
srv_recalc_idx_stat 7 7 0 0.00 37343 506726 277192 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5057 5057 0 0.00 1 11725 1094 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 6123 439 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 1859 35 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 2565 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 2442 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 1263 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 849 40 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 121270 8159 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 3113 27 0 0
t$perf-norm:sp_client_order 20303 20303 0 0.00 0 11697 41 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 427 14 0 0
t$perf-norm:sp_pay_from_customer 7052 7052 0 0.00 0 10415 23 0 0
t$perf-norm:sp_pay_to_supplier 5985 5985 0 0.00 0 5862 23 0 0
t$perf-norm:sp_reserve_write_off 9950 9950 0 0.00 0 4162 23 0 0
t$perf-norm:sp_supplier_invoice 5494 5494 0 0.00 0 5405 36 0 0
t$perf-norm:sp_supplier_order 5561 5561 0 0.00 0 5286 35 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 761 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 11568 163 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 1194 17 0 0
t$perf-norm:srv_make_money_saldo 976 976 0 0.00 0 2557 9 0 0
t$perf-norm:srv_recalc_idx_stat 7 7 0 0.00 2 303 76 0 0
v_add_invoice_to_stock 10246 10034 212 2.06 0 4998 27 0 212
v_all_customers 37352 37352 0 0.00 0 133 4 0 0
v_all_suppliers 30950 30950 0 0.00 0 81 5 0 0
v_all_wares 40588 40588 0 0.00 0 57 4 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 456 15 0 0
v_cancel_client_order 3524 3524 0 0.00 0 6381 15 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 441 12 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 9272 18 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 6375 29 0 0
v_cancel_supplier_invoice 818 804 14 1.71 0 4514 32 0 14
v_cancel_supplier_order 792 792 0 0.00 0 429 12 0 0
v_cancel_write_off 2048 2048 0 0.00 0 6480 26 0 0
v_max_id_clo_ord 5556 5556 0 0.00 0 3108 7 0 0
v_max_id_clo_res 986 986 0 0.00 0 1891 14 0 0
v_max_id_ord_sup 5485 5485 0 0.00 0 8466 8 0 0
v_max_non_paid_invoice 1548 1548 0 0.00 0 631 9 0 0
v_max_non_paid_realizn 3659 3659 0 0.00 0 6063 11 0 0
v_min_id_clo_ord 5555 5555 0 0.00 0 2341 11 0 0
v_min_id_clo_res 986 986 0 0.00 0 5609 24 0 0
v_min_id_ord_sup 5485 5485 0 0.00 0 7276 11 0 0
v_min_non_paid_invoice 5985 5985 0 0.00 0 13287 23 0 0
v_min_non_paid_realizn 7051 7051 0 0.00 0 6131 19 0 0
v_our_firm 10020 10020 0 0.00 0 57 5 0 0
v_reserve_write_off 19896 19896 0 0.00 0 8914 13 0 0
x_lock_depdocs_on_canc_invoice 11089 11089 0 0.00 0 13723 138 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 12282 422 0 0
x_make_qstorno_1000_1200 5561 5561 0 0.00 2 46143 1153 0 0
x_make_qstorno_1200_2000 5494 5494 0 0.00 6 126986 3031 0 0
x_make_qstorno_2100_3300 468932 468932 0 0.00 0 90053 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-01-2023 04:40:34 to 04-01-2023 04:40:38.

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:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:29 34758 8192000000 9724130608 10024767488 798272320 8.879 100 87 172 0 827174048 7849808 21051920
02:10:13 20076 8192000000 9771026464 10080923648 842971328 9.330 100 90 180 0 877434656 12997824 21465504
02:11:46 12860 8192000000 9801587008 10124611584 870619120 9.606 100 83 163 0 912762944 23787472 18356352
02:13:07 8658 8192000000 9881662432 10219180032 917396896 10.070 100 84 162 1 996089024 60904848 17787280
02:14:23 3148 8192000000 9923451008 10265153536 958069776 10.470 100 91 179 0 1037667808 61022416 18575616
02:15:28 2531 8192000000 9962598032 10313478144 987630080 10.758 100 92 180 1 1082869296 76615616 18623600
02:16:34 3067 8192000000 9986183680 10345713664 1013908704 11.013 100 92 183 0 1114140880 82024784 18207392
02:17:45 1181 8192000000 10010185440 10381848576 1040960928 11.274 100 93 172 3 1138600272 80385824 17253520
02:18:49 1026 8192000000 10045867680 10422800384 1053612384 11.395 100 97 193 2 1163066960 90008848 19445728
02:19:52 998 8192000000 10033815856 10411216896 1067097200 11.524 100 97 187 1 1159285552 72453296 19735056
02:20:56 1288 8192000000 10061970448 10444062720 1073386176 11.584 100 93 183 1 1181157696 89837248 17934272
02:21:59 5047 8192000000 10051644720 10432098304 1082988832 11.676 100 95 182 1 1171652032 70108912 18554288
02:23:04 1160 8192000000 10053963600 10438283264 1088321680 11.727 100 94 177 0 1171963216 66922400 16719136
02:24:06 1050 8192000000 10051501216 10444197888 1092001952 11.762 100 87 170 2 1171389824 61231872 18156000
02:25:08 2835 8192000000 10047604976 10436562944 1094832656 11.789 100 75 145 1 1165982928 56760944 14389328
02:26:12 1197 8192000000 10068552528 10453954560 1095528272 11.795 100 97 188 1 1185012192 71054688 18429232
02:27:14 1123 8192000000 10075677072 10461732864 1096434848 11.804 100 95 179 1 1198444992 83366336 18643808
02:28:17 1156 8192000000 10077574880 10469793792 1098552288 11.824 100 97 188 1 1197854928 80016656 19285984
02:29:19 1101 8192000000 10048206528 10437795840 1094353824 11.784 100 92 175 3 1174300016 61102336 18843856
02:30:21 931 8192000000 10044976656 10434007040 1087016544 11.714 100 84 159 2 1166346048 64134544 15194960
02:31:23 1856 8192000000 10024505392 10414518272 1093110336 11.772 100 78 153 2 1154135280 45509760 15515184
02:32:26 1337 8192000000 10019442336 10404651008 1071446240 11.566 99 78 153 2 1142097472 54189312 16461920
02:33:28 1080 8192000000 10040556592 10421477376 1069621216 11.548 100 93 187 1 1162911328 74869968 18420144
02:34:31 3724 8192000000 10002553168 10383433728 1033031776 11.198 98 68 131 1 1115308752 69392720 12884256
02:35:35 1265 8192000000 9970981968 10345738240 1037848864 11.244 100 87 170 1 1092213792 36272512 18092416
02:36:41 1116 8192000000 10000680096 10376347648 1037047248 11.236 100 95 177 2 1122365216 66312736 19005232
02:37:44 1241 8192000000 10001544816 10380038144 1053168896 11.391 100 82 139 1 1124641088 56372432 15099760
02:38:54 18695 8192000000 10020183632 10409275392 1069324512 11.546 100 63 122 2 1146470352 65426784 11719056
02:40:16 1205 8192000000 10076610560 10459365376 1078212480 11.630 100 91 179 3 1196152800 99085936 18854384
02:41:23 20668 8192000000 10071697840 10476175360 1091229776 11.754 100 66 128 0 1195129968 91139024 12761168
02:42:48 5270 8192000000 10097242480 10493812736 1108598448 11.919 100 71 140 0 1215206240 91901472 14706320
02:43:59 6983 8192000000 10115627360 10506067968 1115913104 11.988 100 75 140 2 1225340752 94788560 14639088
02:45:11 3266 8192000000 10176011840 10577326080 1127517824 12.098 100 68 133 0 1280944752 138844384 14582544
02:46:19 2483 8192000000 10189682320 10588934144 1141355232 12.228 100 78 152 0 1304598240 146890544 16352464
02:47:25 6438 8192000000 10241095904 10647576576 1149114720 12.301 100 76 152 0 1351392384 185305792 16971872
02:48:39 1063 8192000000 10272352544 10674900992 1156783056 12.373 100 95 173 2 1385111536 209111920 19216560
02:49:43 1986 8192000000 10319725984 10721103872 1166879600 12.468 100 88 167 1 1436577344 251492432 18205312
02:50:52 1298 8192000000 10318001744 10728644608 1170163200 12.498 100 92 170 0 1429114240 239818992 19132048
02:51:58 817 8192000000 10353109344 10757443584 1180948944 12.599 100 91 171 1 1464455696 264502400 19004352
02:53:00 1414 8192000000 10364290896 10772574208 1181149168 12.601 100 94 186 1 1477256720 275084112 21023440
02:54:04 1351 8192000000 10408745216 10820997120 1190964048 12.692 100 98 191 3 1519674864 306586352 22124464
02:55:09 1286 8192000000 10434431776 10837139456 1181365552 12.603 100 97 178 2 1541471056 338642048 21463456
02:56:13 1871 8192000000 10458147680 10879881216 1182541536 12.614 100 92 181 1 1564612256 360550112 21520608
02:57:21 1730 8192000000 10504000896 10915684352 1180392256 12.594 100 82 161 0 1611512208 412118736 19001216
02:58:27 3857 8192000000 10458818688 10895167488 1158047920 12.385 100 88 172 2 1569317680 390602272 20667488
02:59:33 857 8192000000 10490610288 10919247872 1165238352 12.452 100 99 194 2 1608585328 417713344 25633632
03:00:37 1182 8192000000 10507597504 10906976256 1162486624 12.427 99 96 188 0 1607869824 422997488 22385712
03:01:43 988 8192000000 10535181184 10969505792 1180429840 12.594 100 97 193 1 1641707872 437707568 23570464
03:02:45 1264 8192000000 10521223168 10973196288 1168386112 12.482 100 97 186 3 1621724528 430645904 22692512
03:03:49 2806 8192000000 10518857264 10949611520 1175286992 12.546 100 90 179 1 1641490912 443700992 22502928
03:04:55 7797 8192000000 10499451232 10938925056 1177516272 12.567 100 46 92 1 1601316912 411872352 11928288
03:06:11 21326 8192000000 10530836560 10959130624 1148419312 12.295 98 44 88 0 1611312224 451319168 11573744
03:07:40 8220 8192000000 10477352240 10997231616 1162677472 12.428 100 52 103 0 1584271648 409063360 12530816
03:08:51 11552 8192000000 10486487936 10964041728 1177589360 12.568 100 46 90 0 1595231024 406330608 11311056
03:10:07 22825 8192000000 10500747216 10969571328 1169790864 12.495 99 47 95 0 1594179424 413604960 10783600
03:11:31 21632 8192000000 10444243808 10910646272 1164910304 12.449 100 30 58 0 1557296880 384639968 7746608
03:12:56 14899 8192000000 10425213728 10895081472 1159454176 12.398 100 40 81 0 1525857328 356883008 9520144
03:14:19 13635 8192000000 10384859600 10911514624 1161250720 12.415 99 40 80 0 1499919936 328902416 9766800
03:15:34 33872 8192000000 10308132960 10817990656 1162083536 12.423 100 25 46 0 1430352800 262464416 5804848
03:17:09 53238 8192000000 10161061872 10625269760 1136544240 12.183 99 18 35 0 1261138800 120646592 3947968
03:19:04 81732 8192000000 10110619392 10617868288 1155852672 12.364 100 26 51 0 1237409296 75755216 5801408
03:21:38 56483 8192000000 10119187312 10561736704 1137854624 12.195 99 32 64 0 1181145872 35847936 7443312
03:23:54 130499 8192000000 10095146288 10598944768 1120296096 12.030 97 10 18 0 1181797248 59825920 1675232
03:27:06 76434 8192000000 10071661296 10534191104 1129163840 12.113 98 12 25 0 1174863744 43642240 2057664
03:29:25 106355 8192000000 10071293760 10507841536 1090264688 11.745 95 29 57 1 1123201856 27059552 5877616
03:32:13 40439 8192000000 10080134048 10517704704 1112440192 11.956 99 44 84 0 1141944752 20435536 9069024
03:34:58 1639 8192000000 10091151536 10508148736 1128672288 12.109 100 84 160 2 1182068096 37283168 16112640
03:36:02 62346 8192000000 10133476640 10581065728 1125265696 12.077 99 20 40 0 1211006688 81103504 4637488
03:38:06 4357 8192000000 10133704480 10563600384 1120109792 12.028 98 56 103 0 1213810608 82328800 11372016
03:39:14 11838 8192000000 10112962832 10606026752 1131370864 12.134 100 25 51 1 1217705712 81037008 5297840
03:40:26 39272 8192000000 10096223808 10579537920 1141425088 12.229 100 12 24 1 1193256976 49125792 2706096
03:42:06 54760 8192000000 10112884640 10588143616 1129827248 12.120 98 12 21 0 1220420896 88514224 2079424
03:44:01 59451 8192000000 10117649344 10620485632 1131980528 12.140 99 12 24 0 1221127952 86831184 2316240
03:46:06 91826 8192000000 10034636352 10508144640 1054752352 11.406 93 11 19 0 1107437024 50636576 2048096
03:48:40 48058 8192000000 10059098608 10517708800 1103100720 11.867 98 22 38 0 1142661168 36158688 3401760
03:50:31 39535 8192000000 10076315040 10513543168 1093334112 11.774 97 27 54 0 1144133840 45018208 5781520
03:52:10 21813 8192000000 10158851072 10604998656 1117200064 12.001 99 34 66 0 1237738752 113544992 6993696
03:53:33 58260 8192000000 10091889808 10538754048 1081853888 11.665 96 35 72 0 1182876640 93430144 7592608
03:55:31 29902 8192000000 10090629216 10527760384 1114044976 11.971 100 44 88 0 1181617392 58526384 9046032
03:57:03 26211 8192000000 10150791472 10584293376 1127047616 12.094 100 31 62 0 1234375968 100648720 6679632
03:58:39 49170 8192000000 10170686448 10618216448 1127396784 12.097 100 26 52 0 1283217440 150238816 5581840
04:00:34 56653 8192000000 10069289904 10540793856 1116527568 11.994 100 14 24 2 1155714928 36871328 2316032
04:02:34 89607 8192000000 10106221888 10541301760 1128983312 12.112 100 43 85 0 1191752000 54200672 8568016
04:05:21 54453 8192000000 10056068352 10521554944 1099056448 11.829 97 38 74 1 1134603424 28158336 7388640
04:07:16 40733 8192000000 10103845296 10551418880 1134227104 12.161 100 33 64 0 1221755984 80207056 7321824
04:09:02 70403 8192000000 10067106160 10539548672 1107203408 11.906 97 20 40 1 1186624144 75323088 4097648
04:11:18 61936 8192000000 10076556384 10545610752 1122114288 12.047 99 18 35 0 1186594048 60455936 4023824
04:13:30 69066 8192000000 10081345840 10542538752 1139116208 12.207 99 20 40 0 1177961968 34979040 3866720
04:15:46 57087 8192000000 10080700784 10536574976 1135260608 12.171 99 10 20 0 1161325392 23499088 2565696
04:17:48 48590 8192000000 10104677808 10550321152 1111601184 11.948 97 26 46 2 1167307184 50604352 5101648
04:19:39 41396 8192000000 10122049888 10562433024 1122860928 12.054 99 30 60 0 1213634864 84432368 6341568
04:21:21 25656 8192000000 10151646032 10594074624 1149820096 12.308 100 38 76 0 1265904656 108004544 8080016
04:22:48 19068 8192000000 10152688736 10593890304 1142651488 12.240 100 30 60 0 1244338912 95323328 6364096
04:24:07 80278 8192000000 10079406048 10571243520 1095725440 11.797 97 19 38 0 1156132144 56614464 3792240
04:26:30 35401 8192000000 10116264912 10563260416 1121401184 12.040 99 54 110 0 1200917888 68176784 11339920
04:28:09 28466 8192000000 10153798960 10588241920 1129552080 12.117 99 46 90 0 1213348480 74908560 8887840
04:29:40 46513 8192000000 10102006944 10548539392 1111700496 11.949 97 37 74 0 1153973600 34162912 8110192
04:31:27 87686 8192000000 10142313296 10605273088 1119407552 12.021 98 37 72 1 1206084560 79410064 7266944
04:34:52 87016 8192000000 10098688896 10548109312 1093680752 11.778 96 12 24 0 1190626464 94468640 2477072
04:37:22 30661 8192000000 9098484208 9403973632 250802112 2.970 23 4 8 0 291234592 39573392 859088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-01-2023 04:40:40 to 04-01-2023 04:40:56.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-01-2023 04:40:57 to 04-01-2023 04:40:59.

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          220289
MON$OLDEST_ACTIVE               220290
MON$OLDEST_SNAPSHOT             220290
MON$NEXT_TRANSACTION            220290
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                       2100800
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

04.01.23 04:41:00. 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
2023-01-04 04:41:03 No severe PSQL-related problems occured
04.01.23 04:41:04. End of report.