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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-17 19:38:28
DTS_END                         2022-10-17 19:38:28.5020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60602
TRN_ID                          249342
ATT_ID                          657
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: 9477.30 22811 1137276
customer order: creation 181.08 356 21729
customer order: refuse 15.53 325 1863
order to supplier: creation 48.93 1281 5871
order to supplier: removal 3.51 93745 421
invoice (draft): creation 48.63 3234 5835
invoice (draft): removal 52.99 762 6359
invoice accept: apply 44.22 17591 5306
invoice accept: cancel 47.87 708 5744
customer reserve: creation 4045.02 175 485402
customer reserve: removal 4672.47 54 560696
realization accept: apply 88.20 212 10584
realization accept: cancel 91.34 102 10961
payment from customer: creation 63.24 170 7589
payment from customer: removal 6.02 67 722
payment to supplier: creation 52.16 180 6259
payment to supplier: removal 3.57 95 428
service: total inventory turnovers 4.81 1185 577
service: total monetary turnovers 6.86 32 823
service: aggregate perf. data 0.86 20867 103
service: refresh index statistics 0.03 315083 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2022 19:39:30 to 17-10-2022 19:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2247
TEST_TIME 2 2608
TEST_TIME 3 3098
TEST_TIME 4 3391
TEST_TIME 5 3354
TEST_TIME 6 4223
TEST_TIME 7 4550
TEST_TIME 8 5271
TEST_TIME 9 5364
TEST_TIME 10 5416
TEST_TIME 11 5931
TEST_TIME 12 6021
TEST_TIME 13 5931
TEST_TIME 14 6233
TEST_TIME 15 6598
TEST_TIME 16 6675
TEST_TIME 17 7381
TEST_TIME 18 7435
TEST_TIME 19 7902
TEST_TIME 20 8183
TEST_TIME 21 8008
TEST_TIME 22 8368
TEST_TIME 23 8682
TEST_TIME 24 8932
TEST_TIME 25 8950
TEST_TIME 26 9169
TEST_TIME 27 9123
TEST_TIME 28 9410
TEST_TIME 29 9657
TEST_TIME 30 10529
TEST_TIME 31 10472
TEST_TIME 32 10392
TEST_TIME 33 10528
TEST_TIME 34 10429
TEST_TIME 35 10623
TEST_TIME 36 10565
TEST_TIME 37 10424
TEST_TIME 38 10328
TEST_TIME 39 10086
TEST_TIME 40 10143
TEST_TIME 41 10080
TEST_TIME 42 9934
TEST_TIME 43 9878
TEST_TIME 44 9802
TEST_TIME 45 9688
TEST_TIME 46 9878
TEST_TIME 47 9857
TEST_TIME 48 9949
TEST_TIME 49 9820
TEST_TIME 50 9803
TEST_TIME 51 9712
TEST_TIME 52 9721
TEST_TIME 53 9590
TEST_TIME 54 9578
TEST_TIME 55 9447
TEST_TIME 56 9353
TEST_TIME 57 9345
TEST_TIME 58 9274
TEST_TIME 59 9198
TEST_TIME 60 9173
TEST_TIME 61 9230
TEST_TIME 62 9406
TEST_TIME 63 9429
TEST_TIME 64 9471
TEST_TIME 65 9451
TEST_TIME 66 9422
TEST_TIME 67 9532
TEST_TIME 68 9572
TEST_TIME 69 9622
TEST_TIME 70 9668
TEST_TIME 71 9656
TEST_TIME 72 9650
TEST_TIME 73 9678
TEST_TIME 74 9596
TEST_TIME 75 9603
TEST_TIME 76 9526
TEST_TIME 77 9531
TEST_TIME 78 9517
TEST_TIME 79 9454
TEST_TIME 80 9387
TEST_TIME 81 9339
TEST_TIME 82 9262
TEST_TIME 83 9388
TEST_TIME 84 9387
TEST_TIME 85 9362
TEST_TIME 86 9410
TEST_TIME 87 9397
TEST_TIME 88 9408
TEST_TIME 89 9418
TEST_TIME 90 9452
TEST_TIME 91 9504
TEST_TIME 92 9568
TEST_TIME 93 9573
TEST_TIME 94 9659
TEST_TIME 95 9619
TEST_TIME 96 9649
TEST_TIME 97 9576
TEST_TIME 98 9534
TEST_TIME 99 9495
TEST_TIME 100 9518
TEST_TIME 101 9478
TEST_TIME 102 9426
TEST_TIME 103 9373
TEST_TIME 104 9300
TEST_TIME 105 9437
TEST_TIME 106 9410
TEST_TIME 107 9388
TEST_TIME 108 9395
TEST_TIME 109 9434
TEST_TIME 110 9449
TEST_TIME 111 9430
TEST_TIME 112 9470
TEST_TIME 113 9512
TEST_TIME 114 9515
TEST_TIME 115 9544
TEST_TIME 116 9578
TEST_TIME 117 9610
TEST_TIME 118 9601
TEST_TIME 119 9534
TEST_TIME 120 9479

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-10-2022 19:39:34 to 17-10-2022 19:39:37.

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 1587543 1587543 0 0.00 0 46850 7 0 0
doc_list_biud 1587543 1587543 0 0.00 0 28242 25 0 0
sp_add_invoice_to_stock 5439 5306 133 2.44 47 200385 17591 0 133
sp_cancel_adding_invoice 5744 5744 0 0.00 3 68637 709 0 0
sp_cancel_client_order 1863 1863 0 0.00 4 7795 325 0 0
sp_cancel_customer_reserve 560696 560696 0 0.00 0 28243 55 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 2 4322 67 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 2 9853 95 0 0
sp_cancel_supplier_invoice 6372 6359 13 0.20 1 20227 763 0 13
sp_cancel_supplier_order 421 421 0 0.00 7 931524 93746 0 0
sp_cancel_write_off 10961 10961 0 0.00 1 14043 102 0 0
sp_client_order 21729 21729 0 0.00 3 19592 356 0 0
sp_customer_reserve 485402 485402 0 0.00 0 129000 175 0 0
sp_fill_shopping_cart 33431 33431 0 0.00 1 19676 42 0 0
sp_fill_shopping_cart_clo_res 485401 485401 0 0.00 0 17083 14 0 0
sp_get_clo_for_invoice 5304 5304 0 0.00 0 40786 318 0 0
sp_kill_cost_storno 17855 17855 0 0.00 0 5428 48 0 0
sp_kill_qstorno_ret_qs2qd 1139133 1139133 0 0.00 0 28239 30 0 0
sp_kill_qty_storno 601931 601931 0 0.00 0 28242 65 0 0
sp_lock_selected_doc 21965 21965 0 0.00 0 8293 5 0 0
sp_make_cost_storno 29735 29735 0 0.00 0 46828 87 0 0
sp_multiply_rows_for_pdistr 29735 29735 0 0.00 0 25390 40 0 0
sp_multiply_rows_for_qdistr 21729 21729 0 0.00 0 12980 140 0 0
sp_pay_from_customer 7589 7589 0 0.00 4 29019 171 0 0
sp_pay_to_supplier 6259 6259 0 0.00 4 44468 180 0 0
sp_payment_common 13848 13848 0 0.00 1 28204 45 0 0
sp_qd_handle_on_cancel_clo 1863 1863 0 0.00 0 7737 307 0 0
sp_qd_handle_on_invoice_upd_sts 11047 11047 0 0.00 0 23727 319 0 0
sp_qd_handle_on_reserve_upd_sts 10584 10584 0 0.00 0 18037 66 0 0
sp_reserve_write_off 10584 10584 0 0.00 6 43858 212 0 0
sp_supplier_invoice 5835 5835 0 0.00 6 78988 3234 0 0
sp_supplier_order 5871 5871 0 0.00 5 32874 1282 0 0
srv_aggregate_perf_data 103 103 0 0.00 2176 393441 20868 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1137 119464 41908 0 0
srv_find_qd_qs_mism 1087240 1087240 0 0.00 0 11520 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 32 60405 1186 0 0
srv_make_money_saldo 823 823 0 0.00 0 2488 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 243027 401011 315084 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5307 5307 0 0.00 0 26557 187 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 6913 97 0 0
t$perf-norm:sp_cancel_client_order 1863 1863 0 0.00 0 4204 12 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 921 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 2557 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 5074 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 1312 22 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 21361 1506 0 0
t$perf-norm:sp_cancel_write_off 1087 1087 0 0.00 0 2066 8 0 0
t$perf-norm:sp_client_order 21729 21729 0 0.00 0 10944 18 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 1919 6 0 0
t$perf-norm:sp_pay_from_customer 7589 7589 0 0.00 0 10949 16 0 0
t$perf-norm:sp_pay_to_supplier 6259 6259 0 0.00 0 5137 13 0 0
t$perf-norm:sp_reserve_write_off 10584 10584 0 0.00 0 5119 12 0 0
t$perf-norm:sp_supplier_invoice 5835 5835 0 0.00 0 5681 20 0 0
t$perf-norm:sp_supplier_order 5871 5871 0 0.00 0 3915 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 322 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 231 5 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 710 24 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 964 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10872 10606 266 2.44 0 6267 20 0 266
v_all_customers 39712 39712 0 0.00 0 146 1 0 0
v_all_suppliers 32242 32242 0 0.00 0 26 1 0 0
v_all_wares 43458 43458 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1890 12 0 0
v_cancel_client_order 3726 3726 0 0.00 0 1462 4 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 1408 5 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 1066 6 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 4331 10 0 0
v_cancel_supplier_invoice 850 824 26 3.05 0 508 15 0 26
v_cancel_supplier_order 842 842 0 0.00 0 2954 9 0 0
v_cancel_write_off 2174 2174 0 0.00 0 1055 13 0 0
v_max_id_clo_ord 5870 5870 0 0.00 0 3969 2 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 2653 15 0 0
v_max_id_ord_sup 5832 5832 0 0.00 0 5510 5 0 0
v_max_non_paid_invoice 1840 1840 0 0.00 0 25096 19 0 0
v_max_non_paid_realizn 4060 4060 0 0.00 0 2898 5 0 0
v_min_id_clo_ord 5870 5870 0 0.00 0 7240 15 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 10987 23 0 0
v_min_id_ord_sup 5832 5832 0 0.00 0 3792 6 0 0
v_min_non_paid_invoice 6259 6259 0 0.00 0 27773 26 0 0
v_min_non_paid_realizn 7589 7589 0 0.00 0 28194 37 0 0
v_our_firm 10804 10804 0 0.00 0 18 1 0 0
v_reserve_write_off 21168 21168 0 0.00 0 8781 7 0 0
x_lock_depdocs_on_canc_invoice 11691 11691 0 0.00 0 25733 121 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 16768 354 0 0
x_make_qstorno_1000_1200 5871 5871 0 0.00 1 31623 1190 0 0
x_make_qstorno_1200_2000 5835 5835 0 0.00 2 78961 3091 0 0
x_make_qstorno_2100_3300 485402 485402 0 0.00 0 128999 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2022 19:39:38 to 17-10-2022 19:39:42.

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
17:08:32 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:33 32293 8192000000 9915092272 10230419456 995515408 10.835 100 87 174 0 1024799424 6717728 22566288
17:11:10 20564 8192000000 9952618160 10277961728 1034871728 11.215 100 89 178 0 1067681760 10204368 22605664
17:12:37 12069 8192000000 10004294352 10340089856 1080067952 11.648 100 92 183 0 1114946768 12199424 22679392
17:13:51 9483 8192000000 10036377936 10383925248 1115630816 11.986 100 82 162 0 1147429600 12748096 19050688
17:15:06 5525 8192000000 10069539312 10426830848 1156556032 12.371 100 88 177 0 1190693104 13184432 20952640
17:16:24 5242 8192000000 10109452896 10476400640 1201916320 12.794 100 71 142 2 1230085552 11739504 16429728
17:17:31 1822 8192000000 10152646048 10521513984 1238481184 13.132 100 95 189 1 1274333088 14766896 21085008
17:18:35 2435 8192000000 10173935808 10556153856 1265703680 13.382 100 82 162 1 1296583872 13358608 17521584
17:19:39 1511 8192000000 10203261376 10585452544 1289733472 13.602 100 89 180 1 1322072832 12938560 19400800
17:20:44 1906 8192000000 10200655728 10593959936 1295289328 13.652 100 81 161 0 1321967984 8380304 18298352
17:21:46 9829 8192000000 10192775856 10604212224 1305629216 13.746 100 21 43 0 1316359616 5998160 4732240
17:22:59 10961 8192000000 10201975712 10614788096 1312527824 13.809 100 16 32 0 1322331440 6405040 3398576
17:24:15 47355 8192000000 10218780000 10635452416 1319057664 13.868 100 21 42 0 1329171200 5748960 4364576
17:26:17 5583 8192000000 10223233456 10640904192 1327925312 13.948 100 21 42 2 1338446144 6316304 4204528
17:27:25 6648 8192000000 10220387984 10641240064 1325049680 13.922 100 23 46 0 1336719616 6771184 4898752
17:28:32 7855 8192000000 10192117920 10611396608 1289104016 13.596 98 13 26 0 1297666256 5964064 2598176
17:29:40 30100 8192000000 10165471344 10576863232 1216252416 12.927 93 23 44 0 1226211328 5160752 4798160
17:31:13 10620 8192000000 10141103312 10546565120 1249355728 13.232 98 18 36 0 1259137088 5464416 4316944
17:32:28 6703 8192000000 10150936624 10551447552 1234474240 13.095 97 21 41 1 1245163184 5349840 5339104
17:33:37 60622 8192000000 10176394272 10574016512 1270049744 13.422 100 25 51 0 1282425776 5878192 6497840
17:35:47 5699 8192000000 10184232288 10594811904 1292653440 13.628 100 34 64 0 1306406960 6449008 7304512
17:36:54 6064 8192000000 10191737408 10605293568 1297407840 13.672 100 23 47 0 1311749664 9413776 4928048
17:38:01 15462 8192000000 10212230544 10626064384 1319139088 13.869 100 21 41 2 1327745440 4309952 4296400
17:39:23 90546 8192000000 10247821056 10664480768 1341689728 14.073 100 48 93 2 1360234544 8234944 10309872
17:41:56 10300 8192000000 10260111232 10676359168 1357669168 14.216 100 32 64 0 1373468496 8172432 7626896
17:43:11 35782 8192000000 10275763440 10700496896 1374301296 14.366 100 37 74 0 1394858976 11310672 9247008
17:44:51 1957 8192000000 10308384896 10722631680 1387955104 14.488 100 67 134 0 1417847904 13966416 15926384
17:45:58 12349 8192000000 10303003200 10725314560 1380183824 14.418 99 41 79 1 1405100512 15664672 9252016
17:47:17 7897 8192000000 10322756752 10745704448 1390932688 14.514 99 52 103 0 1420542736 17719008 11891040
17:48:30 12077 8192000000 10324198608 10742906880 1389170064 14.498 99 60 120 0 1428806720 24896320 14740336
17:49:51 13619 8192000000 10319145568 10746351616 1390100000 14.507 99 43 86 0 1423233248 22953984 10179264
17:51:10 8146 8192000000 10323720032 10747437056 1397525440 14.573 99 48 97 0 1435328768 26458688 11344640
17:52:24 1784 8192000000 10362887296 10773245952 1412368304 14.705 100 76 148 0 1463512336 32125952 19018080
17:53:31 3653 8192000000 10346879312 10754355200 1397391408 14.572 100 76 152 0 1451125584 35301024 18433152
17:54:38 1135 8192000000 10344118112 10751094784 1381237280 14.428 99 82 164 0 1441164752 40278736 19648736
17:55:48 6602 8192000000 10336639232 10748936192 1376733936 14.387 99 73 144 0 1428609440 34532512 17342992
17:56:59 5692 8192000000 10341475696 10756112384 1378074464 14.399 99 60 120 0 1435794464 42272224 15447776
17:58:25 13199 8192000000 10323366688 10744270848 1362506848 14.260 98 42 84 0 1407671968 34568080 10597040
17:59:42 4183 8192000000 10335273792 10750242816 1388253840 14.490 100 54 106 3 1442974896 40331008 14390048
18:00:49 10689 8192000000 10330377456 10760413184 1372148064 14.346 99 41 82 0 1435129824 51726080 11255680
18:02:03 9549 8192000000 10321899760 10750185472 1372750720 14.352 100 37 74 0 1429518560 46399744 10368096
18:03:20 14768 8192000000 10328191248 10755534848 1382167024 14.436 100 32 60 0 1434163072 43363200 8632848
18:04:35 18629 8192000000 10325328352 10765066240 1376202512 14.383 99 25 50 0 1426588656 43285472 7100672
18:05:56 26417 8192000000 10291304560 10738970624 1346369280 14.115 98 20 41 0 1393895200 41475328 6050592
18:07:51 37253 8192000000 10285693216 10736922624 1356943872 14.210 100 17 34 0 1402074176 40878352 4251952
18:09:42 83522 8192000000 10279992256 10722275328 1358047680 14.220 99 16 32 0 1372719152 11385920 3285552
18:12:06 26616 8192000000 10298830848 10739376128 1380614224 14.422 100 11 21 0 1388859888 5642720 2602944
18:13:33 72929 8192000000 10297085840 10747973632 1365926752 14.291 99 9 18 0 1382847520 15073280 1847488
18:15:56 106185 8192000000 10313874464 10751418368 1391733504 14.521 100 30 60 0 1409798160 11018752 7045904
18:18:46 51602 8192000000 10322825968 10764574720 1389526608 14.502 99 35 69 1 1413163408 15499888 8136912
18:20:42 43778 8192000000 10322412352 10764431360 1404597440 14.636 100 27 54 0 1423687440 12531600 6558400
18:22:27 47830 8192000000 10312127328 10762321920 1380404384 14.420 98 16 32 0 1402826464 18865312 3556768
18:24:21 33532 8192000000 10311565344 10751397888 1380605264 14.422 99 39 79 0 1410222256 19692512 9924480
18:26:03 49180 8192000000 10284845568 10751324160 1342654000 14.081 96 16 33 0 1361484816 15008768 3822048
18:27:52 119464 8192000000 10277582016 10738008064 1347313632 14.123 97 15 30 1 1364067008 13013840 3739536
18:31:20 14416 8192000000 10272622912 10727026688 1362887056 14.263 99 22 41 1 1374831744 7546816 4397872
18:32:35 106439 8192000000 10281352464 10721234944 1313505088 13.818 95 20 40 1 1332115776 14039360 4571328
18:35:22 53689 8192000000 10277703280 10730213376 1342604608 14.081 97 31 61 0 1359755504 9994320 7156576
18:37:20 58578 8192000000 10286124544 10752393216 1381676208 14.432 100 21 42 0 1409306976 23044224 4586544
18:39:22 45439 8192000000 10280836688 10752204800 1367034080 14.300 99 9 18 0 1393106480 24122880 1949520
18:41:10 47595 8192000000 10298450480 10755805184 1375719104 14.378 99 20 39 0 1388487232 9094512 3673616
18:43:02 39963 8192000000 10295082912 10742300672 1353570080 14.180 98 18 36 0 1367813216 10261840 3981296
18:44:45 41657 8192000000 10297659200 10751418368 1343105328 14.085 97 28 55 0 1371727328 21935552 6686448
18:46:27 68460 8192000000 10291345888 10752774144 1356001152 14.201 98 24 48 0 1379823632 18586816 5235664
18:48:43 39331 8192000000 10261021744 10709139456 1292215296 13.624 94 12 22 0 1304915072 10240320 2459456
18:50:30 66624 8192000000 10286917856 10731716608 1353189360 14.176 99 18 36 0 1372186416 15006224 3990832
18:53:48 48527 8192000000 10272169360 10726739968 1359588112 14.234 99 14 28 1 1371859968 8926464 3345392
18:55:39 26733 8192000000 10260475664 10722357248 1369112208 14.319 100 42 84 0 1389811328 11336960 9362160
18:57:24 64437 8192000000 10271071136 10729361408 1349862640 14.146 98 9 17 0 1364677424 12780896 2033888
18:59:33 84064 8192000000 10259290848 10716246016 1321621968 13.891 96 13 26 0 1340916160 16273120 3021072
19:02:19 47250 8192000000 10265661040 10723196928 1351337568 14.160 99 24 48 0 1365954992 9390496 5226928
19:04:09 57669 8192000000 10269244560 10730487808 1360154240 14.239 99 15 30 0 1373814368 10020288 3639840
19:06:07 28044 8192000000 10280712944 10731421696 1362437664 14.259 99 28 54 1 1376782976 8804400 5540912
19:07:42 61221 8192000000 10295540144 10747494400 1338527408 14.044 97 18 37 0 1356496800 14139968 3829424
19:09:44 33145 8192000000 10285824112 10738565120 1365016576 14.282 99 16 30 0 1383687808 15232544 3438688
19:11:18 68586 8192000000 10285205344 10748436480 1343654800 14.090 97 12 23 0 1357158400 11458544 2045056
19:13:33 4920 8192000000 10305610960 10739306496 1369408656 14.322 99 59 115 1 1388079136 5389232 13281248
19:14:40 66345 8192000000 10266696432 10722865152 1356966224 14.210 99 11 22 0 1371501040 11733984 2800832
19:17:01 57794 8192000000 10298354704 10751475712 1391634736 14.520 100 33 66 0 1408901360 9076528 8190096
19:19:01 42932 8192000000 10282860944 10746781696 1358239392 14.222 97 16 31 1 1374648592 12750192 3659008
19:20:49 46977 8192000000 10291472032 10748678144 1367935328 14.309 98 11 22 0 1384411104 13819248 2656528
19:22:37 69123 8192000000 10270372656 10749214720 1361334736 14.249 99 14 27 2 1383675632 19027792 3313104
19:26:02 43306 8192000000 10276561264 10730700800 1345652912 14.108 98 23 46 0 1367373248 15645040 6075296
19:27:55 56900 8192000000 10286216640 10732720128 1345464144 14.107 98 20 40 0 1364168464 14081312 4623008
19:29:53 81558 8192000000 10285773360 10740244480 1327442944 13.944 96 14 28 0 1352472064 22131072 2898048
19:32:15 20165 8192000000 10286192352 10725298176 1334564944 14.008 97 34 68 0 1352021648 9555120 7901584
19:33:38 60309 8192000000 10284792064 10740486144 1345499952 14.107 98 14 28 0 1362690432 14076528 3113952
19:36:06 90833 8192000000 10262516176 10723708928 1329505664 13.963 97 12 24 1 1341728848 9466016 2757168

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-10-2022 19:39:44 to 17-10-2022 19:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2022 19:40:00 to 17-10-2022 19:40:02.

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          248999
MON$OLDEST_ACTIVE               249477
MON$OLDEST_SNAPSHOT             249477
MON$NEXT_TRANSACTION            249477
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                       2047648
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:15: 
:16: mscs-test-161.budzdorov.ru    Mon Oct 17 19:38:30 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Mon Oct 17 19:38:33 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Mon Oct 17 19:38:33 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Mon Oct 17 19:38:33 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
--- end of diff output ---

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

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