Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.03.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 15380664 kB
MemAvailable 30431100 kB
Buffers 0 kB
Cached 16311028 kB
SwapCached 3028 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-03-08 22:38:50
DTS_END                         2023-03-08 22:38:50.7460
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/42972
TRN_ID                          241163
ATT_ID                          643
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: 9264.75 19319 1111770
customer order: creation 172.62 292 20714
customer order: refuse 15.28 351 1834
order to supplier: creation 45.16 1202 5419
order to supplier: removal 3.47 85680 416
invoice (draft): creation 46.84 3167 5621
invoice (draft): removal 52.43 665 6292
invoice accept: apply 41.73 18359 5008
invoice accept: cancel 47.17 761 5660
customer reserve: creation 3880.38 182 465645
customer reserve: removal 4664.57 49 559748
realization accept: apply 82.87 153 9944
realization accept: cancel 82.84 99 9941
payment from customer: creation 59.27 108 7112
payment from customer: removal 5.76 76 691
payment to supplier: creation 48.46 110 5815
payment to supplier: removal 3.58 70 430
service: total inventory turnovers 4.83 1032 580
service: total monetary turnovers 6.63 24 796
service: aggregate perf. data 0.83 22481 100
service: refresh index statistics 0.03 251527 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-03-2023 22:39:36 to 08-03-2023 22:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3144
TEST_TIME 2 6170
TEST_TIME 3 5878
TEST_TIME 4 6294
TEST_TIME 5 6322
TEST_TIME 6 5898
TEST_TIME 7 5757
TEST_TIME 8 5649
TEST_TIME 9 5609
TEST_TIME 10 5604
TEST_TIME 11 5565
TEST_TIME 12 5854
TEST_TIME 13 5891
TEST_TIME 14 6002
TEST_TIME 15 6540
TEST_TIME 16 6717
TEST_TIME 17 6762
TEST_TIME 18 7686
TEST_TIME 19 7857
TEST_TIME 20 8457
TEST_TIME 21 8725
TEST_TIME 22 8712
TEST_TIME 23 8783
TEST_TIME 24 8878
TEST_TIME 25 9010
TEST_TIME 26 8997
TEST_TIME 27 8851
TEST_TIME 28 8902
TEST_TIME 29 8987
TEST_TIME 30 9070
TEST_TIME 31 9645
TEST_TIME 32 10267
TEST_TIME 33 10165
TEST_TIME 34 10059
TEST_TIME 35 9878
TEST_TIME 36 9746
TEST_TIME 37 9842
TEST_TIME 38 9905
TEST_TIME 39 9675
TEST_TIME 40 9812
TEST_TIME 41 9670
TEST_TIME 42 9542
TEST_TIME 43 9501
TEST_TIME 44 9471
TEST_TIME 45 9575
TEST_TIME 46 9627
TEST_TIME 47 9525
TEST_TIME 48 9514
TEST_TIME 49 9430
TEST_TIME 50 9326
TEST_TIME 51 9380
TEST_TIME 52 9282
TEST_TIME 53 9186
TEST_TIME 54 9203
TEST_TIME 55 9239
TEST_TIME 56 9192
TEST_TIME 57 9102
TEST_TIME 58 8974
TEST_TIME 59 9011
TEST_TIME 60 9009
TEST_TIME 61 8952
TEST_TIME 62 9041
TEST_TIME 63 9151
TEST_TIME 64 9131
TEST_TIME 65 9112
TEST_TIME 66 9173
TEST_TIME 67 9178
TEST_TIME 68 9283
TEST_TIME 69 9275
TEST_TIME 70 9277
TEST_TIME 71 9343
TEST_TIME 72 9367
TEST_TIME 73 9298
TEST_TIME 74 9339
TEST_TIME 75 9333
TEST_TIME 76 9219
TEST_TIME 77 9237
TEST_TIME 78 9238
TEST_TIME 79 9222
TEST_TIME 80 9174
TEST_TIME 81 9120
TEST_TIME 82 9133
TEST_TIME 83 9072
TEST_TIME 84 9073
TEST_TIME 85 9050
TEST_TIME 86 9039
TEST_TIME 87 9019
TEST_TIME 88 9087
TEST_TIME 89 9095
TEST_TIME 90 9154
TEST_TIME 91 9173
TEST_TIME 92 9160
TEST_TIME 93 9218
TEST_TIME 94 9267
TEST_TIME 95 9304
TEST_TIME 96 9342
TEST_TIME 97 9294
TEST_TIME 98 9419
TEST_TIME 99 9434
TEST_TIME 100 9361
TEST_TIME 101 9297
TEST_TIME 102 9258
TEST_TIME 103 9262
TEST_TIME 104 9217
TEST_TIME 105 9163
TEST_TIME 106 9115
TEST_TIME 107 9167
TEST_TIME 108 9174
TEST_TIME 109 9182
TEST_TIME 110 9158
TEST_TIME 111 9214
TEST_TIME 112 9216
TEST_TIME 113 9205
TEST_TIME 114 9199
TEST_TIME 115 9232
TEST_TIME 116 9193
TEST_TIME 117 9244
TEST_TIME 118 9261
TEST_TIME 119 9269
TEST_TIME 120 9267

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-03-2023 22:39:39 to 08-03-2023 22:39:43.

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 1544001 1544001 0 0.00 0 14892 6 0 0
doc_list_biud 1543998 1543998 0 0.00 0 27917 23 0 0
sp_add_invoice_to_stock 5147 5008 139 2.70 28 174065 18360 0 139
sp_cancel_adding_invoice 5660 5660 0 0.00 5 60477 762 0 0
sp_cancel_client_order 1834 1834 0 0.00 4 10768 351 0 0
sp_cancel_customer_reserve 559748 559748 0 0.00 0 27919 50 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 2 4649 77 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 4311 71 0 0
sp_cancel_supplier_invoice 6298 6292 6 0.09 1 18420 665 0 6
sp_cancel_supplier_order 416 416 0 0.00 6 828347 85680 0 0
sp_cancel_write_off 9941 9941 0 0.00 1 8674 100 0 0
sp_client_order 20714 20714 0 0.00 3 17866 292 0 0
sp_customer_reserve 465645 465645 0 0.00 0 97449 183 0 0
sp_fill_shopping_cart 31736 31736 0 0.00 1 23261 38 0 0
sp_fill_shopping_cart_clo_res 465637 465637 0 0.00 0 17683 13 0 0
sp_get_clo_for_invoice 4997 4997 0 0.00 0 15579 275 0 0
sp_kill_cost_storno 16722 16722 0 0.00 0 9602 50 0 0
sp_kill_qstorno_ret_qs2qd 1136145 1136145 0 0.00 0 23070 27 0 0
sp_kill_qty_storno 598831 598831 0 0.00 0 27917 59 0 0
sp_lock_selected_doc 20929 20929 0 0.00 0 8576 4 0 0
sp_make_cost_storno 27868 27868 0 0.00 0 9860 46 0 0
sp_multiply_rows_for_pdistr 27868 27868 0 0.00 0 8435 23 0 0
sp_multiply_rows_for_qdistr 20714 20714 0 0.00 0 17857 143 0 0
sp_pay_from_customer 7112 7112 0 0.00 4 9884 109 0 0
sp_pay_to_supplier 5815 5815 0 0.00 4 9694 110 0 0
sp_payment_common 12927 12927 0 0.00 1 7881 29 0 0
sp_qd_handle_on_cancel_clo 1834 1834 0 0.00 0 10738 324 0 0
sp_qd_handle_on_invoice_upd_sts 10657 10657 0 0.00 0 23054 315 0 0
sp_qd_handle_on_reserve_upd_sts 9943 9943 0 0.00 0 15241 53 0 0
sp_reserve_write_off 9944 9944 0 0.00 6 15278 154 0 0
sp_supplier_invoice 5621 5621 0 0.00 5 75486 3167 0 0
sp_supplier_order 5419 5419 0 0.00 4 29060 1203 0 0
srv_aggregate_perf_data 100 100 0 0.00 3240 536040 22482 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 2269 120268 41110 0 0
srv_find_qd_qs_mism 1064402 1064402 0 0.00 0 14892 5 0 0
srv_make_invnt_saldo 580 580 0 0.00 28 55082 1032 0 0
srv_make_money_saldo 796 796 0 0.00 1 1038 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 101815 342299 251528 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5008 5008 0 0.00 0 23594 160 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 7520 107 0 0
t$perf-norm:sp_cancel_client_order 1834 1834 0 0.00 0 1909 9 0 0
t$perf-norm:sp_cancel_customer_reserve 746 746 0 0.00 0 8202 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 592 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 1387 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 739 13 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 18058 1437 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 9685 31 0 0
t$perf-norm:sp_client_order 20714 20714 0 0.00 0 10513 15 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 1561 6 0 0
t$perf-norm:sp_pay_from_customer 7112 7112 0 0.00 0 7130 10 0 0
t$perf-norm:sp_pay_to_supplier 5815 5815 0 0.00 0 7957 12 0 0
t$perf-norm:sp_reserve_write_off 9944 9944 0 0.00 0 8029 11 0 0
t$perf-norm:sp_supplier_invoice 5621 5621 0 0.00 0 6783 16 0 0
t$perf-norm:sp_supplier_order 5419 5419 0 0.00 0 10255 18 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 1210 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 36 1 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 2265 18 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 7494 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 6 0 0
v_add_invoice_to_stock 10272 9994 278 2.70 0 4378 16 0 278
v_all_customers 37768 37768 0 0.00 0 70 0 0 0
v_all_suppliers 30246 30246 0 0.00 0 33 1 0 0
v_all_wares 41428 41428 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 544 6 0 0
v_cancel_client_order 3668 3668 0 0.00 0 3942 8 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 3192 7 0 0
v_cancel_customer_reserve 1492 1492 0 0.00 0 1241 4 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 1141 5 0 0
v_cancel_supplier_invoice 854 842 12 1.40 0 2244 17 0 12
v_cancel_supplier_order 832 832 0 0.00 0 621 4 0 0
v_cancel_write_off 2070 2070 0 0.00 0 2651 15 0 0
v_max_id_clo_ord 5414 5414 0 0.00 0 1053 2 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 3171 14 0 0
v_max_id_ord_sup 5607 5607 0 0.00 0 6343 7 0 0
v_max_non_paid_invoice 1713 1713 0 0.00 0 2467 7 0 0
v_max_non_paid_realizn 3790 3790 0 0.00 0 5939 8 0 0
v_min_id_clo_ord 5414 5414 0 0.00 0 5714 8 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 10496 36 0 0
v_min_id_ord_sup 5607 5607 0 0.00 0 3894 7 0 0
v_min_non_paid_invoice 5815 5815 0 0.00 0 7877 18 0 0
v_min_non_paid_realizn 7112 7112 0 0.00 0 7600 24 0 0
v_our_firm 10304 10304 0 0.00 0 23 1 0 0
v_reserve_write_off 19886 19886 0 0.00 0 6161 7 0 0
x_lock_depdocs_on_canc_invoice 11531 11531 0 0.00 0 16746 108 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 5438 322 0 0
x_make_qstorno_1000_1200 5419 5419 0 0.00 1 29051 1125 0 0
x_make_qstorno_1200_2000 5621 5621 0 0.00 2 71906 3032 0 0
x_make_qstorno_2100_3300 465645 465645 0 0.00 0 97447 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-03-2023 22:39:44 to 08-03-2023 22:39:48.

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:54 6 8192000000 8811267488 8826826752 15241616 0.185 4 4 5 0 15468368 87456 139296
20:09:55 24086 8192000000 9905264704 10216148992 986567024 10.748 100 87 174 0 1016064720 6102848 23394848
20:11:31 17302 8192000000 9967093056 10289971200 1049327792 11.354 100 84 168 0 1079060048 8184256 21548000
20:12:52 15923 8192000000 9997096736 10328825856 1081032064 11.657 100 88 176 0 1113141952 10171408 21938480
20:14:12 11058 8192000000 10037938288 10385268736 1128749344 12.110 100 77 153 0 1156852656 10466592 17636720
20:15:32 7165 8192000000 10077786912 10435018752 1171703184 12.513 100 78 156 0 1200891568 11311344 17877040
20:16:42 4689 8192000000 10131636192 10502897664 1219067456 12.953 100 79 156 0 1249611808 12774560 17769792
20:17:50 5304 8192000000 10157886384 10534608896 1248069568 13.220 100 80 159 0 1278505696 13916928 16519200
20:18:57 1633 8192000000 10181187216 10559569920 1272631984 13.446 100 86 172 0 1304083152 12051712 19399456
20:20:01 978 8192000000 10211749008 10595610624 1298355200 13.680 100 83 166 2 1329459664 13290432 17814032
20:21:03 34762 8192000000 10207062336 10612994048 1309117456 13.778 100 52 104 0 1328201008 7071008 12012544
20:22:38 4332 8192000000 10211423488 10623410176 1319901680 13.876 100 32 64 0 1335722112 8688000 7132432
20:23:45 7243 8192000000 10209397792 10629509120 1326456224 13.935 100 17 34 1 1336259744 6456160 3347360
20:24:54 14686 8192000000 10229492800 10643058688 1336272176 14.024 100 27 53 0 1348421920 4635600 7514144
20:26:11 30381 8192000000 10238995152 10658975744 1340906560 14.066 100 31 62 0 1353460448 5098384 7455504
20:27:42 4851 8192000000 10235811120 10652622848 1336854352 14.029 100 22 42 1 1347876944 6446064 4576528
20:28:48 4342 8192000000 10220311296 10644410368 1325049936 13.922 99 20 41 0 1334787360 4963904 4773520
20:29:57 8245 8192000000 10184088944 10602594304 1262686672 13.355 96 13 26 1 1270941312 5521808 2732832
20:31:06 61569 8192000000 10095040000 10481856512 1135205664 12.170 89 26 52 0 1146733552 5689168 5838720
20:33:09 3184 8192000000 10156331712 10553765888 1257583984 13.308 100 49 98 2 1276840464 8077024 11179456
20:34:15 7074 8192000000 10142939808 10548690944 1239869968 13.145 98 19 39 1 1249163440 4969408 4324064
20:35:22 10575 8192000000 10186536400 10597203968 1289534880 13.600 100 20 40 0 1298891312 4820096 4536336
20:36:34 27358 8192000000 10196662736 10608070656 1300336512 13.698 100 37 74 0 1319763760 11360448 8066800
20:38:04 12024 8192000000 10210466320 10621534208 1314150576 13.824 100 20 40 0 1328014320 9363456 4500288
20:39:22 14982 8192000000 10239452384 10648133632 1331678752 13.982 100 43 83 0 1347580832 6737696 9164384
20:40:41 8431 8192000000 10262025280 10674876416 1353822848 14.182 100 59 118 0 1377211712 9846016 13542848
20:41:53 22065 8192000000 10275311728 10694295552 1362016208 14.255 100 49 98 0 1386540128 13823760 10700160
20:43:18 20079 8192000000 10274898560 10698702848 1370478208 14.331 100 47 94 0 1390038656 8740720 10819728
20:44:42 23443 8192000000 10305315344 10725588992 1388483952 14.492 100 44 85 0 1413595376 15643392 9468032
20:46:12 21820 8192000000 10305061808 10735476736 1398450016 14.581 100 39 78 0 1424102992 16813024 8839952
20:47:59 25446 8192000000 10310792528 10742775808 1387430240 14.483 99 35 68 1 1413476448 18222080 7824128
20:49:26 4418 8192000000 10325408576 10753294336 1406439056 14.652 100 51 101 2 1437728064 19452688 11836320
20:50:38 2912 8192000000 10350464704 10770243584 1418109424 14.756 100 67 130 1 1456358304 22090320 16158560
20:51:53 5104 8192000000 10329815584 10749104128 1386782304 14.477 98 51 100 0 1427062688 28327920 11952464
20:53:06 2268 8192000000 10350544336 10767224832 1409011392 14.675 100 71 139 1 1457917696 32093088 16813216
20:54:14 9147 8192000000 10335202560 10761646080 1400470128 14.599 100 48 96 1 1444774112 31865584 12438400
20:55:32 2311 8192000000 10340776544 10753273856 1389145248 14.498 100 81 160 1 1444827168 35588560 20093360
20:56:37 5175 8192000000 10306407776 10732716032 1374433184 14.367 100 43 87 0 1413759936 28273424 11053328
20:57:48 8539 8192000000 10268415472 10687778816 1329285216 13.961 98 39 76 0 1368450768 29044672 10120880
20:59:03 7008 8192000000 10257799776 10676793344 1321977824 13.895 99 36 72 1 1356400736 25059200 9363712
21:00:13 11509 8192000000 10258625376 10684657664 1334594576 14.009 99 28 56 0 1365190784 23749920 6846288
21:01:43 14102 8192000000 10298986944 10708340736 1359155792 14.230 100 49 98 1 1397111520 26117552 11838176
21:03:01 14479 8192000000 10311632704 10734542848 1373036480 14.354 100 43 87 0 1416531392 32687488 10807424
21:04:23 10912 8192000000 10306021568 10736357376 1378427664 14.402 100 35 70 0 1414530464 27281728 8821072
21:05:37 15082 8192000000 10324948480 10755530752 1391179824 14.516 100 28 54 0 1428553824 29793488 7580512
21:06:56 27721 8192000000 10320680544 10755424256 1384994592 14.461 100 21 42 0 1427547552 36680288 5872672
21:08:26 22685 8192000000 10325314688 10758389760 1389347056 14.500 100 18 36 0 1429007728 35043840 4616832
21:09:50 53325 8192000000 10296619536 10736533504 1363566800 14.269 98 12 24 0 1391024672 24268544 3189328
21:11:53 58930 8192000000 10286094048 10730635264 1367333424 14.303 99 6 12 0 1377031376 8477792 1220160
21:13:53 22386 8192000000 10318349472 10753708032 1397717648 14.575 100 47 94 0 1422594352 12803152 12073552
21:15:17 17032 8192000000 10330447888 10761297920 1398639808 14.583 100 50 99 1 1430590416 20038256 11912352
21:16:40 77738 8192000000 10317236208 10752552960 1383767072 14.450 99 48 95 2 1410303376 15775264 10761040
21:18:59 84558 8192000000 10312574256 10758799360 1391252544 14.517 99 23 46 0 1407088432 10485040 5350848
21:21:25 30021 8192000000 10301344656 10746609664 1393634048 14.538 99 20 40 0 1409082656 10635552 4813056
21:23:03 50442 8192000000 10315243008 10792800256 1409566752 14.680 100 17 32 1 1433505472 19857152 4081568
21:24:55 35420 8192000000 10313518432 10780958720 1415659808 14.734 100 8 16 0 1430735728 13065008 2010912
21:26:31 104611 8192000000 10316840192 10773618688 1404296736 14.633 99 21 41 0 1422527248 13841040 4389472
21:29:27 69655 8192000000 10313206352 10789101568 1395249968 14.553 99 10 20 1 1412690960 15632608 1808384
21:31:37 67205 8192000000 10326302432 10786885632 1410620384 14.689 100 18 36 0 1432556656 18410208 3526064
21:33:48 45168 8192000000 10300837936 10763751424 1393214560 14.535 99 17 34 0 1407787360 11243728 3329072
21:35:41 55318 8192000000 10301363744 10775228416 1409818256 14.682 100 12 20 0 1424310976 12193712 2299008
21:38:31 34535 8192000000 10304500224 10770472960 1389433568 14.501 98 11 21 0 1398932464 7531600 1967296
21:40:06 60211 8192000000 10323542352 10785005568 1413599744 14.716 100 16 32 0 1425346576 8429216 3317616
21:42:07 24395 8192000000 10302685152 10757312512 1354527776 14.188 96 20 40 0 1371600912 13360752 3712384
21:43:33 10327 8192000000 10267732416 10702999552 1327212288 13.942 97 51 102 0 1352881744 14805408 10864048
21:44:44 49126 8192000000 10295480816 10731098112 1322908336 13.903 96 38 76 0 1345012496 13704144 8400016
21:46:37 56573 8192000000 10262960176 10707034112 1316616592 13.846 96 11 22 0 1331805744 12526320 2662832
21:48:40 40963 8192000000 10254761840 10704158720 1315014096 13.832 97 12 24 0 1333742432 16311600 2416736
21:50:23 64799 8192000000 10261297488 10696916992 1304884256 13.740 96 18 35 0 1316631664 8032560 3714848
21:52:32 82281 8192000000 10242156512 10698723328 1309735824 13.784 97 18 36 0 1330350576 16891952 3722800
21:54:54 44177 8192000000 10241472416 10681102336 1306001760 13.750 97 32 64 1 1319391280 6279504 7110016
21:56:51 20467 8192000000 10233227072 10680487936 1326153040 13.932 99 26 52 0 1348754336 16970720 5630576
21:58:12 35550 8192000000 10235480704 10690871296 1334976448 14.012 99 11 23 2 1342570336 5218064 2375824
21:59:51 71805 8192000000 10239094544 10692222976 1329391056 13.962 99 13 24 0 1340192528 8050752 2750720
22:02:25 75037 8192000000 10253356288 10714533888 1353981808 14.183 100 15 31 0 1366306208 8073184 4251216
22:04:49 31143 8192000000 10256523056 10701426688 1348808656 14.137 99 18 35 0 1363174128 10613424 3752048
22:06:26 46819 8192000000 10265348496 10717048832 1361152800 14.248 100 12 24 0 1379495936 15502736 2840400
22:08:13 75105 8192000000 10280772480 10733928448 1368669456 14.315 99 15 30 0 1386054832 13637792 3747584
22:10:34 120268 8192000000 10293161552 10741825536 1353451584 14.179 97 9 18 0 1369376576 14385856 1539136
22:13:35 44810 8192000000 10301328192 10758934528 1381984480 14.434 99 22 44 0 1398847568 11853040 5010048
22:15:30 84523 8192000000 10308762128 10778001408 1374201456 14.365 98 21 40 0 1397307392 18661168 4444768
22:18:53 65633 8192000000 10301972448 10747531264 1371462752 14.340 98 29 57 2 1389576496 11698688 6415056
22:21:09 66859 8192000000 10299220320 10761957376 1386696112 14.476 99 19 38 0 1408664896 17708096 4260688
22:23:20 54447 8192000000 10299356544 10759782400 1396506352 14.564 100 6 12 0 1415455856 17478544 1470960
22:25:32 51162 8192000000 10291476976 10770464768 1389134864 14.498 100 9 19 0 1413817904 22284080 2398960
22:27:28 84405 8192000000 10280719536 10749644800 1354600800 14.189 97 20 41 0 1375867184 16414880 4851504
22:29:56 92662 8192000000 10278849568 10734686208 1328145824 13.950 96 11 22 1 1343678224 12747312 2785088
22:32:29 50790 8192000000 10284655216 10733514752 1334076544 14.004 96 27 54 1 1350723280 10469472 6177264
22:34:26 48900 8192000000 10269357728 10715811840 1320360496 13.880 96 23 46 0 1339536912 13878624 5297792
22:36:15 12998 8192000000 10243449312 10679193600 1309590880 13.782 97 30 60 1 1325140496 8901920 6647696
22:37:34 82037 8192000000 9305090208 9589248000 442367952 5.123 33 10 20 0 451128160 7071376 1688832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-03-2023 22:39:50 to 08-03-2023 22:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-03-2023 22:40:06 to 08-03-2023 22:40:08.

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          239825
MON$OLDEST_ACTIVE               241422
MON$OLDEST_SNAPSHOT             241422
MON$NEXT_TRANSACTION            241422
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                       2020224
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:1208: 
:1209: mscs-test-161.budzdorov.ru    Wed Mar  8 22:39:05 2023
:1210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1211: 
--- end of diff output ---

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

08.03.23 22:40:10. 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-03-08 22:40:12 No severe PSQL-related problems occured
08.03.23 22:40:13. End of report.