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

Server and database settinfs

SERVER_MODE                     SuperServer 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 2023-01-17 22:38:37
DTS_END                         2023-01-17 22:38:37.2260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37392
TRN_ID                          239272
ATT_ID                          624
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: 9040.43 19850 1084852
customer order: creation 172.62 354 20714
customer order: refuse 14.48 314 1737
order to supplier: creation 44.40 1211 5328
order to supplier: removal 3.48 90789 417
invoice (draft): creation 46.43 3209 5571
invoice (draft): removal 50.74 800 6089
invoice accept: apply 42.20 16175 5064
invoice accept: cancel 45.85 764 5502
customer reserve: creation 3842.12 161 461054
customer reserve: removal 4485.81 55 538297
realization accept: apply 82.83 198 9940
realization accept: cancel 79.83 114 9579
payment from customer: creation 59.63 115 7155
payment from customer: removal 5.61 68 673
payment to supplier: creation 49.34 137 5921
payment to supplier: removal 3.28 248 394
service: total inventory turnovers 4.47 1182 537
service: total monetary turnovers 6.40 20 768
service: aggregate perf. data 0.90 18123 108
service: refresh index statistics 0.03 262963 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-01-2023 22:39:56 to 17-01-2023 22:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2619
TEST_TIME 2 3131
TEST_TIME 3 4135
TEST_TIME 4 4869
TEST_TIME 5 4908
TEST_TIME 6 4425
TEST_TIME 7 4491
TEST_TIME 8 4492
TEST_TIME 9 5252
TEST_TIME 10 5459
TEST_TIME 11 5851
TEST_TIME 12 5789
TEST_TIME 13 6147
TEST_TIME 14 6379
TEST_TIME 15 6621
TEST_TIME 16 6714
TEST_TIME 17 7309
TEST_TIME 18 7813
TEST_TIME 19 7936
TEST_TIME 20 7985
TEST_TIME 21 8653
TEST_TIME 22 9177
TEST_TIME 23 9770
TEST_TIME 24 9746
TEST_TIME 25 10382
TEST_TIME 26 10389
TEST_TIME 27 10086
TEST_TIME 28 9854
TEST_TIME 29 9900
TEST_TIME 30 9992
TEST_TIME 31 9962
TEST_TIME 32 9914
TEST_TIME 33 9759
TEST_TIME 34 9706
TEST_TIME 35 9596
TEST_TIME 36 9554
TEST_TIME 37 9401
TEST_TIME 38 9467
TEST_TIME 39 9457
TEST_TIME 40 9251
TEST_TIME 41 9201
TEST_TIME 42 9186
TEST_TIME 43 9157
TEST_TIME 44 9042
TEST_TIME 45 8974
TEST_TIME 46 9008
TEST_TIME 47 9038
TEST_TIME 48 9022
TEST_TIME 49 8960
TEST_TIME 50 9108
TEST_TIME 51 9054
TEST_TIME 52 9079
TEST_TIME 53 9050
TEST_TIME 54 9159
TEST_TIME 55 9121
TEST_TIME 56 9248
TEST_TIME 57 9307
TEST_TIME 58 9263
TEST_TIME 59 9296
TEST_TIME 60 9347
TEST_TIME 61 9283
TEST_TIME 62 9236
TEST_TIME 63 9138
TEST_TIME 64 9095
TEST_TIME 65 9138
TEST_TIME 66 9042
TEST_TIME 67 9030
TEST_TIME 68 8988
TEST_TIME 69 8979
TEST_TIME 70 8945
TEST_TIME 71 8958
TEST_TIME 72 8997
TEST_TIME 73 9020
TEST_TIME 74 9044
TEST_TIME 75 9031
TEST_TIME 76 9080
TEST_TIME 77 9193
TEST_TIME 78 9170
TEST_TIME 79 9191
TEST_TIME 80 9296
TEST_TIME 81 9247
TEST_TIME 82 9301
TEST_TIME 83 9271
TEST_TIME 84 9212
TEST_TIME 85 9162
TEST_TIME 86 9092
TEST_TIME 87 9149
TEST_TIME 88 9095
TEST_TIME 89 9071
TEST_TIME 90 9063
TEST_TIME 91 9042
TEST_TIME 92 9021
TEST_TIME 93 9061
TEST_TIME 94 9091
TEST_TIME 95 9058
TEST_TIME 96 9055
TEST_TIME 97 9105
TEST_TIME 98 9185
TEST_TIME 99 9168
TEST_TIME 100 9191
TEST_TIME 101 9185
TEST_TIME 102 9224
TEST_TIME 103 9262
TEST_TIME 104 9280
TEST_TIME 105 9350
TEST_TIME 106 9333
TEST_TIME 107 9261
TEST_TIME 108 9231
TEST_TIME 109 9194
TEST_TIME 110 9190
TEST_TIME 111 9170
TEST_TIME 112 9138
TEST_TIME 113 9116
TEST_TIME 114 9086
TEST_TIME 115 9055
TEST_TIME 116 9041
TEST_TIME 117 9053
TEST_TIME 118 9062
TEST_TIME 119 9091
TEST_TIME 120 9044

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-01-2023 22:39:59 to 17-01-2023 22:40:03.

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 1511369 1511369 0 0.00 0 15837 5 0 0
doc_list_biud 1511368 1511368 0 0.00 0 34133 26 0 0
sp_add_invoice_to_stock 5215 5064 151 2.89 35 151701 16176 0 151
sp_cancel_adding_invoice 5502 5502 0 0.00 5 77550 764 0 0
sp_cancel_client_order 1737 1737 0 0.00 3 11254 315 0 0
sp_cancel_customer_reserve 538297 538297 0 0.00 0 34145 55 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 2 3216 68 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 2 11536 249 0 0
sp_cancel_supplier_invoice 6099 6089 10 0.16 2 23954 801 0 10
sp_cancel_supplier_order 417 417 0 0.00 9 1025180 90790 0 0
sp_cancel_write_off 9579 9579 0 0.00 1 18828 115 0 0
sp_client_order 20714 20714 0 0.00 3 33008 354 0 0
sp_customer_reserve 461054 461054 0 0.00 0 64770 162 0 0
sp_fill_shopping_cart 31596 31596 0 0.00 1 10233 37 0 0
sp_fill_shopping_cart_clo_res 461053 461053 0 0.00 0 14782 14 0 0
sp_get_clo_for_invoice 5061 5061 0 0.00 0 31628 351 0 0
sp_kill_cost_storno 16148 16148 0 0.00 0 4285 49 0 0
sp_kill_qstorno_ret_qs2qd 1092679 1092679 0 0.00 0 30422 30 0 0
sp_kill_qty_storno 576621 576621 0 0.00 0 34132 67 0 0
sp_lock_selected_doc 20731 20731 0 0.00 0 6612 4 0 0
sp_make_cost_storno 28077 28077 0 0.00 0 15826 48 0 0
sp_multiply_rows_for_pdistr 28077 28077 0 0.00 0 12464 26 0 0
sp_multiply_rows_for_qdistr 20714 20714 0 0.00 0 14858 142 0 0
sp_pay_from_customer 7155 7155 0 0.00 4 19364 116 0 0
sp_pay_to_supplier 5921 5921 0 0.00 4 18302 138 0 0
sp_payment_common 13076 13076 0 0.00 1 19341 29 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 11249 290 0 0
sp_qd_handle_on_invoice_upd_sts 10562 10562 0 0.00 0 32971 332 0 0
sp_qd_handle_on_reserve_upd_sts 9940 9940 0 0.00 0 29689 87 0 0
sp_reserve_write_off 9940 9940 0 0.00 5 29939 198 0 0
sp_supplier_invoice 5571 5571 0 0.00 6 75963 3209 0 0
sp_supplier_order 5328 5328 0 0.00 5 41234 1211 0 0
srv_aggregate_perf_data 108 108 0 0.00 3160 262848 18123 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2424 132238 47990 0 0
srv_find_qd_qs_mism 1036932 1036932 0 0.00 0 13102 5 0 0
srv_make_invnt_saldo 537 537 0 0.00 36 64906 1183 0 0
srv_make_money_saldo 768 768 0 0.00 1 622 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 210781 308088 262964 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 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 5064 5064 0 0.00 0 11916 191 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 8541 135 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 2789 12 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 5004 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2114 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 5686 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 2972 29 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 26274 1775 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 3853 15 0 0
t$perf-norm:sp_client_order 20714 20714 0 0.00 0 8341 14 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 1888 9 0 0
t$perf-norm:sp_pay_from_customer 7155 7155 0 0.00 0 11849 13 0 0
t$perf-norm:sp_pay_to_supplier 5921 5921 0 0.00 0 6925 14 0 0
t$perf-norm:sp_reserve_write_off 9940 9940 0 0.00 0 7398 13 0 0
t$perf-norm:sp_supplier_invoice 5571 5571 0 0.00 0 4685 16 0 0
t$perf-norm:sp_supplier_order 5328 5328 0 0.00 0 3337 12 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 677 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 537 537 0 0.00 0 1746 20 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 3631 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10422 10120 302 2.89 0 10555 20 0 302
v_all_customers 37915 37915 0 0.00 0 17 0 0 0
v_all_suppliers 30324 30324 0 0.00 0 22 1 0 0
v_all_wares 41428 41428 0 0.00 0 97 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 18049 31 0 0
v_cancel_client_order 3474 3474 0 0.00 0 4613 8 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 2495 9 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 6715 19 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 11186 93 0 0
v_cancel_supplier_invoice 816 796 20 2.45 0 1048 15 0 20
v_cancel_supplier_order 834 834 0 0.00 0 1554 5 0 0
v_cancel_write_off 2090 2090 0 0.00 0 10593 25 0 0
v_max_id_clo_ord 5328 5328 0 0.00 0 248 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 1189 14 0 0
v_max_id_ord_sup 5554 5554 0 0.00 0 6562 7 0 0
v_max_non_paid_invoice 1640 1640 0 0.00 0 4719 8 0 0
v_max_non_paid_realizn 3734 3734 0 0.00 0 3381 9 0 0
v_min_id_clo_ord 5328 5328 0 0.00 0 2683 5 0 0
v_min_id_clo_res 974 974 0 0.00 0 6710 30 0 0
v_min_id_ord_sup 5554 5554 0 0.00 0 6186 6 0 0
v_min_non_paid_invoice 5921 5921 0 0.00 0 13089 21 0 0
v_min_non_paid_realizn 7155 7155 0 0.00 0 19336 21 0 0
v_our_firm 10354 10354 0 0.00 0 21 1 0 0
v_reserve_write_off 19880 19880 0 0.00 0 18542 12 0 0
x_lock_depdocs_on_canc_invoice 11193 11193 0 0.00 0 22466 119 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 3176 300 0 0
x_make_qstorno_1000_1200 5328 5328 0 0.00 1 41227 1150 0 0
x_make_qstorno_1200_2000 5571 5571 0 0.00 2 75954 3066 0 0
x_make_qstorno_2100_3300 461054 461054 0 0.00 0 64768 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-01-2023 22:40:04 to 17-01-2023 22:40:08.

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:41 7 8192000000 8810146128 8825053184 14676752 0.178 4 4 5 0 14903488 87456 139280
20:09:41 33768 8192000000 9904042112 10217095168 983648352 10.720 100 82 164 0 1010662736 5951824 21062560
20:11:20 14403 8192000000 9949900992 10272903168 1034326560 11.210 100 88 177 0 1064651248 7994704 22329984
20:12:41 13255 8192000000 9984144448 10317127680 1070355408 11.555 100 84 168 0 1100665856 9805552 20504896
20:13:57 10368 8192000000 10037025984 10380865536 1120121376 12.028 100 82 162 0 1149702544 10759024 18822144
20:15:20 6040 8192000000 10080618672 10436648960 1164652256 12.447 100 78 154 0 1194601952 12360592 17589104
20:16:28 3767 8192000000 10122706096 10491473920 1215564064 12.921 100 70 140 1 1243193072 12155344 15473664
20:17:35 1980 8192000000 10159673248 10532802560 1248853088 13.228 100 85 166 0 1279040240 11966832 18220320
20:18:39 875 8192000000 10199557840 10577731584 1279898672 13.512 100 93 184 0 1314687200 14325664 20462864
20:19:42 1263 8192000000 10211749968 10594983936 1293678416 13.638 100 90 182 1 1327661200 13843408 20139376
20:20:45 6931 8192000000 10210404720 10606329856 1299579248 13.691 100 66 132 0 1322988416 8099888 15309280
20:21:52 4962 8192000000 10192771008 10607472640 1308673856 13.774 100 29 56 0 1321726688 6429936 6622896
20:22:58 14080 8192000000 10201677040 10617819136 1316636912 13.846 100 16 31 2 1326336992 5353760 4346320
20:24:12 40838 8192000000 10222276192 10633924608 1323271936 13.906 100 45 90 0 1339357392 5602160 10483296
20:25:54 4912 8192000000 10221291136 10639273984 1326244560 13.933 100 31 59 1 1338590720 5933600 6412560
20:27:04 6477 8192000000 10218933152 10639532032 1330591248 13.972 100 22 45 0 1341162800 4924592 5646960
20:28:11 26545 8192000000 10207202192 10634297344 1313346928 13.816 99 15 30 0 1326632352 9450688 3834736
20:30:01 6667 8192000000 10205104384 10614530048 1285237936 13.561 98 28 54 0 1297467888 6517360 5712592
20:31:09 8613 8192000000 10138994672 10525044736 1149511120 12.305 91 24 48 0 1160586496 5004000 6071376
20:32:22 13205 8192000000 10135006784 10538057728 1228288032 13.038 98 13 24 1 1236149968 4950048 2911888
20:33:39 39521 8192000000 10144132288 10547040256 1201752384 12.793 95 37 68 0 1214654720 4920544 7981792
20:35:19 11465 8192000000 10166500672 10573672448 1269746288 13.419 99 27 54 0 1281831056 5480080 6604688
20:36:34 7916 8192000000 10186346480 10601467904 1295796368 13.657 100 33 67 1 1308532928 4522848 8213712
20:37:49 56733 8192000000 10207089312 10623926272 1313628736 13.819 100 37 74 0 1327345808 4909216 8807856
20:39:53 22813 8192000000 10248987024 10658983936 1338538320 14.044 100 54 108 0 1362283136 11502912 12241904
20:41:18 19097 8192000000 10260206400 10673385472 1353871104 14.182 100 48 94 0 1377730816 13338128 10521584
20:42:56 23653 8192000000 10280310592 10705096704 1367819808 14.308 100 34 67 1 1390244128 14140944 8283376
20:44:22 14044 8192000000 10303394400 10723033088 1387568256 14.484 100 40 80 0 1416012608 18862128 9582224
20:45:40 14738 8192000000 10306914768 10726289408 1390905088 14.514 100 42 84 1 1421461216 19655808 10900320
20:47:09 11091 8192000000 10329079200 10751336448 1391566512 14.520 99 52 104 1 1431845024 26377904 13900608
20:48:29 9938 8192000000 10341337184 10761793536 1412438064 14.706 100 60 119 0 1453995936 27190288 14367584
20:49:46 3566 8192000000 10345990320 10766192640 1414033472 14.720 100 62 123 1 1453643872 23749440 15860960
20:50:54 2924 8192000000 10369061600 10787250176 1430935536 14.870 100 67 129 2 1476283728 28584048 16764144
20:52:03 3727 8192000000 10362688416 10779054080 1420495872 14.777 99 68 136 0 1469176208 30506736 18173600
20:53:12 3066 8192000000 10369023584 10784157696 1412254304 14.704 100 68 136 0 1470879936 40645248 17980384
20:54:34 10177 8192000000 10359116176 10785071104 1405368880 14.643 99 51 102 0 1459918704 41058352 13491472
20:55:56 2421 8192000000 10362550352 10775019520 1404337152 14.634 100 77 153 1 1460026640 36664912 19024576
20:57:04 10641 8192000000 10336463488 10767712256 1400901472 14.603 100 38 76 1 1443466816 32578240 9987104
20:58:23 15868 8192000000 10312831520 10742865920 1372165312 14.346 99 32 64 0 1422014528 40701184 9148032
20:59:43 3275 8192000000 10332887568 10762526720 1377607328 14.395 100 50 101 0 1438152464 47300480 13244656
21:00:51 22569 8192000000 10287497520 10725224448 1360352368 14.241 99 26 52 0 1398820048 31364880 7102800
21:02:19 36147 8192000000 10275230368 10712330240 1336711056 14.028 98 18 36 1 1364002720 22559408 4732256
21:03:57 16891 8192000000 10256950816 10693120000 1332343984 13.988 98 17 34 0 1353856944 17197600 4315360
21:05:25 106782 8192000000 10274537632 10712313856 1361865312 14.254 100 51 102 0 1386555680 12723808 11966560
21:08:13 20547 8192000000 10254104432 10703867904 1352086096 14.166 99 21 40 1 1374465936 17314752 5065088
21:09:34 49660 8192000000 10230274256 10682277888 1315440912 13.835 97 8 16 0 1329547664 12772320 1334432
21:11:24 76999 8192000000 10252896528 10696355840 1342804400 14.083 99 22 42 1 1355205440 7540912 4860128
21:13:45 77160 8192000000 10251110320 10698330112 1334685008 14.009 98 22 42 0 1350743936 10949600 5109328
21:16:26 100834 8192000000 10261685312 10718908416 1366138672 14.292 100 19 38 0 1386093200 15605552 4348976
21:19:12 21537 8192000000 10282898992 10728652800 1380626096 14.422 100 36 74 0 1397948480 9238400 8083984
21:20:35 67171 8192000000 10290456112 10742710272 1387535552 14.484 100 14 28 0 1404280656 13466208 3278896
21:22:47 49549 8192000000 10293992528 10748043264 1395776304 14.557 100 21 42 0 1415853696 14540976 5536416
21:24:53 80229 8192000000 10293132640 10744967168 1388009728 14.488 100 9 18 0 1398792208 9047056 1735424
21:27:23 45674 8192000000 10309023952 10758762496 1401608656 14.609 100 37 75 0 1420790144 10598224 8583264
21:29:22 50260 8192000000 10310038272 10756796416 1387359584 14.482 99 25 50 0 1402690320 9698160 5632576
21:31:15 53984 8192000000 10316841696 10769408000 1376358512 14.384 98 35 70 1 1396880336 12551344 7970480
21:33:13 79521 8192000000 10328202128 10772000768 1378960464 14.407 98 18 36 0 1395153184 12112624 4080096
21:35:33 87159 8192000000 10307801024 10754682880 1324242032 13.915 94 11 22 1 1335410704 9169072 1999600
21:38:04 132238 8192000000 10294094656 10743271424 1290731312 13.611 92 17 34 0 1307505584 13797616 2976656
21:41:28 14419 8192000000 10282809280 10722353152 1363240912 14.266 99 51 101 0 1381112256 7010704 10860640
21:42:43 31112 8192000000 10285002928 10729922560 1369919248 14.326 99 46 92 1 1392287072 12193120 10174704
21:44:24 68665 8192000000 10256138672 10712731648 1334666528 14.009 98 9 18 0 1351730640 15103824 1960288
21:46:33 61603 8192000000 10265721552 10719969280 1365442032 14.286 100 18 36 1 1378197840 8323952 4431856
21:48:35 44818 8192000000 10276189872 10732969984 1354663056 14.189 98 32 62 2 1368881456 7561120 6657280
21:51:22 57105 8192000000 10255714320 10711371776 1308001120 13.768 95 13 26 0 1324252704 13112192 3139392
21:53:21 33852 8192000000 10265349040 10704957440 1340610448 14.063 99 24 48 0 1354497440 8123072 5763920
21:54:55 64306 8192000000 10257621856 10703302656 1329741600 13.965 98 11 22 1 1350224704 18171024 2312080
21:57:02 80022 8192000000 10281273824 10726195200 1356921824 14.210 99 16 32 1 1373093616 12625696 3546096
21:59:22 31082 8192000000 10269660592 10708860928 1334410752 14.007 98 27 54 0 1352314560 11911200 5992608
22:01:00 125842 8192000000 10265003808 10712334336 1332810544 13.993 97 31 62 0 1350859808 10318592 7730672
22:04:25 66026 8192000000 10262888608 10727305216 1343986896 14.093 98 15 30 0 1360461120 12855904 3618320
22:06:34 37417 8192000000 10268259312 10725023744 1355958720 14.201 99 15 28 0 1374168288 14851392 3358176
22:08:13 73233 8192000000 10270450992 10719883264 1355394400 14.196 99 12 24 1 1364624576 6462240 2767936
22:10:29 62595 8192000000 10295372352 10752626688 1385292240 14.464 100 14 28 0 1398859360 10259440 3307680
22:13:12 56287 8192000000 10302416160 10750648320 1373711600 14.360 98 20 38 1 1388259424 10467856 4079968
22:15:11 50767 8192000000 10318256448 10760302592 1375107648 14.373 98 22 44 0 1391195008 11168960 4918400
22:17:05 56867 8192000000 10313311984 10757234688 1386593504 14.475 99 25 50 0 1403753600 11745216 5414880
22:19:12 46991 8192000000 10323371888 10778030080 1393139728 14.534 99 22 44 0 1413298960 15497376 4661856
22:21:06 88041 8192000000 10300595456 10763411456 1315263968 13.834 94 7 14 0 1335726192 18867968 1594256
22:23:35 120317 8192000000 10299962688 10757132288 1354767872 14.190 97 9 18 0 1373299616 16642720 1889024
22:26:36 29647 8192000000 10284437296 10732310528 1347819552 14.128 97 37 74 0 1363254976 7057568 8377856
22:28:18 61418 8192000000 10271924112 10732535808 1360479664 14.242 98 17 34 0 1372079584 7534512 4065408
22:30:26 76204 8192000000 10279659264 10732781568 1353420976 14.178 97 12 24 0 1365673600 9681424 2571200
22:32:42 81897 8192000000 10294362128 10754691072 1394863344 14.549 100 10 20 0 1409924320 13137088 1923888
22:35:05 47160 8192000000 10290131312 10749128704 1368944448 14.318 98 15 30 0 1385543216 12689024 3909744
22:37:17 52866 8192000000 10284266016 10735620096 1361480464 14.251 98 15 29 0 1375039024 10453136 3105424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-01-2023 22:40:10 to 17-01-2023 22:40:25.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-01-2023 22:40:26 to 17-01-2023 22:40:28.

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          237252
MON$OLDEST_ACTIVE               239439
MON$OLDEST_SNAPSHOT             239439
MON$NEXT_TRANSACTION            239439
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                       2009792
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.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Jan 17 22:38:39 2023
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Tue Jan 17 22:38:43 2023
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Tue Jan 17 22:38:44 2023
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Tue Jan 17 22:38:46 2023
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:55: 
--- end of diff output ---

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

17.01.23 22:40:29. 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
2023-01-17 22:40:32 No severe PSQL-related problems occured
17.01.23 22:40:33. End of report.