Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.12.2022 16:40
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 15083712 kB
MemAvailable 30102496 kB
Buffers 0 kB
Cached 16651932 kB
SwapCached 3352 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-12-15 16:38:33
DTS_END                         2022-12-15 16:38:33.4480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38418
TRN_ID                          239300
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: 9365.59 21299 1123871
customer order: creation 168.39 350 20207
customer order: refuse 13.70 361 1644
order to supplier: creation 45.17 1295 5420
order to supplier: removal 3.53 92411 423
invoice (draft): creation 45.01 3342 5401
invoice (draft): removal 53.88 680 6465
invoice accept: apply 41.08 17186 4929
invoice accept: cancel 48.93 722 5871
customer reserve: creation 3824.15 170 458898
customer reserve: removal 4832.44 53 579893
realization accept: apply 81.13 179 9736
realization accept: cancel 83.07 97 9968
payment from customer: creation 57.88 115 6945
payment from customer: removal 5.32 93 638
payment to supplier: creation 46.93 133 5631
payment to supplier: removal 3.20 105 384
service: total inventory turnovers 4.78 883 574
service: total monetary turnovers 6.21 30 745
service: aggregate perf. data 0.79 23317 95
service: refresh index statistics 0.03 284465 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-12-2022 16:40:41 to 15-12-2022 16:40:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1876
TEST_TIME 2 1622
TEST_TIME 3 3058
TEST_TIME 4 3364
TEST_TIME 5 3558
TEST_TIME 6 4613
TEST_TIME 7 5077
TEST_TIME 8 5227
TEST_TIME 9 5201
TEST_TIME 10 5174
TEST_TIME 11 5513
TEST_TIME 12 5682
TEST_TIME 13 6144
TEST_TIME 14 6405
TEST_TIME 15 6371
TEST_TIME 16 6767
TEST_TIME 17 7251
TEST_TIME 18 7288
TEST_TIME 19 7556
TEST_TIME 20 7757
TEST_TIME 21 8149
TEST_TIME 22 8791
TEST_TIME 23 8950
TEST_TIME 24 9029
TEST_TIME 25 9101
TEST_TIME 26 9266
TEST_TIME 27 9802
TEST_TIME 28 10171
TEST_TIME 29 10407
TEST_TIME 30 10237
TEST_TIME 31 10017
TEST_TIME 32 10239
TEST_TIME 33 10284
TEST_TIME 34 10305
TEST_TIME 35 10161
TEST_TIME 36 9903
TEST_TIME 37 10132
TEST_TIME 38 10025
TEST_TIME 39 9905
TEST_TIME 40 9928
TEST_TIME 41 9844
TEST_TIME 42 9834
TEST_TIME 43 9774
TEST_TIME 44 9792
TEST_TIME 45 9631
TEST_TIME 46 9556
TEST_TIME 47 9560
TEST_TIME 48 9590
TEST_TIME 49 9566
TEST_TIME 50 9528
TEST_TIME 51 9500
TEST_TIME 52 9470
TEST_TIME 53 9411
TEST_TIME 54 9558
TEST_TIME 55 9539
TEST_TIME 56 9488
TEST_TIME 57 9503
TEST_TIME 58 9617
TEST_TIME 59 9651
TEST_TIME 60 9693
TEST_TIME 61 9709
TEST_TIME 62 9695
TEST_TIME 63 9698
TEST_TIME 64 9823
TEST_TIME 65 9822
TEST_TIME 66 9859
TEST_TIME 67 9865
TEST_TIME 68 9760
TEST_TIME 69 9690
TEST_TIME 70 9642
TEST_TIME 71 9553
TEST_TIME 72 9566
TEST_TIME 73 9614
TEST_TIME 74 9530
TEST_TIME 75 9522
TEST_TIME 76 9482
TEST_TIME 77 9426
TEST_TIME 78 9424
TEST_TIME 79 9403
TEST_TIME 80 9356
TEST_TIME 81 9451
TEST_TIME 82 9406
TEST_TIME 83 9370
TEST_TIME 84 9442
TEST_TIME 85 9421
TEST_TIME 86 9443
TEST_TIME 87 9510
TEST_TIME 88 9535
TEST_TIME 89 9522
TEST_TIME 90 9536
TEST_TIME 91 9644
TEST_TIME 92 9615
TEST_TIME 93 9558
TEST_TIME 94 9522
TEST_TIME 95 9477
TEST_TIME 96 9423
TEST_TIME 97 9405
TEST_TIME 98 9351
TEST_TIME 99 9317
TEST_TIME 100 9413
TEST_TIME 101 9396
TEST_TIME 102 9360
TEST_TIME 103 9387
TEST_TIME 104 9417
TEST_TIME 105 9458
TEST_TIME 106 9430
TEST_TIME 107 9394
TEST_TIME 108 9493
TEST_TIME 109 9539
TEST_TIME 110 9549
TEST_TIME 111 9580
TEST_TIME 112 9611
TEST_TIME 113 9551
TEST_TIME 114 9508
TEST_TIME 115 9463
TEST_TIME 116 9439
TEST_TIME 117 9412
TEST_TIME 118 9428
TEST_TIME 119 9385
TEST_TIME 120 9366

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-12-2022 16:40:45 to 15-12-2022 16:40: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 1548782 1548782 0 0.00 0 31844 6 0 0
doc_list_biud 1548782 1548782 0 0.00 0 31705 25 0 0
sp_add_invoice_to_stock 5048 4929 119 2.35 26 163954 17186 0 119
sp_cancel_adding_invoice 5871 5871 0 0.00 5 57428 722 0 0
sp_cancel_client_order 1644 1644 0 0.00 4 16783 362 0 0
sp_cancel_customer_reserve 579893 579893 0 0.00 0 24053 54 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 3 6531 94 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 4115 106 0 0
sp_cancel_supplier_invoice 6474 6465 9 0.13 1 26696 680 0 9
sp_cancel_supplier_order 423 423 0 0.00 5 1004091 92412 0 0
sp_cancel_write_off 9968 9968 0 0.00 1 17163 98 0 0
sp_client_order 20207 20207 0 0.00 3 21345 350 0 0
sp_customer_reserve 458898 458898 0 0.00 0 153709 170 0 0
sp_fill_shopping_cart 31003 31003 0 0.00 1 17855 36 0 0
sp_fill_shopping_cart_clo_res 458897 458897 0 0.00 0 26857 13 0 0
sp_get_clo_for_invoice 4928 4928 0 0.00 0 26334 329 0 0
sp_kill_cost_storno 16861 16861 0 0.00 0 31840 49 0 0
sp_kill_qstorno_ret_qs2qd 1176642 1176642 0 0.00 0 26690 29 0 0
sp_kill_qty_storno 618928 618928 0 0.00 0 31705 63 0 0
sp_lock_selected_doc 20137 20137 0 0.00 0 8410 5 0 0
sp_make_cost_storno 27240 27240 0 0.00 0 14257 51 0 0
sp_multiply_rows_for_pdistr 27240 27240 0 0.00 0 8404 26 0 0
sp_multiply_rows_for_qdistr 20207 20207 0 0.00 0 21310 154 0 0
sp_pay_from_customer 6945 6945 0 0.00 4 12366 116 0 0
sp_pay_to_supplier 5631 5631 0 0.00 4 11722 134 0 0
sp_payment_common 12576 12576 0 0.00 1 11709 31 0 0
sp_qd_handle_on_cancel_clo 1644 1644 0 0.00 0 16761 320 0 0
sp_qd_handle_on_invoice_upd_sts 10799 10799 0 0.00 0 31702 310 0 0
sp_qd_handle_on_reserve_upd_sts 9736 9736 0 0.00 0 16998 68 0 0
sp_reserve_write_off 9736 9736 0 0.00 6 21047 179 0 0
sp_supplier_invoice 5401 5401 0 0.00 6 96970 3342 0 0
sp_supplier_order 5420 5420 0 0.00 5 45297 1296 0 0
srv_aggregate_perf_data 95 95 0 0.00 2478 416413 23318 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1405 180598 48357 0 0
srv_find_qd_qs_mism 1077067 1077067 0 0.00 0 23693 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 17 31616 883 0 0
srv_make_money_saldo 745 745 0 0.00 1 4487 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 143479 479285 284466 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4930 4930 0 0.00 0 15121 176 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 0 2271 88 0 0
t$perf-norm:sp_cancel_client_order 1644 1644 0 0.00 0 7055 25 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 5068 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 1642 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 5282 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 939 10 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 22876 1785 0 0
t$perf-norm:sp_cancel_write_off 967 967 0 0.00 0 2399 9 0 0
t$perf-norm:sp_client_order 20207 20207 0 0.00 0 15844 19 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 2945 9 0 0
t$perf-norm:sp_pay_from_customer 6945 6945 0 0.00 0 9952 16 0 0
t$perf-norm:sp_pay_to_supplier 5631 5631 0 0.00 0 5980 12 0 0
t$perf-norm:sp_reserve_write_off 9736 9736 0 0.00 0 13644 14 0 0
t$perf-norm:sp_supplier_invoice 5401 5401 0 0.00 0 6939 20 0 0
t$perf-norm:sp_supplier_order 5420 5420 0 0.00 0 8071 18 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 364 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 13 1 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 1704 17 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 1046 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10094 9856 238 2.35 0 6154 20 0 238
v_all_customers 36784 36784 0 0.00 0 19 1 0 0
v_all_suppliers 29844 29844 0 0.00 0 22 1 0 0
v_all_wares 40414 40414 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 346 6 0 0
v_cancel_client_order 3288 3288 0 0.00 0 4002 12 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 3744 13 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 2645 9 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 4089 10 0 0
v_cancel_supplier_invoice 786 768 18 2.29 0 1331 16 0 18
v_cancel_supplier_order 846 846 0 0.00 0 2208 9 0 0
v_cancel_write_off 1934 1934 0 0.00 0 1433 13 0 0
v_max_id_clo_ord 5419 5419 0 0.00 0 767 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 3440 11 0 0
v_max_id_ord_sup 5377 5377 0 0.00 0 4971 7 0 0
v_max_non_paid_invoice 1505 1505 0 0.00 0 1682 4 0 0
v_max_non_paid_realizn 3689 3689 0 0.00 0 3728 6 0 0
v_min_id_clo_ord 5419 5419 0 0.00 0 1532 4 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 199 2 0 0
v_min_id_ord_sup 5377 5377 0 0.00 0 5509 8 0 0
v_min_non_paid_invoice 5631 5631 0 0.00 0 11703 23 0 0
v_min_non_paid_realizn 6945 6945 0 0.00 0 4474 20 0 0
v_our_firm 10142 10142 0 0.00 0 197 1 0 0
v_reserve_write_off 19472 19472 0 0.00 0 3525 8 0 0
x_lock_depdocs_on_canc_invoice 11952 11952 0 0.00 0 21272 100 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 6926 286 0 0
x_make_qstorno_1000_1200 5420 5420 0 0.00 1 45280 1228 0 0
x_make_qstorno_1200_2000 5401 5401 0 0.00 3 94190 3205 0 0
x_make_qstorno_2100_3300 458898 458898 0 0.00 0 153709 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2022 16:40:49 to 15-12-2022 16:40: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
14:08:37 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:38 26267 8192000000 9904268624 10217791488 990231856 10.784 100 86 171 0 1017854960 6006912 21616192
14:11:14 22255 8192000000 9953536704 10278993920 1037289248 11.239 100 89 178 0 1068636016 8543920 22802848
14:12:41 12698 8192000000 9984012848 10321051648 1072829440 11.579 100 85 170 0 1103533360 9758336 20945584
14:13:58 10679 8192000000 10023431680 10368806912 1109164704 11.925 100 77 154 0 1138467968 10751776 18551488
14:15:14 6729 8192000000 10065794640 10422607872 1155906800 12.365 100 76 151 0 1185044496 11661024 17476672
14:16:23 3455 8192000000 10111878192 10478526464 1205121280 12.824 100 73 146 0 1233478672 11914880 16442512
14:17:29 2246 8192000000 10146689440 10518626304 1231982528 13.072 100 80 160 0 1262631184 12309392 18339264
14:18:34 2070 8192000000 10183625376 10564034560 1266780400 13.392 100 82 162 1 1297720880 12521296 18419184
14:19:38 1589 8192000000 10202079200 10586034176 1294038960 13.641 100 90 177 2 1324830592 10908352 19883280
14:20:40 8856 8192000000 10208904576 10603122688 1301468688 13.709 100 60 117 1 1324523312 10465808 12588816
14:21:50 3625 8192000000 10203187584 10612764672 1311681328 13.801 100 37 70 0 1325698176 6091952 7924896
14:22:58 9861 8192000000 10214767440 10626113536 1319644496 13.873 100 20 41 1 1333784768 9885056 4255216
14:24:08 65076 8192000000 10213105488 10635350016 1326542848 13.936 100 20 40 0 1336937216 6160048 4234320
14:26:18 7422 8192000000 10244613440 10657345536 1341775552 14.073 100 24 46 1 1353242544 6091184 5375808
14:27:28 6578 8192000000 10239326464 10658287616 1347060336 14.121 100 22 44 0 1358611776 6063296 5488144
14:28:38 20204 8192000000 10193688928 10605219840 1276773264 13.484 97 26 52 1 1288231120 5173184 6284672
14:29:58 19277 8192000000 10174577120 10589462528 1230165280 13.056 94 15 30 1 1238675680 4970368 3540032
14:31:23 6973 8192000000 10104985728 10507821056 1198150544 12.759 96 25 50 0 1209376256 5578496 5647216
14:32:30 11165 8192000000 10136221872 10540425216 1230936672 13.063 98 17 34 0 1240160992 5283424 3940896
14:33:43 73207 8192000000 10171806608 10581250048 1268729552 13.410 99 30 59 1 1280609760 5664000 6216208
14:36:00 6171 8192000000 10177820576 10592063488 1290794944 13.611 100 30 60 0 1303741712 5784032 7162736
14:37:07 8282 8192000000 10198595040 10608889856 1301305360 13.707 100 20 38 3 1310393424 4844272 4243792
14:38:16 56224 8192000000 10223972176 10635980800 1320551808 13.882 100 32 64 1 1332596048 4134944 7909296
14:41:18 14616 8192000000 10249997744 10666831872 1346529792 14.116 100 49 95 1 1367892752 10360624 11002336
14:42:39 30520 8192000000 10261692688 10685472768 1356117824 14.202 100 42 82 1 1379030432 12978896 9933712
14:44:17 11370 8192000000 10289905360 10709520384 1372035760 14.345 100 46 88 2 1398931744 16383088 10512896
14:45:33 4518 8192000000 10300919120 10730520576 1386376752 14.474 100 41 82 1 1415794416 19841504 9576160
14:46:42 4414 8192000000 10324548560 10748002304 1398210576 14.579 100 56 108 0 1432648928 22078144 12360208
14:47:50 16283 8192000000 10336379792 10763579392 1410542192 14.689 100 43 82 0 1445723040 24849936 10330912
14:49:14 6319 8192000000 10348437440 10771701760 1413831456 14.718 100 53 104 0 1456130112 29254656 13044000
14:50:25 4524 8192000000 10350743216 10767474688 1403993888 14.631 99 79 156 0 1454618352 31946176 18678288
14:51:40 3786 8192000000 10318373712 10741792768 1390258832 14.508 100 46 92 1 1429486016 27979920 11247264
14:52:51 2308 8192000000 10308003776 10723188736 1364171376 14.275 99 59 117 1 1409459536 30601408 14686752
14:53:59 1622 8192000000 10308289520 10715111424 1358903696 14.228 98 69 136 4 1410183952 35403280 15876976
14:55:03 6097 8192000000 10319765712 10729975808 1368470304 14.313 100 60 120 1 1426066944 42123648 15472992
14:56:16 9634 8192000000 10312944688 10718732288 1357788256 14.217 100 65 130 0 1414361872 40770096 15803520
14:57:31 1404 8192000000 10292030640 10707300352 1329741344 13.965 98 64 125 1 1385932336 40526864 15664128
14:58:40 20656 8192000000 10307762688 10718818304 1345384368 14.106 99 46 90 0 1402116064 44399696 12332000
15:00:02 10132 8192000000 10283115280 10707181568 1346850080 14.119 100 38 76 0 1401967936 44480096 10637760
15:01:17 33251 8192000000 10281004064 10712223744 1344761632 14.100 100 30 59 1 1385708400 33324608 7622160
15:03:07 17699 8192000000 10269102736 10702159872 1347786256 14.128 100 25 50 0 1386921616 32529584 6605776
15:04:30 24788 8192000000 10273884800 10704969728 1331419104 13.980 99 16 32 0 1368605408 32931696 4254608
15:05:56 44308 8192000000 10261421664 10709958656 1338341072 14.042 99 16 32 1 1369522896 26624288 4557536
15:07:41 42799 8192000000 10265986288 10697625600 1342157968 14.077 99 20 42 0 1356427616 8545744 5723904
15:09:27 31268 8192000000 10310886320 10729844736 1375318064 14.375 100 51 95 1 1397934848 11869568 10747216
15:11:10 82584 8192000000 10296761776 10739900416 1342488752 14.080 97 14 29 0 1359218288 13701008 3028528
15:13:33 93493 8192000000 10318531776 10753667072 1384220592 14.454 100 29 57 0 1411144272 19362944 7560736
15:16:08 180598 8192000000 10309864496 10766409728 1398519184 14.582 100 32 64 1 1413667616 8708624 6439808
15:20:13 161682 8192000000 10320285088 10788544512 1402470496 14.617 99 13 24 0 1419135392 13600256 3064640
15:24:29 68080 8192000000 10325966560 10790690816 1420094960 14.774 100 18 36 1 1439988336 15653056 4240320
15:26:42 53230 8192000000 10321809472 10788356096 1418232784 14.757 100 17 32 0 1437218352 15085920 3899648
15:28:48 46134 8192000000 10333363376 10804031488 1427535584 14.839 100 17 34 0 1449879648 18195792 4148272
15:30:57 58957 8192000000 10322519712 10804199424 1392722992 14.530 98 7 14 0 1413396000 19070432 1602576
15:32:59 27807 8192000000 10333785296 10791342080 1407920032 14.665 99 33 65 2 1430386432 15651888 6814512
15:34:31 52347 8192000000 10310987632 10771730432 1337307056 14.033 94 17 34 0 1359594912 18534592 3753264
15:36:26 52836 8192000000 10281597664 10747838464 1349008224 14.139 97 12 23 0 1370945600 18877680 3059696
15:38:29 47914 8192000000 10292326064 10735419392 1342398768 14.079 97 45 87 0 1361417024 9850400 9167856
15:40:17 37500 8192000000 10305192272 10754068480 1388932048 14.496 100 31 60 2 1404732912 9446848 6354016
15:41:58 107822 8192000000 10280379776 10736021504 1275954976 13.476 92 24 47 0 1301064736 20168976 4940784
15:44:46 66224 8192000000 10254258496 10707169280 1313353488 13.816 96 22 44 0 1335372608 16482880 5536240
15:46:53 58565 8192000000 10260421776 10702852096 1331161408 13.978 98 38 76 0 1346409968 6050816 9197744
15:48:56 39547 8192000000 10242009808 10679541760 1330193536 13.969 99 27 51 0 1344545792 8367136 5985120
15:50:36 53411 8192000000 10258736896 10710228992 1350407792 14.151 100 20 41 0 1368534768 13669424 4457552
15:53:24 69709 8192000000 10245843632 10696421376 1317005472 13.850 98 14 28 1 1327607424 7341600 3260352
15:56:53 98594 8192000000 10243664832 10700464128 1309713536 13.783 97 11 22 0 1328431920 16186480 2531904
15:59:51 31430 8192000000 10256294176 10705027072 1336002656 14.021 99 37 72 1 1354068144 9005664 9059824
16:01:31 45769 8192000000 10275171232 10727206912 1345906432 14.111 99 36 71 0 1373550704 19752128 7892144
16:03:50 47841 8192000000 10277772048 10726019072 1353704144 14.181 100 40 80 0 1379563088 16646944 9212000
16:05:47 69056 8192000000 10276955840 10733735936 1337523392 14.035 98 18 36 1 1352468976 10979568 3966016
16:08:03 50052 8192000000 10305570000 10749165568 1372989728 14.354 99 28 56 1 1390785888 11756800 6039360
16:09:56 44665 8192000000 10302432224 10749779968 1376800016 14.388 99 14 28 0 1387285840 7991888 2493936
16:11:47 85870 8192000000 10306670016 10767020032 1400871648 14.603 100 38 76 1 1418805056 9318864 8614544
16:14:14 53335 8192000000 10310179872 10774786048 1409955904 14.684 100 20 40 0 1427028976 12384944 4688128
16:17:10 46756 8192000000 10297319296 10757107712 1380652640 14.422 98 20 40 0 1406363392 20956672 4754080
16:19:03 66812 8192000000 10297632608 10759610368 1373582816 14.359 98 10 21 0 1390502384 15021936 1897632
16:21:21 102831 8192000000 10315994432 10797830144 1409731008 14.682 100 27 52 0 1434553344 18529024 6293312
16:24:18 30406 8192000000 10309329712 10764951552 1388928256 14.496 99 13 26 0 1399793616 8200864 2664496
16:25:53 62988 8192000000 10319632080 10793238528 1407353536 14.660 100 9 18 0 1427180720 17282144 2545040
16:27:56 26595 8192000000 10291273312 10737680384 1329573168 13.963 95 18 36 0 1341171056 7270608 4327280
16:29:23 91512 8192000000 10276941312 10736050176 1343933936 14.093 97 9 18 0 1361801504 15888000 1979568
16:31:55 116696 8192000000 10258152192 10712395776 1310047056 13.786 95 24 48 0 1324264960 9022304 5195600
16:35:36 59672 8192000000 10256216416 10718625792 1346732928 14.118 99 6 12 0 1364980304 16986496 1260880
16:37:36 61888 8192000000 9767899664 10150273024 892261424 9.822 65 14 26 1 903576544 8732688 2582432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-12-2022 16:40:55 to 15-12-2022 16:41:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-12-2022 16:41:11 to 15-12-2022 16:41: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          237105
MON$OLDEST_ACTIVE               239545
MON$OLDEST_SNAPSHOT             239545
MON$NEXT_TRANSACTION            239545
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                       2026080
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:70: 
:71: mscs-test-161.budzdorov.ru    Thu Dec 15 16:38:41 2022
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Thu Dec 15 16:38:52 2022
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:77: 
--- end of diff output ---

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

15.12.22 16:41: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
2022-12-15 16:41:17 No severe PSQL-related problems occured
15.12.22 16:41:18. End of report.