Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.2022 22: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 15274208 kB
MemAvailable 30510448 kB
Buffers 0 kB
Cached 16462988 kB
SwapCached 2096 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-19 22:38:30
DTS_END                         2022-10-19 22:38:30.6590
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/37104
TRN_ID                          241036
ATT_ID                          716
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: 9640.54 20065 1156865
customer order: creation 169.74 375 20369
customer order: refuse 14.57 333 1748
order to supplier: creation 45.67 1308 5480
order to supplier: removal 3.54 98253 425
invoice (draft): creation 46.05 3337 5526
invoice (draft): removal 55.47 762 6656
invoice accept: apply 41.88 16102 5025
invoice accept: cancel 50.60 694 6072
customer reserve: creation 3887.92 158 466550
customer reserve: removal 5025.58 54 603070
realization accept: apply 81.24 171 9749
realization accept: cancel 90.03 93 10804
payment from customer: creation 58.90 110 7068
payment from customer: removal 5.59 67 671
payment to supplier: creation 48.79 130 5855
payment to supplier: removal 3.26 51 391
service: total inventory turnovers 4.41 1301 529
service: total monetary turnovers 6.52 26 782
service: aggregate perf. data 0.76 22494 91
service: refresh index statistics 0.03 255479 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-10-2022 22:40:19 to 19-10-2022 22:40:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8017
TEST_TIME 2 5135
TEST_TIME 3 5360
TEST_TIME 4 5075
TEST_TIME 5 5417
TEST_TIME 6 5841
TEST_TIME 7 6508
TEST_TIME 8 6487
TEST_TIME 9 6246
TEST_TIME 10 6381
TEST_TIME 11 6286
TEST_TIME 12 6359
TEST_TIME 13 6602
TEST_TIME 14 6568
TEST_TIME 15 6512
TEST_TIME 16 7036
TEST_TIME 17 7027
TEST_TIME 18 6971
TEST_TIME 19 7355
TEST_TIME 20 7634
TEST_TIME 21 7919
TEST_TIME 22 8033
TEST_TIME 23 8236
TEST_TIME 24 8328
TEST_TIME 25 8243
TEST_TIME 26 8231
TEST_TIME 27 8927
TEST_TIME 28 9602
TEST_TIME 29 9897
TEST_TIME 30 10327
TEST_TIME 31 10697
TEST_TIME 32 10698
TEST_TIME 33 10855
TEST_TIME 34 10813
TEST_TIME 35 10831
TEST_TIME 36 10692
TEST_TIME 37 10438
TEST_TIME 38 10401
TEST_TIME 39 10462
TEST_TIME 40 10405
TEST_TIME 41 10189
TEST_TIME 42 10299
TEST_TIME 43 10206
TEST_TIME 44 10219
TEST_TIME 45 10159
TEST_TIME 46 10120
TEST_TIME 47 10003
TEST_TIME 48 9938
TEST_TIME 49 9920
TEST_TIME 50 9847
TEST_TIME 51 9754
TEST_TIME 52 9839
TEST_TIME 53 9739
TEST_TIME 54 9768
TEST_TIME 55 9718
TEST_TIME 56 9765
TEST_TIME 57 9674
TEST_TIME 58 9742
TEST_TIME 59 9947
TEST_TIME 60 9860
TEST_TIME 61 9959
TEST_TIME 62 10105
TEST_TIME 63 10129
TEST_TIME 64 10122
TEST_TIME 65 10148
TEST_TIME 66 10232
TEST_TIME 67 10134
TEST_TIME 68 10025
TEST_TIME 69 10100
TEST_TIME 70 10033
TEST_TIME 71 9992
TEST_TIME 72 9942
TEST_TIME 73 9881
TEST_TIME 74 9875
TEST_TIME 75 9764
TEST_TIME 76 9706
TEST_TIME 77 9657
TEST_TIME 78 9613
TEST_TIME 79 9629
TEST_TIME 80 9572
TEST_TIME 81 9592
TEST_TIME 82 9597
TEST_TIME 83 9663
TEST_TIME 84 9604
TEST_TIME 85 9672
TEST_TIME 86 9721
TEST_TIME 87 9694
TEST_TIME 88 9717
TEST_TIME 89 9732
TEST_TIME 90 9747
TEST_TIME 91 9808
TEST_TIME 92 9818
TEST_TIME 93 9892
TEST_TIME 94 9803
TEST_TIME 95 9753
TEST_TIME 96 9726
TEST_TIME 97 9714
TEST_TIME 98 9688
TEST_TIME 99 9673
TEST_TIME 100 9634
TEST_TIME 101 9612
TEST_TIME 102 9597
TEST_TIME 103 9619
TEST_TIME 104 9751
TEST_TIME 105 9753
TEST_TIME 106 9745
TEST_TIME 107 9720
TEST_TIME 108 9723
TEST_TIME 109 9751
TEST_TIME 110 9828
TEST_TIME 111 9849
TEST_TIME 112 9857
TEST_TIME 113 9841
TEST_TIME 114 9780
TEST_TIME 115 9756
TEST_TIME 116 9723
TEST_TIME 117 9693
TEST_TIME 118 9760
TEST_TIME 119 9712
TEST_TIME 120 9646

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-10-2022 22:40:23 to 19-10-2022 22:40:26.

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 1588645 1588645 0 0.00 0 21152 5 0 0
doc_list_biud 1588645 1588645 0 0.00 0 64550 26 0 0
sp_add_invoice_to_stock 5151 5025 126 2.44 19 168922 16103 0 126
sp_cancel_adding_invoice 6072 6072 0 0.00 4 83363 695 0 0
sp_cancel_client_order 1748 1748 0 0.00 3 15699 333 0 0
sp_cancel_customer_reserve 603070 603070 0 0.00 0 28667 55 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 2 1700 68 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 1589 52 0 0
sp_cancel_supplier_invoice 6666 6656 10 0.15 2 64625 763 0 10
sp_cancel_supplier_order 425 425 0 0.00 8 1155524 98254 0 0
sp_cancel_write_off 10804 10804 0 0.00 1 8657 93 0 0
sp_client_order 20369 20369 0 0.00 3 47721 376 0 0
sp_customer_reserve 466550 466550 0 0.00 0 113597 159 0 0
sp_fill_shopping_cart 31363 31363 0 0.00 1 15500 41 0 0
sp_fill_shopping_cart_clo_res 466542 466542 0 0.00 0 20272 13 0 0
sp_get_clo_for_invoice 5017 5017 0 0.00 0 15486 322 0 0
sp_kill_cost_storno 17938 17938 0 0.00 0 8654 49 0 0
sp_kill_qstorno_ret_qs2qd 1224025 1224025 0 0.00 0 64548 30 0 0
sp_kill_qty_storno 643540 643540 0 0.00 0 64549 63 0 0
sp_lock_selected_doc 20447 20447 0 0.00 0 7587 5 0 0
sp_make_cost_storno 27688 27688 0 0.00 0 12228 55 0 0
sp_multiply_rows_for_pdistr 27688 27688 0 0.00 0 8305 28 0 0
sp_multiply_rows_for_qdistr 20369 20369 0 0.00 0 47712 178 0 0
sp_pay_from_customer 7068 7068 0 0.00 4 12225 111 0 0
sp_pay_to_supplier 5855 5855 0 0.00 5 11917 130 0 0
sp_payment_common 12923 12923 0 0.00 1 12214 24 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 15692 299 0 0
sp_qd_handle_on_invoice_upd_sts 11088 11088 0 0.00 0 14422 276 0 0
sp_qd_handle_on_reserve_upd_sts 9749 9749 0 0.00 0 13674 52 0 0
sp_reserve_write_off 9749 9749 0 0.00 6 13697 171 0 0
sp_supplier_invoice 5526 5526 0 0.00 7 90586 3338 0 0
sp_supplier_order 5480 5480 0 0.00 4 83354 1309 0 0
srv_aggregate_perf_data 91 91 0 0.00 2509 159689 22494 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1508 112370 45535 0 0
srv_find_qd_qs_mism 1109106 1109106 0 0.00 0 21152 4 0 0
srv_make_invnt_saldo 529 529 0 0.00 60 100049 1302 0 0
srv_make_money_saldo 782 782 0 0.00 1 2679 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 182924 353276 255479 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 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 5025 5025 0 0.00 0 18025 186 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 9566 115 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 1444 11 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 1494 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 2980 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 797 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 1187 13 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 28082 1841 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 7354 20 0 0
t$perf-norm:sp_client_order 20369 20369 0 0.00 0 11942 15 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 983 6 0 0
t$perf-norm:sp_pay_from_customer 7068 7068 0 0.00 0 8189 11 0 0
t$perf-norm:sp_pay_to_supplier 5855 5855 0 0.00 0 9317 12 0 0
t$perf-norm:sp_reserve_write_off 9749 9749 0 0.00 0 5288 12 0 0
t$perf-norm:sp_supplier_invoice 5526 5526 0 0.00 0 8803 17 0 0
t$perf-norm:sp_supplier_order 5480 5480 0 0.00 0 4069 16 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 278 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 278 5 0 0
t$perf-norm:srv_make_invnt_saldo 529 529 0 0.00 0 3382 29 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 1277 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 83 24 0 0
v_add_invoice_to_stock 10284 10032 252 2.45 0 6151 19 0 252
v_all_customers 37734 37734 0 0.00 0 17 1 0 0
v_all_suppliers 30638 30638 0 0.00 0 39 1 0 0
v_all_wares 40734 40734 0 0.00 0 52 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 4324 25 0 0
v_cancel_client_order 3496 3496 0 0.00 0 6008 9 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 860 4 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 1612 5 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 431 3 0 0
v_cancel_supplier_invoice 812 792 20 2.46 0 3930 24 0 20
v_cancel_supplier_order 850 850 0 0.00 0 1625 8 0 0
v_cancel_write_off 2006 2006 0 0.00 0 4984 16 0 0
v_max_id_clo_ord 5480 5480 0 0.00 0 6145 2 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 3614 20 0 0
v_max_id_ord_sup 5516 5516 0 0.00 0 4673 5 0 0
v_max_non_paid_invoice 1532 1532 0 0.00 0 2521 5 0 0
v_max_non_paid_realizn 3538 3538 0 0.00 0 5511 6 0 0
v_min_id_clo_ord 5480 5480 0 0.00 0 4693 9 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 870 4 0 0
v_min_id_ord_sup 5516 5516 0 0.00 0 14386 16 0 0
v_min_non_paid_invoice 5855 5855 0 0.00 0 5243 15 0 0
v_min_non_paid_realizn 7068 7068 0 0.00 0 12206 18 0 0
v_our_firm 10060 10060 0 0.00 0 14 1 0 0
v_reserve_write_off 19498 19498 0 0.00 0 6248 8 0 0
x_lock_depdocs_on_canc_invoice 12332 12332 0 0.00 0 22232 107 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 10872 332 0 0
x_make_qstorno_1000_1200 5480 5480 0 0.00 1 83317 1229 0 0
x_make_qstorno_1200_2000 5526 5526 0 0.00 3 90531 3184 0 0
x_make_qstorno_2100_3300 466550 466550 0 0.00 0 113595 143 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 19-10-2022 22:40:27 to 19-10-2022 22:40:32.

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
20:08:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:35 24580 8192000000 9903483424 10217332736 989676592 10.778 100 82 164 0 1015991920 5572704 20742624
20:11:10 21869 8192000000 9956680720 10280067072 1039391872 11.259 100 89 179 0 1070415600 8450128 22573600
20:12:37 17299 8192000000 10000623872 10335633408 1083145984 11.677 100 86 172 0 1114261280 10274288 20841008
20:14:05 6603 8192000000 10035905744 10383622144 1124298448 12.068 100 82 164 0 1155943488 12273728 19371312
20:15:16 6843 8192000000 10068848944 10430525440 1163057632 12.432 100 75 147 0 1191390336 12092032 16240672
20:16:25 2783 8192000000 10118350928 10485960704 1210057424 12.870 100 76 153 0 1240143472 13258128 16827920
20:17:30 1811 8192000000 10149112224 10526769152 1241898752 13.164 100 83 165 1 1274037184 13441728 18696704
20:18:34 1034 8192000000 10192375504 10569601024 1272420096 13.444 100 87 174 0 1305332512 13483328 19429088
20:19:36 687 8192000000 10208783632 10598322176 1301173904 13.706 100 78 158 2 1330957024 12502352 17280768
20:20:39 43441 8192000000 10191423472 10608148480 1309066480 13.778 100 34 69 1 1323150608 5914880 8169248
20:22:24 3849 8192000000 10215941408 10627559424 1323170016 13.905 100 28 58 1 1336540784 6732272 6638496
20:23:30 7358 8192000000 10219323760 10637271040 1331222848 13.978 100 27 54 1 1344059360 6524112 6312400
20:24:38 6507 8192000000 10224005024 10644738048 1337617008 14.036 100 24 46 0 1349258336 6784128 4857200
20:25:45 25442 8192000000 10250602784 10656821248 1342932416 14.084 100 60 119 1 1362994720 6101600 13960704
20:27:12 4800 8192000000 10238685648 10658246656 1346956640 14.120 100 28 56 0 1360066208 6147824 6961744
20:28:17 7640 8192000000 10223742256 10642059264 1314888368 13.830 98 27 56 1 1326459344 5396480 6174496
20:29:25 7695 8192000000 10163117200 10561945600 1253691440 13.272 97 28 52 0 1266105232 5737136 6676656
20:30:33 13631 8192000000 10153492096 10557902848 1217809856 12.941 95 17 32 1 1226415616 4873808 3731952
20:31:49 6260 8192000000 10138417312 10533822464 1225361296 13.011 98 34 68 0 1239389888 5660240 8368352
20:32:56 6357 8192000000 10150028192 10555142144 1262830528 13.356 100 30 60 0 1276352256 6423392 7098336
20:34:04 57970 8192000000 10174569984 10586071040 1284449456 13.554 100 36 73 2 1298689952 5735008 8505488
20:36:09 4808 8192000000 10193409104 10603364352 1300372160 13.699 100 26 52 2 1311538816 5583280 5583376
20:37:15 12580 8192000000 10191175216 10608345088 1305732560 13.747 100 16 32 0 1315001152 5655312 3613280
20:38:30 17604 8192000000 10227137936 10639450112 1328794896 13.956 100 18 35 1 1337205840 4958464 3452480
20:39:58 96690 8192000000 10246825824 10677571584 1353486128 14.179 100 38 76 0 1372307088 9678864 9142096
20:42:40 41702 8192000000 10277280384 10701099008 1372355472 14.348 100 43 84 1 1394122880 11612400 10155008
20:44:32 31058 8192000000 10294172080 10718490624 1384268592 14.455 100 43 86 0 1407849056 13806592 9773872
20:46:10 7661 8192000000 10308965280 10729132032 1389158384 14.498 100 65 128 0 1417388400 13890464 14339552
20:47:24 27679 8192000000 10321458928 10752258048 1406500144 14.653 100 47 92 1 1438137216 20293648 11343424
20:48:55 9030 8192000000 10331234944 10761363456 1416462208 14.741 100 46 92 0 1446537824 18814288 11261328
20:50:09 3388 8192000000 10339695984 10753081344 1408314192 14.669 100 69 136 3 1451845216 26827136 16703888
20:51:17 10782 8192000000 10309890336 10732195840 1375371264 14.375 99 45 91 0 1414655344 28016496 11267584
20:52:33 8143 8192000000 10311228112 10730852352 1369584400 14.323 98 50 100 1 1411389504 29171312 12633792
20:53:45 5217 8192000000 10334515760 10748764160 1389239248 14.499 100 67 133 0 1442042048 36499184 16303616
20:55:00 6309 8192000000 10304928224 10708299776 1353864416 14.182 99 78 154 0 1405477808 32297920 19315472
20:56:15 19818 8192000000 10319689600 10737860608 1371561552 14.341 99 43 86 0 1421848624 38765728 11521344
20:57:43 2319 8192000000 10311983200 10723258368 1364210976 14.275 100 54 108 1 1416256224 38636416 13408832
20:58:51 2912 8192000000 10320270736 10727161856 1361311136 14.249 100 72 143 1 1422764064 43528000 17924928
20:59:57 9419 8192000000 10300228176 10715066368 1335761696 14.019 97 49 98 0 1389685648 41120208 12803744
21:01:14 1507 8192000000 10338012272 10746114048 1376058256 14.381 100 68 134 0 1436750032 43240480 17451296
21:02:17 10297 8192000000 10338798576 10759454720 1391583472 14.520 100 45 90 1 1447537600 43586048 12368080
21:03:30 22800 8192000000 10345810320 10777640960 1390675584 14.512 100 36 72 0 1452206480 50799664 10731232
21:05:00 7246 8192000000 10365579584 10800533504 1389296640 14.500 99 37 72 0 1464596144 64837968 10461536
21:06:17 36419 8192000000 10331635568 10777985024 1390223264 14.508 100 20 40 1 1447503792 51099232 6181296
21:07:59 16856 8192000000 10315450016 10770448384 1379932912 14.416 100 19 38 0 1430023424 44929584 5160928
21:09:56 22361 8192000000 10293903136 10735316992 1378269712 14.401 100 20 40 1 1395228160 12705520 4252928
21:11:19 86292 8192000000 10288767808 10727489536 1356877040 14.209 98 15 29 1 1372242256 12113744 3251472
21:13:47 112370 8192000000 10295669488 10735169536 1351161424 14.158 97 34 69 0 1376610176 16865136 8583616
21:16:57 107945 8192000000 10308967136 10759667712 1370922096 14.335 98 19 38 0 1391763968 16313184 4528688
21:19:48 70033 8192000000 10310839760 10765389824 1395844448 14.558 100 15 30 1 1416846944 17664768 3337728
21:22:13 21439 8192000000 10322616592 10767282176 1407653040 14.663 100 50 105 3 1428919824 9422720 11844064
21:23:39 80270 8192000000 10310993264 10781143040 1401540192 14.609 99 15 30 0 1415584848 10777120 3267536
21:26:00 30879 8192000000 10323934336 10786578432 1416901856 14.745 100 11 22 0 1435481072 16058288 2520928
21:27:33 53166 8192000000 10315984192 10777288704 1400354672 14.598 99 13 26 0 1422473808 18815904 3303232
21:30:12 52424 8192000000 10310796176 10783346688 1417112624 14.747 100 12 24 0 1427266944 7325248 2829072
21:32:05 103430 8192000000 10303302800 10765275136 1339637728 14.054 95 14 28 0 1360432784 16604816 4190240
21:34:50 72698 8192000000 10330150528 10793926656 1396563504 14.564 99 14 28 0 1421434336 21507536 3363296
21:37:36 74082 8192000000 10317629232 10772164608 1356976928 14.210 96 15 28 1 1377834896 17647760 3210208
21:39:50 48552 8192000000 10302613792 10756153344 1360670112 14.243 98 12 24 0 1390088240 26304080 3114048
21:41:44 67243 8192000000 10275544944 10704605184 1263120048 13.359 92 28 54 0 1280953248 11555104 6278096
21:43:52 45629 8192000000 10279125472 10731388928 1323726384 13.910 97 22 45 0 1345308672 16345248 5237040
21:45:44 78816 8192000000 10269266800 10705981440 1315946720 13.840 97 31 62 0 1330459312 7984976 6527616
21:50:35 62322 8192000000 10257753408 10703372288 1338906448 14.048 98 10 20 0 1349219152 7844752 2467952
21:52:38 74645 8192000000 10244020240 10702135296 1319295216 13.870 97 13 28 1 1329460656 7201152 2964288
21:54:54 42427 8192000000 10251511504 10702651392 1350167024 14.149 100 29 58 0 1365476240 8843072 6466144
21:56:37 48460 8192000000 10258621328 10705063936 1331407136 13.980 98 22 45 1 1347956288 11369280 5179872
21:58:26 63538 8192000000 10260369952 10721026048 1348867008 14.137 99 11 22 0 1363127088 11728016 2532064
22:00:38 45799 8192000000 10284240000 10734333952 1349853936 14.146 98 24 46 0 1366155264 11177504 5123824
22:02:25 60449 8192000000 10290267232 10750058496 1366546480 14.296 99 30 60 0 1391347536 17995200 6805856
22:04:37 32916 8192000000 10282429120 10725322752 1358995136 14.228 99 28 56 0 1374384352 9276256 6112960
22:06:13 45856 8192000000 10286699280 10729181184 1359141056 14.230 99 31 62 0 1378497840 12540384 6816400
22:08:25 67676 8192000000 10282953728 10738331648 1337306816 14.033 96 9 18 0 1348654480 9484688 1862976
22:10:33 82481 8192000000 10305781456 10771185664 1385352656 14.464 100 16 32 0 1402984016 14409168 3222192
22:12:57 54908 8192000000 10295153856 10749128704 1384005376 14.452 100 17 35 0 1396053920 8590176 3458368
22:14:52 71384 8192000000 10284922848 10757550080 1375279648 14.374 99 9 18 0 1390384512 12968400 2136464
22:17:08 46475 8192000000 10307834944 10767159296 1397750656 14.575 100 18 36 0 1408217840 6685472 3781712
22:18:55 44635 8192000000 10301971824 10755358720 1358166608 14.221 96 23 46 0 1380789120 17043696 5578816
22:20:55 50900 8192000000 10309461488 10770305024 1361740672 14.253 97 22 44 0 1385094912 17962656 5391584
22:22:49 59692 8192000000 10290345888 10751606784 1348640176 14.135 97 10 20 0 1363170656 12538160 1992320
22:24:55 36659 8192000000 10299836048 10737172480 1338853888 14.047 97 30 60 0 1354018608 8326592 6838128
22:26:37 41048 8192000000 10298324512 10749046784 1368904256 14.317 100 27 54 1 1389339936 14418880 6016800
22:29:02 78100 8192000000 10259155808 10708942848 1318934176 13.867 96 19 38 0 1339389216 16132528 4322512
22:31:21 47452 8192000000 10265510048 10721730560 1354860528 14.191 99 20 40 0 1368000160 9254016 3885616
22:33:08 84460 8192000000 10260878144 10708357120 1306172336 13.751 95 30 60 1 1322879600 10385568 6321696
22:35:36 57484 8192000000 10235258304 10703101952 1279694240 13.510 94 35 68 0 1301377808 13652032 8031536
22:37:39 46252 8192000000 10244653824 10699804672 1329241024 13.960 99 10 20 0 1343884800 12729936 1913840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-10-2022 22:40:34 to 19-10-2022 22:40:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 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:01, from 19-10-2022 22:40:50 to 19-10-2022 22:40:51.

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          239868
MON$OLDEST_ACTIVE               241259
MON$OLDEST_SNAPSHOT             241259
MON$NEXT_TRANSACTION            241259
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                       2051424
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.33635 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33635 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33635 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33635 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 ---
:184: 
:185: mscs-test-161.budzdorov.ru    Wed Oct 19 22:38:34 2022
:186:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:187: 
:188: 
:189: mscs-test-161.budzdorov.ru    Wed Oct 19 22:38:52 2022
:190:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:191: 
--- end of diff output ---

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

19.10.22 22:40:53. 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-19 22:40:55 No severe PSQL-related problems occured
19.10.22 22:40:57. End of report.