Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.02.2023 01: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 15254000 kB
MemAvailable 30431552 kB
Buffers 0 kB
Cached 16437484 kB
SwapCached 3348 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-02-17 01:38:37
DTS_END                         2023-02-17 01:38:37.3520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48770
TRN_ID                          246021
ATT_ID                          709
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: 9473.78 22358 1136853
customer order: creation 174.66 347 20959
customer order: refuse 14.86 317 1783
order to supplier: creation 46.96 1192 5635
order to supplier: removal 3.41 95051 409
invoice (draft): creation 47.09 3326 5651
invoice (draft): removal 53.29 779 6395
invoice accept: apply 42.48 17860 5098
invoice accept: cancel 48.08 741 5770
customer reserve: creation 3956.40 176 474768
customer reserve: removal 4785.62 52 574274
realization accept: apply 85.01 173 10201
realization accept: cancel 84.68 109 10161
payment from customer: creation 60.12 109 7214
payment from customer: removal 5.94 93 713
payment to supplier: creation 49.02 105 5882
payment to supplier: removal 3.72 105 446
service: total inventory turnovers 4.68 876 562
service: total monetary turnovers 6.87 22 824
service: aggregate perf. data 0.87 19705 104
service: refresh index statistics 0.03 306025 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-02-2023 01:39:41 to 17-02-2023 01:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2861
TEST_TIME 2 3427
TEST_TIME 3 4318
TEST_TIME 4 4848
TEST_TIME 5 5555
TEST_TIME 6 5357
TEST_TIME 7 5229
TEST_TIME 8 5229
TEST_TIME 9 5140
TEST_TIME 10 5178
TEST_TIME 11 5555
TEST_TIME 12 6225
TEST_TIME 13 6277
TEST_TIME 14 6507
TEST_TIME 15 6766
TEST_TIME 16 6672
TEST_TIME 17 7129
TEST_TIME 18 7223
TEST_TIME 19 7379
TEST_TIME 20 8139
TEST_TIME 21 8235
TEST_TIME 22 8442
TEST_TIME 23 8737
TEST_TIME 24 9059
TEST_TIME 25 9342
TEST_TIME 26 9598
TEST_TIME 27 9924
TEST_TIME 28 10524
TEST_TIME 29 10498
TEST_TIME 30 10336
TEST_TIME 31 10227
TEST_TIME 32 10361
TEST_TIME 33 10131
TEST_TIME 34 10023
TEST_TIME 35 9958
TEST_TIME 36 9783
TEST_TIME 37 9791
TEST_TIME 38 9779
TEST_TIME 39 9791
TEST_TIME 40 9815
TEST_TIME 41 9842
TEST_TIME 42 9688
TEST_TIME 43 9723
TEST_TIME 44 9723
TEST_TIME 45 9594
TEST_TIME 46 9663
TEST_TIME 47 9591
TEST_TIME 48 9520
TEST_TIME 49 9437
TEST_TIME 50 9509
TEST_TIME 51 9462
TEST_TIME 52 9401
TEST_TIME 53 9549
TEST_TIME 54 9552
TEST_TIME 55 9515
TEST_TIME 56 9665
TEST_TIME 57 9713
TEST_TIME 58 9678
TEST_TIME 59 9632
TEST_TIME 60 9644
TEST_TIME 61 9781
TEST_TIME 62 9732
TEST_TIME 63 9671
TEST_TIME 64 9587
TEST_TIME 65 9575
TEST_TIME 66 9505
TEST_TIME 67 9448
TEST_TIME 68 9512
TEST_TIME 69 9497
TEST_TIME 70 9441
TEST_TIME 71 9446
TEST_TIME 72 9548
TEST_TIME 73 9560
TEST_TIME 74 9646
TEST_TIME 75 9621
TEST_TIME 76 9650
TEST_TIME 77 9614
TEST_TIME 78 9713
TEST_TIME 79 9723
TEST_TIME 80 9819
TEST_TIME 81 9743
TEST_TIME 82 9700
TEST_TIME 83 9631
TEST_TIME 84 9577
TEST_TIME 85 9561
TEST_TIME 86 9599
TEST_TIME 87 9560
TEST_TIME 88 9529
TEST_TIME 89 9483
TEST_TIME 90 9432
TEST_TIME 91 9404
TEST_TIME 92 9389
TEST_TIME 93 9420
TEST_TIME 94 9418
TEST_TIME 95 9440
TEST_TIME 96 9444
TEST_TIME 97 9453
TEST_TIME 98 9531
TEST_TIME 99 9505
TEST_TIME 100 9534
TEST_TIME 101 9566
TEST_TIME 102 9620
TEST_TIME 103 9603
TEST_TIME 104 9594
TEST_TIME 105 9614
TEST_TIME 106 9636
TEST_TIME 107 9590
TEST_TIME 108 9532
TEST_TIME 109 9541
TEST_TIME 110 9504
TEST_TIME 111 9537
TEST_TIME 112 9487
TEST_TIME 113 9466
TEST_TIME 114 9445
TEST_TIME 115 9394
TEST_TIME 116 9363
TEST_TIME 117 9413
TEST_TIME 118 9475
TEST_TIME 119 9459
TEST_TIME 120 9476

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-02-2023 01:39:45 to 17-02-2023 01:39:48.

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 1576602 1576602 0 0.00 0 14694 6 0 0
doc_list_biud 1576602 1576602 0 0.00 0 60475 25 0 0
sp_add_invoice_to_stock 5241 5098 143 2.72 42 212493 17860 0 143
sp_cancel_adding_invoice 5770 5770 0 0.00 4 62355 741 0 0
sp_cancel_client_order 1783 1783 0 0.00 4 18701 318 0 0
sp_cancel_customer_reserve 574274 574274 0 0.00 0 60478 53 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 6623 94 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 2 6915 105 0 0
sp_cancel_supplier_invoice 6406 6395 11 0.17 2 20371 779 0 11
sp_cancel_supplier_order 409 409 0 0.00 5 1214668 95051 0 0
sp_cancel_write_off 10161 10161 0 0.00 1 10034 109 0 0
sp_client_order 20959 20959 0 0.00 3 38188 347 0 0
sp_customer_reserve 474768 474768 0 0.00 0 83369 176 0 0
sp_fill_shopping_cart 32238 32238 0 0.00 1 14611 39 0 0
sp_fill_shopping_cart_clo_res 474765 474765 0 0.00 0 21627 13 0 0
sp_get_clo_for_invoice 5093 5093 0 0.00 0 21608 277 0 0
sp_kill_cost_storno 17090 17090 0 0.00 0 7238 52 0 0
sp_kill_qstorno_ret_qs2qd 1165513 1165513 0 0.00 0 60441 29 0 0
sp_kill_qty_storno 614086 614086 0 0.00 0 60475 63 0 0
sp_lock_selected_doc 21196 21196 0 0.00 0 9818 6 0 0
sp_make_cost_storno 28390 28390 0 0.00 0 12423 46 0 0
sp_multiply_rows_for_pdistr 28390 28390 0 0.00 0 6812 26 0 0
sp_multiply_rows_for_qdistr 20959 20959 0 0.00 0 14131 151 0 0
sp_pay_from_customer 7214 7214 0 0.00 4 13563 110 0 0
sp_pay_to_supplier 5882 5882 0 0.00 4 12946 105 0 0
sp_payment_common 13096 13096 0 0.00 1 13554 26 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 18696 292 0 0
sp_qd_handle_on_invoice_upd_sts 10863 10863 0 0.00 0 32852 304 0 0
sp_qd_handle_on_reserve_upd_sts 10201 10201 0 0.00 0 17803 55 0 0
sp_reserve_write_off 10201 10201 0 0.00 5 17935 174 0 0
sp_supplier_invoice 5651 5651 0 0.00 5 92595 3326 0 0
sp_supplier_order 5635 5635 0 0.00 5 27491 1192 0 0
srv_aggregate_perf_data 104 104 0 0.00 2684 345566 19705 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2093 138385 44813 0 0
srv_find_qd_qs_mism 1088221 1088221 0 0.00 0 14693 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 24 31688 877 0 0
srv_make_money_saldo 824 824 0 0.00 1 356 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 157611 375765 306025 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5098 5098 0 0.00 0 14661 170 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 4005 99 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 4132 13 0 0
t$perf-norm:sp_cancel_customer_reserve 737 737 0 0.00 0 2937 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 994 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 1778 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 918 14 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 20116 1378 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 7960 16 0 0
t$perf-norm:sp_client_order 20959 20959 0 0.00 0 5872 17 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 1528 6 0 0
t$perf-norm:sp_pay_from_customer 7214 7214 0 0.00 0 8166 9 0 0
t$perf-norm:sp_pay_to_supplier 5882 5882 0 0.00 0 8139 11 0 0
t$perf-norm:sp_reserve_write_off 10201 10201 0 0.00 0 5839 9 0 0
t$perf-norm:sp_supplier_invoice 5651 5651 0 0.00 0 4952 20 0 0
t$perf-norm:sp_supplier_order 5635 5635 0 0.00 0 5716 13 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 115 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2646 44 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 3128 23 0 0
t$perf-norm:srv_make_money_saldo 824 824 0 0.00 0 1352 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10470 10184 286 2.73 0 6612 19 0 286
v_all_customers 38150 38150 0 0.00 0 101 1 0 0
v_all_suppliers 30678 30678 0 0.00 0 27 1 0 0
v_all_wares 41918 41918 0 0.00 0 101 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 3470 12 0 0
v_cancel_client_order 3566 3566 0 0.00 0 5885 6 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 5895 21 0 0
v_cancel_customer_reserve 1474 1474 0 0.00 0 3590 6 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 6090 13 0 0
v_cancel_supplier_invoice 866 844 22 2.54 0 5824 24 0 22
v_cancel_supplier_order 818 818 0 0.00 0 2790 10 0 0
v_cancel_write_off 1972 1972 0 0.00 0 2550 19 0 0
v_max_id_clo_ord 5634 5634 0 0.00 0 1197 2 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 2509 14 0 0
v_max_id_ord_sup 5644 5644 0 0.00 0 3942 6 0 0
v_max_non_paid_invoice 1821 1821 0 0.00 0 743 3 0 0
v_max_non_paid_realizn 3854 3854 0 0.00 0 947 4 0 0
v_min_id_clo_ord 5634 5634 0 0.00 0 9734 12 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 2811 6 0 0
v_min_id_ord_sup 5644 5644 0 0.00 0 8232 7 0 0
v_min_non_paid_invoice 5882 5882 0 0.00 0 5477 16 0 0
v_min_non_paid_realizn 7214 7214 0 0.00 0 13551 21 0 0
v_our_firm 10488 10488 0 0.00 0 20 1 0 0
v_reserve_write_off 20402 20402 0 0.00 0 11718 8 0 0
x_lock_depdocs_on_canc_invoice 11743 11743 0 0.00 0 16332 119 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 6249 364 0 0
x_make_qstorno_1000_1200 5635 5635 0 0.00 2 19224 1118 0 0
x_make_qstorno_1200_2000 5651 5651 0 0.00 2 92588 3183 0 0
x_make_qstorno_2100_3300 474768 474768 0 0.00 0 83365 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-02-2023 01:39:49 to 17-02-2023 01:39:53.

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
23:08:41 3 8192000000 8801574464 8813707264 7731408 0.094 2 2 3 0 7872512 66144 74960
23:09:42 22428 8192000000 9915995456 10229370880 989120448 10.773 100 85 169 0 1016755360 6030384 21604528
23:11:18 9142 8192000000 9969848976 10293895168 1048083408 11.342 100 88 176 0 1078916256 8529392 22303456
23:12:32 7142 8192000000 9986797872 10320654336 1073618720 11.587 100 86 172 0 1105160720 9935360 21606640
23:14:03 8273 8192000000 10051633072 10400911360 1134509520 12.164 100 81 160 1 1164793840 11411232 18873088
23:15:15 6292 8192000000 10084505120 10442211328 1168222400 12.480 100 83 165 0 1200755824 13555152 18978272
23:16:23 2474 8192000000 10117883248 10490122240 1216875376 12.933 100 75 150 0 1245861280 11882832 17103072
23:17:28 2013 8192000000 10158044912 10532528128 1246089040 13.202 100 87 168 2 1277131280 12670464 18371776
23:18:31 1545 8192000000 10192537616 10572255232 1274010448 13.458 100 93 184 2 1309031472 14751280 20269744
23:19:35 3553 8192000000 10217913552 10605826048 1301610544 13.710 100 82 164 2 1334242784 13911584 18720656
23:20:49 20626 8192000000 10193108608 10602110976 1308628960 13.774 100 26 52 0 1321211824 6240704 6342160
23:22:12 7561 8192000000 10204722016 10618241024 1318116784 13.860 100 22 44 1 1329909360 7134896 4657680
23:23:22 12736 8192000000 10207312960 10625945600 1325830080 13.929 100 16 34 0 1334270096 4934032 3505984
23:24:37 62189 8192000000 10231679696 10644971520 1333147680 13.996 100 31 63 0 1346693504 6151792 7394032
23:26:41 9768 8192000000 10227684448 10649554944 1337181408 14.032 100 17 35 1 1346638656 4974016 4483232
23:27:52 6025 8192000000 10226828656 10642984960 1331434768 13.980 100 34 70 0 1345598464 6672240 7491456
23:29:00 46090 8192000000 10170389184 10580422656 1258303664 13.314 97 23 46 0 1268949888 5236064 5410160
23:30:49 5343 8192000000 10155462800 10556715008 1242562672 13.170 98 23 46 0 1253627232 5845856 5218704
23:31:55 2866 8192000000 10142748624 10532814848 1238941872 13.136 99 37 70 2 1253533904 6672960 7919072
23:33:00 11079 8192000000 10124472304 10524082176 1233287360 13.084 99 19 38 0 1243158672 5586832 4284480
23:34:11 34473 8192000000 10156741648 10567290880 1258817520 13.319 99 33 67 0 1272291648 5921712 7552416
23:35:48 6321 8192000000 10181232112 10588704768 1291560352 13.618 100 22 44 1 1302457216 6229552 4667312
23:36:57 7395 8192000000 10204498560 10607468544 1300100176 13.696 100 41 83 0 1315835920 5285824 10449920
23:38:05 14880 8192000000 10209544832 10617487360 1314578144 13.828 100 16 32 0 1322698320 4770608 3349568
23:39:22 73324 8192000000 10237876624 10653663232 1336793056 14.028 100 50 99 1 1355735760 7499968 11442736
23:41:40 25532 8192000000 10262853968 10682945536 1358290288 14.222 100 50 98 0 1383657056 14252128 11114640
23:43:12 20917 8192000000 10280994688 10700845056 1369891248 14.326 100 38 71 1 1392075552 13345888 8838416
23:44:43 5820 8192000000 10300190704 10717986816 1379330608 14.411 100 50 100 1 1412518992 21563888 11624496
23:45:53 11540 8192000000 10310747888 10725953536 1377216096 14.392 99 54 106 2 1409174752 20224016 11734640
23:47:08 5214 8192000000 10321309728 10735906816 1391118464 14.516 100 64 128 0 1432741584 26266496 15356624
23:48:23 10238 8192000000 10320488384 10746007552 1404207232 14.632 100 50 97 1 1439815104 23388912 12218960
23:49:41 7032 8192000000 10315136832 10739195904 1387345792 14.482 99 43 86 0 1427709184 29154752 11208640
23:50:54 11609 8192000000 10326301696 10755633152 1401595328 14.609 100 48 96 1 1441526848 28168064 11763456
23:52:14 4865 8192000000 10311494688 10729390080 1378321872 14.402 100 56 110 1 1421899584 29450848 14126864
23:53:33 6471 8192000000 10323587280 10742984704 1386380880 14.474 100 57 109 1 1432378144 31990608 14006656
23:54:43 7007 8192000000 10333023296 10744209408 1383318560 14.446 100 67 134 1 1435557696 35879568 16359568
23:55:58 3818 8192000000 10312659264 10722025472 1364382832 14.277 100 70 138 1 1417649376 36135376 17131168
23:57:07 2091 8192000000 10324763056 10722770944 1356121376 14.203 99 88 176 0 1417899856 40616912 21161568
23:58:16 2551 8192000000 10343906096 10747969536 1381848096 14.433 100 70 140 1 1445224704 45367312 18009296
23:59:22 7004 8192000000 10315980576 10747015168 1377453472 14.394 100 35 70 0 1423448016 36660416 9334128
00:00:50 5472 8192000000 10325602048 10744934400 1369181232 14.320 99 53 106 1 1424032320 40897376 13953712
00:02:00 16270 8192000000 10319799760 10753789952 1380613904 14.422 100 32 64 1 1430026864 40934960 8478000
00:03:21 31037 8192000000 10299089088 10739044352 1361548272 14.251 99 24 46 0 1406070464 38087744 6434448
00:04:53 20007 8192000000 10298602336 10743754752 1368915376 14.317 100 19 38 0 1403583408 29311696 5356336
00:06:17 34872 8192000000 10279711712 10735255552 1361603024 14.252 99 16 30 0 1393432064 27779520 4049520
00:08:19 76954 8192000000 10289793056 10731085824 1343342864 14.088 97 37 72 1 1361211776 9823808 8045104
00:10:43 68849 8192000000 10297953248 10736799744 1384739024 14.459 100 32 63 0 1402287664 9954448 7594192
00:12:56 32122 8192000000 10303079552 10745675776 1387165792 14.481 100 39 78 0 1408547808 12584496 8797520
00:14:48 55288 8192000000 10294791472 10736652288 1367419632 14.304 98 42 84 0 1392065680 14747344 9898704
00:16:50 37109 8192000000 10309320368 10754056192 1396971408 14.568 100 34 66 0 1419281904 14471728 7838768
00:18:50 52136 8192000000 10307034864 10762797056 1403041776 14.622 100 19 38 0 1427170352 19561616 4566960
00:21:56 48975 8192000000 10303728112 10758438912 1378444752 14.403 98 19 38 0 1396550144 13451904 4653488
00:23:55 40982 8192000000 10298683216 10765201408 1384941568 14.461 99 19 38 0 1402122864 13081520 4099776
00:25:36 51976 8192000000 10295343600 10782478336 1400534608 14.600 100 7 14 0 1421488784 19436496 1517680
00:27:37 37276 8192000000 10297792128 10764791808 1390759376 14.513 99 8 16 0 1399939728 7064880 2115472
00:29:15 40700 8192000000 10297804528 10754871296 1388643696 14.494 99 15 30 0 1399353776 7657328 3052752
00:31:04 42079 8192000000 10301463072 10766704640 1394961984 14.550 100 30 61 0 1416742784 15203248 6577552
00:32:56 34213 8192000000 10289770720 10738671616 1339858624 14.056 96 26 52 1 1353760944 8450000 5452320
00:34:43 43322 8192000000 10280799664 10730831872 1359525952 14.233 98 18 36 0 1380622096 16684176 4411968
00:36:45 46919 8192000000 10292863232 10736762880 1343446752 14.088 97 26 52 0 1357318544 8004512 5867280
00:38:32 61021 8192000000 10259318544 10702790656 1296763664 13.666 95 27 54 0 1318811024 15890816 6156544
00:40:37 77678 8192000000 10276687328 10729680896 1370031488 14.327 100 16 31 0 1386350784 13090560 3228736
00:43:23 42891 8192000000 10267579216 10728595456 1337507056 14.035 97 14 28 0 1352662448 12007152 3148240
00:45:07 77115 8192000000 10276266896 10738249728 1353612592 14.180 99 9 18 0 1366708464 10761072 2334800
00:47:44 55065 8192000000 10279633136 10720800768 1326260528 13.933 97 38 76 0 1345555632 9609296 9685808
00:49:49 96708 8192000000 10282956848 10736488448 1343592336 14.090 98 8 16 0 1360434192 14889168 1952688
00:52:27 70852 8192000000 10268113152 10720862208 1292103344 13.623 94 7 14 0 1308125696 14246544 1775808
00:54:38 53492 8192000000 10283714448 10727022592 1349257808 14.141 99 31 62 0 1370230256 14186192 6786256
00:56:35 78550 8192000000 10293205056 10755039232 1370344096 14.330 100 7 14 1 1396510592 24207952 1958544
00:58:53 138385 8192000000 10275294320 10730291200 1364768496 14.280 99 18 36 0 1382943904 13945488 4229920
01:02:15 27342 8192000000 10294627200 10739519488 1371319776 14.339 99 34 68 0 1391266256 12761152 7185328
01:03:44 55154 8192000000 10277438112 10737770496 1355826592 14.200 98 26 52 0 1374918240 13716896 5374752
01:05:47 49864 8192000000 10278236288 10741547008 1382543360 14.439 100 6 12 0 1391275408 7440288 1291760
01:07:45 60438 8192000000 10278504128 10742161408 1384208336 14.454 100 7 14 0 1395129792 9613648 1307808
01:10:27 76027 8192000000 10285744272 10758598656 1368534976 14.314 98 9 18 0 1382288096 12019712 1733408
01:12:46 64259 8192000000 10278616304 10748952576 1343595072 14.090 96 14 28 0 1356493888 9663392 3235424
01:14:57 47655 8192000000 10288823536 10743902208 1351733712 14.163 96 31 62 0 1370278512 11062336 7482464
01:16:55 86835 8192000000 10303997456 10765578240 1360796144 14.245 97 17 34 0 1378031104 12884592 4350368
01:20:20 65255 8192000000 10305105200 10759847936 1398968848 14.586 100 18 36 0 1416462224 13101632 4391744
01:22:27 24764 8192000000 10306920448 10755158016 1369403936 14.322 98 37 73 0 1388157568 10743152 8010480
01:23:53 69844 8192000000 10281769504 10739294208 1299798000 13.693 93 19 38 0 1318264256 14445472 4020784
01:26:03 127341 8192000000 10276944800 10727469056 1326426416 13.935 96 29 58 0 1344454320 11505648 6522256
01:29:12 60584 8192000000 10264200624 10734276608 1361637152 14.252 99 14 28 0 1377262144 12049664 3575328
01:31:20 53461 8192000000 10255915024 10714599424 1340223360 14.059 98 12 24 0 1352220864 9147296 2850208
01:33:24 43350 8192000000 10258859104 10703757312 1330101424 13.968 98 16 32 0 1347200016 13253648 3844944
01:35:24 84911 8192000000 10256593632 10719666176 1326320112 13.934 98 8 16 0 1345318656 17385504 1613040
01:37:49 52734 8192000000 9142524944 9389338624 283388864 3.343 22 7 14 0 289727440 4817456 1521120

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-02-2023 01:39:55 to 17-02-2023 01:40:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          246281
MON$OLDEST_ACTIVE               246282
MON$OLDEST_SNAPSHOT             246282
MON$NEXT_TRANSACTION            246282
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                       2037536
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 ---
:89: 
:90: mscs-test-161.budzdorov.ru    Fri Feb 17 01:38:48 2023
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Fri Feb 17 01:38:49 2023
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:96: 
--- end of diff output ---

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

17.02.23 01:40:14. 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-02-17 01:40:17 No severe PSQL-related problems occured
17.02.23 01:40:18. End of report.