Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2022 16:39
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 15457344 kB
MemAvailable 30452588 kB
Buffers 0 kB
Cached 16242860 kB
SwapCached 2044 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-22 16:38:31
DTS_END                         2022-10-22 16:38:31.5400
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(57:17)
IP                              ::1/49452
TRN_ID                          244859
ATT_ID                          650
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: 9462.45 22376 1135494
customer order: creation 177.56 402 21307
customer order: refuse 14.85 346 1782
order to supplier: creation 47.59 1258 5711
order to supplier: removal 3.69 84456 443
invoice (draft): creation 46.90 3380 5628
invoice (draft): removal 53.57 691 6428
invoice accept: apply 42.43 16923 5091
invoice accept: cancel 49.03 732 5884
customer reserve: creation 3926.38 168 471166
customer reserve: removal 4794.65 51 575358
realization accept: apply 86.89 160 10427
realization accept: cancel 87.82 107 10538
payment from customer: creation 59.71 104 7165
payment from customer: removal 5.82 63 698
payment to supplier: creation 50.14 127 6017
payment to supplier: removal 3.54 60 425
service: total inventory turnovers 4.47 1064 537
service: total monetary turnovers 6.53 36 784
service: aggregate perf. data 0.84 22507 101
service: refresh index statistics 0.03 314875 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-10-2022 16:39:34 to 22-10-2022 16:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7358
TEST_TIME 2 5074
TEST_TIME 3 4999
TEST_TIME 4 4912
TEST_TIME 5 4773
TEST_TIME 6 5133
TEST_TIME 7 5130
TEST_TIME 8 5106
TEST_TIME 9 5341
TEST_TIME 10 5521
TEST_TIME 11 5312
TEST_TIME 12 5303
TEST_TIME 13 5561
TEST_TIME 14 6132
TEST_TIME 15 6328
TEST_TIME 16 6927
TEST_TIME 17 6862
TEST_TIME 18 7345
TEST_TIME 19 7926
TEST_TIME 20 8186
TEST_TIME 21 8519
TEST_TIME 22 8661
TEST_TIME 23 9085
TEST_TIME 24 9638
TEST_TIME 25 9919
TEST_TIME 26 10432
TEST_TIME 27 10335
TEST_TIME 28 10181
TEST_TIME 29 10200
TEST_TIME 30 10315
TEST_TIME 31 10265
TEST_TIME 32 10027
TEST_TIME 33 9940
TEST_TIME 34 9905
TEST_TIME 35 9927
TEST_TIME 36 9949
TEST_TIME 37 9865
TEST_TIME 38 9763
TEST_TIME 39 9875
TEST_TIME 40 9751
TEST_TIME 41 9606
TEST_TIME 42 9584
TEST_TIME 43 9755
TEST_TIME 44 9661
TEST_TIME 45 9679
TEST_TIME 46 9654
TEST_TIME 47 9528
TEST_TIME 48 9403
TEST_TIME 49 9600
TEST_TIME 50 9552
TEST_TIME 51 9487
TEST_TIME 52 9548
TEST_TIME 53 9655
TEST_TIME 54 9633
TEST_TIME 55 9604
TEST_TIME 56 9722
TEST_TIME 57 9655
TEST_TIME 58 9793
TEST_TIME 59 9791
TEST_TIME 60 9896
TEST_TIME 61 9806
TEST_TIME 62 9730
TEST_TIME 63 9696
TEST_TIME 64 9620
TEST_TIME 65 9599
TEST_TIME 66 9500
TEST_TIME 67 9542
TEST_TIME 68 9539
TEST_TIME 69 9534
TEST_TIME 70 9506
TEST_TIME 71 9510
TEST_TIME 72 9643
TEST_TIME 73 9581
TEST_TIME 74 9653
TEST_TIME 75 9691
TEST_TIME 76 9656
TEST_TIME 77 9589
TEST_TIME 78 9843
TEST_TIME 79 9801
TEST_TIME 80 9756
TEST_TIME 81 9655
TEST_TIME 82 9639
TEST_TIME 83 9590
TEST_TIME 84 9555
TEST_TIME 85 9498
TEST_TIME 86 9458
TEST_TIME 87 9400
TEST_TIME 88 9354
TEST_TIME 89 9440
TEST_TIME 90 9437
TEST_TIME 91 9490
TEST_TIME 92 9511
TEST_TIME 93 9516
TEST_TIME 94 9509
TEST_TIME 95 9565
TEST_TIME 96 9612
TEST_TIME 97 9628
TEST_TIME 98 9620
TEST_TIME 99 9668
TEST_TIME 100 9658
TEST_TIME 101 9700
TEST_TIME 102 9664
TEST_TIME 103 9589
TEST_TIME 104 9559
TEST_TIME 105 9520
TEST_TIME 106 9512
TEST_TIME 107 9491
TEST_TIME 108 9455
TEST_TIME 109 9415
TEST_TIME 110 9369
TEST_TIME 111 9410
TEST_TIME 112 9388
TEST_TIME 113 9395
TEST_TIME 114 9389
TEST_TIME 115 9359
TEST_TIME 116 9368
TEST_TIME 117 9438
TEST_TIME 118 9440
TEST_TIME 119 9444
TEST_TIME 120 9468

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-10-2022 16:39:38 to 22-10-2022 16:39:41.

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 1572440 1572440 0 0.00 0 20179 6 0 0
doc_list_biud 1572438 1572438 0 0.00 0 28486 24 0 0
sp_add_invoice_to_stock 5259 5091 168 3.19 36 168903 16923 0 168
sp_cancel_adding_invoice 5884 5884 0 0.00 3 41084 732 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 11592 346 0 0
sp_cancel_customer_reserve 575358 575358 0 0.00 0 28488 51 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 2 3947 64 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 3569 60 0 0
sp_cancel_supplier_invoice 6443 6428 15 0.23 1 15740 692 0 15
sp_cancel_supplier_order 443 443 0 0.00 9 1016650 84456 0 0
sp_cancel_write_off 10538 10538 0 0.00 1 13138 107 0 0
sp_client_order 21307 21307 0 0.00 3 30916 402 0 0
sp_customer_reserve 471166 471166 0 0.00 0 72892 169 0 0
sp_fill_shopping_cart 32627 32627 0 0.00 1 16860 37 0 0
sp_fill_shopping_cart_clo_res 471158 471158 0 0.00 0 28258 15 0 0
sp_get_clo_for_invoice 5083 5083 0 0.00 0 14630 264 0 0
sp_kill_cost_storno 17545 17545 0 0.00 0 7789 50 0 0
sp_kill_qstorno_ret_qs2qd 1168125 1168125 0 0.00 0 28448 28 0 0
sp_kill_qty_storno 615943 615943 0 0.00 0 28486 60 0 0
sp_lock_selected_doc 21413 21413 0 0.00 0 9521 4 0 0
sp_make_cost_storno 28692 28692 0 0.00 0 20048 51 0 0
sp_multiply_rows_for_pdistr 28692 28692 0 0.00 0 7252 26 0 0
sp_multiply_rows_for_qdistr 21307 21307 0 0.00 0 15099 188 0 0
sp_pay_from_customer 7165 7165 0 0.00 4 14935 105 0 0
sp_pay_to_supplier 6017 6017 0 0.00 5 20192 128 0 0
sp_payment_common 13182 13182 0 0.00 1 5450 24 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 11586 305 0 0
sp_qd_handle_on_invoice_upd_sts 10967 10967 0 0.00 0 24085 289 0 0
sp_qd_handle_on_reserve_upd_sts 10427 10427 0 0.00 0 12878 54 0 0
sp_reserve_write_off 10427 10427 0 0.00 7 12908 160 0 0
sp_supplier_invoice 5628 5628 0 0.00 6 92232 3380 0 0
sp_supplier_order 5711 5711 0 0.00 5 46860 1258 0 0
srv_aggregate_perf_data 101 101 0 0.00 2371 384984 22508 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1871 130878 45092 0 0
srv_find_qd_qs_mism 1087049 1087049 0 0.00 0 14383 5 0 0
srv_make_invnt_saldo 537 537 0 0.00 45 30999 1065 0 0
srv_make_money_saldo 784 784 0 0.00 1 3376 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 164700 508549 314876 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5091 5091 0 0.00 0 17688 162 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 0 10983 143 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 2354 10 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 1360 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 12798 32 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 509 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 692 10 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 24407 1310 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 1579 5 0 0
t$perf-norm:sp_client_order 21307 21307 0 0.00 0 5485 15 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 24756 28 0 0
t$perf-norm:sp_pay_from_customer 7165 7165 0 0.00 0 10962 13 0 0
t$perf-norm:sp_pay_to_supplier 6017 6017 0 0.00 0 12080 16 0 0
t$perf-norm:sp_reserve_write_off 10427 10427 0 0.00 0 6762 11 0 0
t$perf-norm:sp_supplier_invoice 5628 5628 0 0.00 0 10553 15 0 0
t$perf-norm:sp_supplier_order 5711 5711 0 0.00 0 26693 23 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 177 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 29 2 0 0
t$perf-norm:srv_make_invnt_saldo 537 537 0 0.00 0 3302 26 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 1723 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10502 10166 336 3.19 0 7180 21 0 336
v_all_customers 38226 38226 0 0.00 0 24 1 0 0
v_all_suppliers 31286 31286 0 0.00 0 30 1 0 0
v_all_wares 42614 42614 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 2376 8 0 0
v_cancel_client_order 3564 3564 0 0.00 0 5928 8 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 3938 9 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 1553 6 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 1691 7 0 0
v_cancel_supplier_invoice 816 786 30 3.67 0 3914 30 0 30
v_cancel_supplier_order 886 886 0 0.00 0 1395 5 0 0
v_cancel_write_off 1954 1954 0 0.00 0 7642 19 0 0
v_max_id_clo_ord 5703 5703 0 0.00 0 603 1 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 3851 25 0 0
v_max_id_ord_sup 5616 5616 0 0.00 0 4422 5 0 0
v_max_non_paid_invoice 1693 1693 0 0.00 0 1480 5 0 0
v_max_non_paid_realizn 3994 3994 0 0.00 0 2697 6 0 0
v_min_id_clo_ord 5703 5703 0 0.00 0 7705 9 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 3066 7 0 0
v_min_id_ord_sup 5616 5616 0 0.00 0 5269 10 0 0
v_min_non_paid_invoice 6017 6017 0 0.00 0 5445 16 0 0
v_min_non_paid_realizn 7165 7165 0 0.00 0 4869 18 0 0
v_our_firm 10730 10730 0 0.00 0 22 1 0 0
v_reserve_write_off 20854 20854 0 0.00 0 5932 6 0 0
x_lock_depdocs_on_canc_invoice 11919 11919 0 0.00 0 32511 121 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 21977 333 0 0
x_make_qstorno_1000_1200 5711 5711 0 0.00 1 46833 1183 0 0
x_make_qstorno_1200_2000 5628 5628 0 0.00 3 92204 3245 0 0
x_make_qstorno_2100_3300 471166 471166 0 0.00 0 72889 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2022 16:39:42 to 22-10-2022 16:39:46.

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
14:08:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:36 22424 8192000000 9913163888 10227236864 998590896 10.865 100 87 174 0 1027132320 6173280 22368144
14:11:05 20623 8192000000 9956212176 10281566208 1035889824 11.225 100 87 174 0 1066829136 8868384 22070928
14:12:31 14338 8192000000 9977429408 10317062144 1066228240 11.516 100 70 138 1 1092578000 9545904 16803856
14:13:50 10880 8192000000 10042264976 10390175744 1128999616 12.112 100 84 166 0 1164220624 15378528 19842480
14:15:05 6475 8192000000 10085092128 10441211904 1166515088 12.464 100 83 166 0 1198906592 13668896 18722608
14:16:12 3749 8192000000 10123339872 10493870080 1211721568 12.885 100 77 151 0 1241346800 12619792 17005440
14:17:18 4344 8192000000 10160342976 10532020224 1244877184 13.191 100 82 164 0 1277275328 14349488 18048656
14:18:24 1786 8192000000 10184866368 10562179072 1270172112 13.423 100 92 183 0 1304047840 13740880 20134848
14:19:27 998 8192000000 10213419120 10603343872 1300504048 13.700 100 85 169 1 1331021392 11699216 18818128
14:20:29 25778 8192000000 10203450400 10608730112 1310046112 13.786 100 54 107 1 1328332928 6140912 12145904
14:21:56 7087 8192000000 10211101088 10624380928 1319814464 13.875 100 32 61 0 1332643472 6430688 6398320
14:23:04 14624 8192000000 10210710848 10634842112 1330494304 13.972 100 15 30 0 1339247136 5619744 3133088
14:24:21 8687 8192000000 10222270144 10643767296 1336418800 14.025 100 16 32 0 1346095984 6271472 3405712
14:25:36 56180 8192000000 10243964624 10654715904 1336875120 14.029 100 42 87 0 1353084400 5582640 10626640
14:27:33 7000 8192000000 10214013136 10629902336 1321133792 13.887 99 14 28 0 1328983408 4802080 3047536
14:28:40 9568 8192000000 10190208272 10603040768 1273446240 13.453 97 13 26 0 1281525312 5302848 2776224
14:29:52 61953 8192000000 10157538608 10562088960 1205280704 12.825 94 28 56 0 1217089936 4837728 6971504
14:31:55 5571 8192000000 10124143184 10521935872 1195932864 12.739 96 23 46 0 1206686992 5681712 5072416
14:33:02 10898 8192000000 10169375840 10567974912 1258908384 13.320 99 23 47 0 1269704848 5397472 5398992
14:34:14 5877 8192000000 10166691904 10574925824 1269094912 13.413 99 21 44 0 1279368560 5449168 4824480
14:35:26 20144 8192000000 10171747968 10588721152 1280598704 13.518 99 16 31 0 1289310832 5160272 3551856
14:36:47 5378 8192000000 10182487568 10600861696 1296095888 13.660 100 26 52 0 1308570144 6185488 6288768
14:37:53 8905 8192000000 10191007632 10609090560 1305584400 13.746 100 19 38 0 1315393504 5393872 4415232
14:39:07 29618 8192000000 10245373520 10660147200 1339460784 14.053 100 45 90 1 1357125504 7722400 9942320
14:40:41 22380 8192000000 10254966000 10677276672 1354029408 14.184 100 39 78 1 1371108624 8510544 8568672
14:42:19 13853 8192000000 10281628144 10704879616 1376280944 14.383 100 46 89 1 1396272304 9959872 10031488
14:43:36 17777 8192000000 10296965488 10718978048 1374190000 14.365 99 45 90 0 1400822960 15843552 10789408
14:45:01 7104 8192000000 10303042112 10727600128 1389579104 14.502 100 46 90 0 1420294384 19311184 11404096
14:46:12 29230 8192000000 10317937504 10743214080 1381013360 14.426 98 41 82 0 1412036304 20971184 10051760
14:47:48 18157 8192000000 10325491760 10755194880 1405686080 14.646 100 43 84 0 1440772160 24408208 10677872
14:49:20 1871 8192000000 10340677584 10764320768 1412470368 14.706 100 53 103 0 1451191792 26284224 12437200
14:50:29 6386 8192000000 10355169616 10776678400 1414260464 14.722 100 57 114 0 1462381296 34524288 13596544
14:51:40 3206 8192000000 10337502176 10750754816 1393784224 14.540 99 70 139 2 1445133088 34934400 16414464
14:52:51 1893 8192000000 10348001088 10754621440 1390001568 14.506 99 76 151 1 1447630128 39748192 17880368
14:53:58 9745 8192000000 10344669744 10759352320 1393541856 14.537 100 69 134 0 1453914768 43958960 16413952
14:55:12 7648 8192000000 10325312496 10745565184 1382606384 14.440 100 52 102 0 1437907136 42239200 13061552
14:56:31 3044 8192000000 10340737888 10750808064 1381277456 14.428 100 69 138 1 1443674480 45670432 16726592
14:57:40 9862 8192000000 10291858416 10713063424 1347789312 14.128 98 36 72 0 1393210768 35365536 10055920
14:58:56 6690 8192000000 10287222272 10704314368 1337043168 14.031 99 37 73 0 1382982432 36856560 9082704
15:00:15 9347 8192000000 10300042576 10716745728 1350217904 14.149 99 49 96 0 1396118112 32824720 13075488
15:01:26 12421 8192000000 10285610496 10716573696 1357553968 14.215 100 29 58 0 1407607696 41223696 8830032
15:02:41 29866 8192000000 10275353856 10716717056 1346676160 14.118 99 21 42 0 1380608784 27996512 5936112
15:04:32 42008 8192000000 10244422016 10681012224 1285242608 13.561 95 8 17 0 1296484400 9298992 1942800
15:06:14 48800 8192000000 10283541216 10715652096 1351157280 14.158 99 37 72 0 1373671776 14303552 8210944
15:08:06 64741 8192000000 10261903552 10704973824 1335756576 14.019 98 21 43 1 1359938528 19767664 4414288
15:10:13 52179 8192000000 10279659904 10704445440 1351056176 14.157 99 40 78 1 1365340096 4790944 9492976
15:12:06 68688 8192000000 10285086608 10734002176 1385577616 14.466 100 32 64 0 1403826928 10806064 7443248
15:14:18 46735 8192000000 10305404848 10741673984 1391632416 14.520 100 46 94 1 1410195328 7446960 11115952
15:16:06 9616 8192000000 10298800896 10742927360 1392646512 14.529 100 34 68 1 1413566800 12519920 8400368
15:17:22 38187 8192000000 10293531696 10750500864 1382280608 14.437 99 17 34 0 1404833952 18451952 4101392
15:19:08 26990 8192000000 10299961824 10751954944 1387657584 14.485 99 11 22 0 1404674688 14325920 2691184
15:20:35 71379 8192000000 10292754656 10752991232 1394026976 14.542 100 24 48 1 1423506288 23437440 6041872
15:22:48 45287 8192000000 10290573968 10754875392 1383537344 14.448 99 27 54 0 1404304000 14062256 6704400
15:24:42 28768 8192000000 10274471872 10729541632 1369260848 14.320 99 17 34 0 1379577984 6648512 3668624
15:26:19 61335 8192000000 10287572192 10753593344 1378976176 14.407 99 17 31 1 1397947168 15034480 3936512
15:28:22 27754 8192000000 10295923840 10748952576 1386821552 14.477 99 19 39 0 1399991648 8724800 4445296
15:29:57 84119 8192000000 10295854224 10756112384 1381103744 14.426 98 18 36 0 1397068784 11747568 4217472
15:32:22 21936 8192000000 10302739024 10759987200 1387806368 14.486 99 41 82 0 1406569136 9980320 8782448
15:33:44 63758 8192000000 10301835120 10764120064 1346134688 14.113 96 25 50 0 1368804144 17307088 5362368
15:35:58 38847 8192000000 10308760432 10757316608 1395131168 14.552 100 28 56 0 1418998336 16525808 7341360
15:37:37 19374 8192000000 10279215088 10715176960 1314746688 13.829 95 36 72 0 1333674752 10842784 8085280
15:39:01 125305 8192000000 10300947984 10760318976 1364039584 14.274 99 24 49 0 1391327232 21891248 5396400
15:42:19 17583 8192000000 10262179344 10711142400 1347226464 14.123 99 24 48 0 1365791664 13300992 5264208
15:43:37 54928 8192000000 10261995824 10719477760 1350214128 14.149 98 11 22 0 1362013376 9485648 2313600
15:46:18 77044 8192000000 10275402544 10723553280 1268712128 13.410 92 11 22 1 1285846592 15132160 2002304
15:48:35 92589 8192000000 10290203136 10744254464 1342675680 14.082 97 19 38 0 1368462128 21175728 4610720
15:51:18 130878 8192000000 10276751840 10728804352 1319006272 13.868 97 11 22 0 1343380032 21930048 2443712
15:55:21 23894 8192000000 10303568512 10739433472 1368849680 14.317 99 49 98 1 1398321024 17246992 12224352
15:56:51 62834 8192000000 10270487408 10721255424 1343684768 14.091 98 4 8 0 1361199104 16805728 708608
15:58:55 97989 8192000000 10283082384 10720792576 1349025952 14.139 98 38 76 1 1365879120 8352736 8500432
16:01:35 8816 8192000000 10284994688 10726404096 1364074736 14.274 99 32 64 0 1384318480 13670880 6572864
16:02:45 50325 8192000000 10283943600 10744541184 1386812304 14.477 100 19 38 1 1403331888 12563520 3956064
16:05:18 87588 8192000000 10300827056 10760851456 1379273264 14.410 99 11 20 0 1399685648 18104064 2308320
16:07:51 56493 8192000000 10298651008 10768592896 1394473760 14.546 99 14 28 0 1415616896 18163952 2979184
16:10:04 75627 8192000000 10307563952 10768424960 1354060800 14.184 96 14 27 0 1365725456 8814272 2850384
16:12:20 44632 8192000000 10311983808 10762002432 1390484240 14.510 99 22 42 0 1407607472 12941392 4181840
16:14:04 56018 8192000000 10296741328 10753400832 1359452736 14.232 97 15 30 0 1374834560 11411488 3970336
16:16:01 48909 8192000000 10306954880 10759020544 1351966256 14.165 97 19 38 0 1369304256 13146560 4191440
16:17:53 80232 8192000000 10306541184 10747588608 1339142096 14.050 96 27 52 1 1358155264 12782400 6230768
16:20:14 79438 8192000000 10267955968 10715467776 1347035568 14.121 97 15 30 0 1362917840 12139712 3742560
16:23:51 44926 8192000000 10288185552 10748940288 1384591504 14.458 100 27 52 0 1402904864 12584240 5729120
16:25:38 93668 8192000000 10276425488 10732163072 1356862400 14.209 97 15 30 1 1370377456 9531536 3983520
16:28:12 67444 8192000000 10264354576 10725732352 1340144016 14.059 97 13 26 0 1356577056 12643504 3789536
16:30:21 46231 8192000000 10262717296 10715779072 1366112112 14.292 100 9 17 0 1374417088 6595712 1709264
16:32:08 59466 8192000000 10259519904 10697031680 1247833808 13.218 90 28 55 0 1265489792 11850544 5805440
16:34:11 78594 8192000000 10272716576 10734157824 1337189824 14.032 98 30 60 0 1366079488 22401632 6488032
16:36:30 123729 8192000000 10169499472 10617368576 1201148320 12.787 88 18 36 0 1217995424 12652272 4194832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-10-2022 16:39:48 to 22-10-2022 16:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-10-2022 16:40:04 to 22-10-2022 16:40:06.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          244866
MON$OLDEST_ACTIVE               245484
MON$OLDEST_SNAPSHOT             245484
MON$NEXT_TRANSACTION            245484
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2025824
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:66: 
:67: mscs-test-161.budzdorov.ru    Sat Oct 22 16:38:54 2022
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:69: 
:70: 
:71: mscs-test-161.budzdorov.ru    Sat Oct 22 16:38:54 2022
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
--- end of diff output ---

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

22.10.22 16:40:07. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-10-22 16:40:10 No severe PSQL-related problems occured
22.10.22 16:40:11. End of report.