Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.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 15433244 kB
MemAvailable 30467068 kB
Buffers 0 kB
Cached 16302324 kB
SwapCached 3160 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-28 01:38:42
DTS_END                         2023-02-28 01:38:42.5960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47866
TRN_ID                          243308
ATT_ID                          670
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: 9223.84 22934 1106861
customer order: creation 177.42 326 21290
customer order: refuse 15.10 342 1812
order to supplier: creation 47.82 1184 5738
order to supplier: removal 3.68 89313 442
invoice (draft): creation 48.28 3308 5794
invoice (draft): removal 50.83 760 6099
invoice accept: apply 42.91 18384 5149
invoice accept: cancel 45.63 880 5476
customer reserve: creation 4018.06 179 482167
customer reserve: removal 4474.13 58 536895
realization accept: apply 86.46 165 10375
realization accept: cancel 78.78 114 9453
payment from customer: creation 61.61 106 7393
payment from customer: removal 6.09 81 731
payment to supplier: creation 51.58 116 6190
payment to supplier: removal 3.63 53 436
service: total inventory turnovers 4.53 1126 543
service: total monetary turnovers 6.50 24 780
service: aggregate perf. data 0.78 27262 94
service: refresh index statistics 0.03 314906 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-02-2023 01:40:13 to 28-02-2023 01:40:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2128
TEST_TIME 2 3588
TEST_TIME 3 5312
TEST_TIME 4 6006
TEST_TIME 5 6742
TEST_TIME 6 6526
TEST_TIME 7 6711
TEST_TIME 8 6657
TEST_TIME 9 6920
TEST_TIME 10 6679
TEST_TIME 11 6833
TEST_TIME 12 6878
TEST_TIME 13 6935
TEST_TIME 14 7035
TEST_TIME 15 7358
TEST_TIME 16 7235
TEST_TIME 17 7804
TEST_TIME 18 8059
TEST_TIME 19 8152
TEST_TIME 20 8276
TEST_TIME 21 8609
TEST_TIME 22 9076
TEST_TIME 23 9173
TEST_TIME 24 9250
TEST_TIME 25 9177
TEST_TIME 26 9480
TEST_TIME 27 9755
TEST_TIME 28 10190
TEST_TIME 29 10829
TEST_TIME 30 10748
TEST_TIME 31 10677
TEST_TIME 32 10561
TEST_TIME 33 10587
TEST_TIME 34 10419
TEST_TIME 35 10172
TEST_TIME 36 10092
TEST_TIME 37 10059
TEST_TIME 38 10055
TEST_TIME 39 10075
TEST_TIME 40 10024
TEST_TIME 41 10014
TEST_TIME 42 9898
TEST_TIME 43 9780
TEST_TIME 44 9728
TEST_TIME 45 9711
TEST_TIME 46 9709
TEST_TIME 47 9565
TEST_TIME 48 9519
TEST_TIME 49 9457
TEST_TIME 50 9375
TEST_TIME 51 9371
TEST_TIME 52 9452
TEST_TIME 53 9656
TEST_TIME 54 9553
TEST_TIME 55 9585
TEST_TIME 56 9682
TEST_TIME 57 9630
TEST_TIME 58 9602
TEST_TIME 59 9815
TEST_TIME 60 9759
TEST_TIME 61 9795
TEST_TIME 62 9753
TEST_TIME 63 9655
TEST_TIME 64 9622
TEST_TIME 65 9546
TEST_TIME 66 9519
TEST_TIME 67 9587
TEST_TIME 68 9517
TEST_TIME 69 9472
TEST_TIME 70 9464
TEST_TIME 71 9396
TEST_TIME 72 9310
TEST_TIME 73 9331
TEST_TIME 74 9303
TEST_TIME 75 9267
TEST_TIME 76 9288
TEST_TIME 77 9305
TEST_TIME 78 9316
TEST_TIME 79 9390
TEST_TIME 80 9391
TEST_TIME 81 9394
TEST_TIME 82 9431
TEST_TIME 83 9438
TEST_TIME 84 9415
TEST_TIME 85 9402
TEST_TIME 86 9446
TEST_TIME 87 9482
TEST_TIME 88 9457
TEST_TIME 89 9403
TEST_TIME 90 9418
TEST_TIME 91 9363
TEST_TIME 92 9355
TEST_TIME 93 9311
TEST_TIME 94 9299
TEST_TIME 95 9299
TEST_TIME 96 9300
TEST_TIME 97 9307
TEST_TIME 98 9346
TEST_TIME 99 9324
TEST_TIME 100 9366
TEST_TIME 101 9332
TEST_TIME 102 9379
TEST_TIME 103 9353
TEST_TIME 104 9326
TEST_TIME 105 9324
TEST_TIME 106 9400
TEST_TIME 107 9418
TEST_TIME 108 9444
TEST_TIME 109 9468
TEST_TIME 110 9389
TEST_TIME 111 9346
TEST_TIME 112 9378
TEST_TIME 113 9339
TEST_TIME 114 9298
TEST_TIME 115 9264
TEST_TIME 116 9252
TEST_TIME 117 9258
TEST_TIME 118 9224
TEST_TIME 119 9233
TEST_TIME 120 9226

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-02-2023 01:40:16 to 28-02-2023 01:40:20.

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 1551098 1551098 0 0.00 0 16716 6 0 0
doc_list_biud 1551098 1551098 0 0.00 0 45594 26 0 0
sp_add_invoice_to_stock 5273 5149 124 2.35 32 222996 18385 0 124
sp_cancel_adding_invoice 5476 5476 0 0.00 3 80426 881 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 10773 343 0 0
sp_cancel_customer_reserve 536895 536895 0 0.00 0 45602 58 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 2 4560 82 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 2 4006 53 0 0
sp_cancel_supplier_invoice 6112 6099 13 0.21 2 14394 761 0 13
sp_cancel_supplier_order 442 442 0 0.00 8 1065511 89313 0 0
sp_cancel_write_off 9453 9453 0 0.00 1 17232 115 0 0
sp_client_order 21290 21290 0 0.00 3 19232 327 0 0
sp_customer_reserve 482167 482167 0 0.00 0 198101 180 0 0
sp_fill_shopping_cart 32822 32822 0 0.00 1 13743 39 0 0
sp_fill_shopping_cart_clo_res 482162 482162 0 0.00 0 10202 13 0 0
sp_get_clo_for_invoice 5144 5144 0 0.00 0 23025 287 0 0
sp_kill_cost_storno 16096 16096 0 0.00 0 11785 54 0 0
sp_kill_qstorno_ret_qs2qd 1089784 1089784 0 0.00 0 37617 31 0 0
sp_kill_qty_storno 575696 575696 0 0.00 0 45592 69 0 0
sp_lock_selected_doc 21547 21547 0 0.00 0 7070 5 0 0
sp_make_cost_storno 29102 29102 0 0.00 0 16713 48 0 0
sp_multiply_rows_for_pdistr 29102 29102 0 0.00 0 8171 27 0 0
sp_multiply_rows_for_qdistr 21290 21290 0 0.00 0 14130 156 0 0
sp_pay_from_customer 7393 7393 0 0.00 4 14726 106 0 0
sp_pay_to_supplier 6190 6190 0 0.00 4 14388 117 0 0
sp_payment_common 13583 13583 0 0.00 1 12269 25 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 10763 319 0 0
sp_qd_handle_on_invoice_upd_sts 10620 10620 0 0.00 0 35829 348 0 0
sp_qd_handle_on_reserve_upd_sts 10375 10375 0 0.00 0 11275 63 0 0
sp_reserve_write_off 10375 10375 0 0.00 6 16739 165 0 0
sp_supplier_invoice 5794 5794 0 0.00 6 138387 3308 0 0
sp_supplier_order 5738 5738 0 0.00 5 35608 1184 0 0
srv_aggregate_perf_data 94 94 0 0.00 2644 819096 27262 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1004 166394 45990 0 0
srv_find_qd_qs_mism 1056022 1056022 0 0.00 0 12324 6 0 0
srv_make_invnt_saldo 543 543 0 0.00 33 34500 1127 0 0
srv_make_money_saldo 780 780 0 0.00 1 349 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 198634 432399 314907 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 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 5149 5149 0 0.00 0 18164 176 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 8906 87 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 4477 15 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 957 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 3121 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 5069 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 809 19 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 24755 1728 0 0
t$perf-norm:sp_cancel_write_off 1049 1049 0 0.00 0 4885 12 0 0
t$perf-norm:sp_client_order 21290 21290 0 0.00 0 7113 17 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 1388 7 0 0
t$perf-norm:sp_pay_from_customer 7393 7393 0 0.00 0 6485 10 0 0
t$perf-norm:sp_pay_to_supplier 6190 6190 0 0.00 0 15995 15 0 0
t$perf-norm:sp_reserve_write_off 10375 10375 0 0.00 0 11932 15 0 0
t$perf-norm:sp_supplier_invoice 5794 5794 0 0.00 0 8846 20 0 0
t$perf-norm:sp_supplier_order 5738 5738 0 0.00 0 6052 17 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 16236 173 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1417 24 0 0
t$perf-norm:srv_make_invnt_saldo 543 543 0 0.00 0 1820 28 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 1027 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 5 4 0 0
v_add_invoice_to_stock 10528 10280 248 2.35 0 5441 15 0 248
v_all_customers 38576 38576 0 0.00 0 20 1 0 0
v_all_suppliers 32302 32302 0 0.00 0 178 1 0 0
v_all_wares 42580 42580 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 824 8 0 0
v_cancel_client_order 3624 3624 0 0.00 0 1247 3 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 2211 6 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 2374 9 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 436 3 0 0
v_cancel_supplier_invoice 872 846 26 2.98 0 619 13 0 26
v_cancel_supplier_order 884 884 0 0.00 0 3165 9 0 0
v_cancel_write_off 2098 2098 0 0.00 0 3321 23 0 0
v_max_id_clo_ord 5738 5738 0 0.00 0 1224 1 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 2077 10 0 0
v_max_id_ord_sup 5794 5794 0 0.00 0 3676 4 0 0
v_max_non_paid_invoice 1571 1571 0 0.00 0 1772 4 0 0
v_max_non_paid_realizn 4113 4113 0 0.00 0 6736 4 0 0
v_min_id_clo_ord 5738 5738 0 0.00 0 4378 6 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 7973 19 0 0
v_min_id_ord_sup 5794 5794 0 0.00 0 5435 9 0 0
v_min_non_paid_invoice 6190 6190 0 0.00 0 12264 18 0 0
v_min_non_paid_realizn 7393 7393 0 0.00 0 6528 15 0 0
v_our_firm 10564 10564 0 0.00 0 46 1 0 0
v_reserve_write_off 20750 20750 0 0.00 0 3155 5 0 0
x_lock_depdocs_on_canc_invoice 11152 11152 0 0.00 0 24766 131 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 22227 352 0 0
x_make_qstorno_1000_1200 5738 5738 0 0.00 1 35597 1111 0 0
x_make_qstorno_1200_2000 5794 5794 0 0.00 3 138281 3163 0 0
x_make_qstorno_2100_3300 482167 482167 0 0.00 0 197995 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-02-2023 01:40:21 to 28-02-2023 01:40:25.

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:46 4 8192000000 8803814512 8816726016 9398128 0.114 3 2 3 0 9539712 65168 76416
23:09:47 26727 8192000000 9890704720 10199244800 968912272 10.576 100 90 180 0 998826208 5703248 24210688
23:11:18 23501 8192000000 9946816656 10267865088 1026009840 11.130 100 85 170 0 1055639232 7807088 21822304
23:12:50 13510 8192000000 9989975104 10322595840 1074506624 11.595 100 86 172 0 1105901248 9834144 21560480
23:14:08 9581 8192000000 10034178512 10379804672 1119273696 12.020 100 81 162 0 1149047280 10732688 19040896
23:15:22 7723 8192000000 10075842112 10436874240 1166654528 12.466 100 72 142 0 1193764624 11244480 15865616
23:16:32 2640 8192000000 10107106912 10473213952 1200293664 12.779 100 81 162 0 1230886960 12744256 17849040
23:17:38 3114 8192000000 10144073616 10516123648 1231144080 13.065 100 82 162 0 1262095248 13475600 17475568
23:18:43 1249 8192000000 10173181424 10550546432 1264456576 13.371 100 84 166 1 1296243152 13276864 18509712
23:19:45 1455 8192000000 10202604800 10586857472 1289861056 13.603 100 83 166 0 1319455888 11370912 18223920
23:20:48 1592 8192000000 10209803584 10601336832 1304711680 13.738 100 81 159 2 1333252560 10428432 18112448
23:21:51 3500 8192000000 10202973456 10612506624 1315067392 13.832 100 36 72 0 1331429568 8893296 7468880
23:22:55 25860 8192000000 10206005168 10626265088 1321167040 13.887 100 15 30 1 1330515312 5695952 3652320
23:24:21 5953 8192000000 10207127296 10628530176 1325006512 13.922 100 30 59 0 1338901712 6491744 7403456
23:25:30 7151 8192000000 10221463600 10641891328 1331734912 13.983 100 23 44 0 1342854528 6253936 4865680
23:26:39 8973 8192000000 10225598016 10646056960 1323189552 13.906 99 20 40 0 1337292752 10083424 4019776
23:27:49 26760 8192000000 10220942528 10647080960 1327697904 13.946 99 22 44 0 1339220560 5846208 5676448
23:29:16 3773 8192000000 10228027888 10649145344 1339264416 14.051 100 32 63 0 1352011440 5514416 7232608
23:30:20 7164 8192000000 10191049120 10601709568 1277216192 13.488 98 21 42 0 1291666960 9917360 4533408
23:31:29 8217 8192000000 10156535360 10559483904 1245135600 13.193 98 33 64 0 1258456560 5030464 8290496
23:32:41 62457 8192000000 10135889856 10542329856 1234140416 13.092 98 29 58 0 1246690128 5602688 6947024
23:34:47 10032 8192000000 10169509744 10574249984 1276808304 13.484 100 21 42 0 1287058096 5657888 4591904
23:35:59 7089 8192000000 10179747552 10591232000 1291675536 13.619 100 22 44 0 1302184160 5010464 5498160
23:37:08 18749 8192000000 10203665616 10609758208 1304700864 13.738 100 14 28 2 1312909392 5542096 2666432
23:38:28 24402 8192000000 10213500608 10627145728 1319299136 13.870 100 14 28 0 1326652304 4353424 2999744
23:40:07 12603 8192000000 10244676544 10658504704 1345531392 14.107 100 42 83 1 1364731536 10017328 9182816
23:41:28 8904 8192000000 10271128880 10687225856 1359147184 14.230 100 45 90 0 1381103456 11321200 10635072
23:42:47 44180 8192000000 10282648304 10708062208 1370060656 14.328 100 35 70 0 1393579280 15364320 8154304
23:44:36 6332 8192000000 10289915920 10719813632 1386386448 14.474 100 39 78 0 1413036544 17603872 9046224
23:45:46 19395 8192000000 10312137328 10741149696 1395836432 14.558 100 49 96 0 1425513152 18392448 11284272
23:47:12 9092 8192000000 10327731968 10753572864 1406167120 14.650 100 53 106 0 1440892016 22200816 12524080
23:48:26 5330 8192000000 10325782336 10754408448 1407850304 14.665 100 44 87 0 1436975808 18695824 10429680
23:49:40 17080 8192000000 10349563200 10782081024 1421512304 14.786 100 56 110 1 1458996112 24322752 13161056
23:51:01 2701 8192000000 10353171280 10773438464 1413964288 14.719 100 76 152 2 1459842240 27662352 18215600
23:52:07 4337 8192000000 10331346112 10748715008 1396748864 14.566 100 71 140 0 1440481888 27386080 16346944
23:53:16 2833 8192000000 10355099280 10772467712 1409003808 14.675 100 75 146 0 1463255968 36546432 17705728
23:54:24 3270 8192000000 10367294000 10779176960 1410338912 14.687 100 73 146 0 1468475936 39988304 18148720
23:55:34 3848 8192000000 10362566784 10780176384 1404989312 14.639 100 71 141 0 1468388432 45007168 18391952
23:56:43 1104 8192000000 10342548704 10745364480 1384553072 14.457 100 85 166 1 1438729424 34566112 19610240
23:57:48 1618 8192000000 10344216992 10747322368 1378790960 14.406 100 82 162 2 1440827360 40964560 21071840
23:58:55 4242 8192000000 10332603840 10744553472 1376365824 14.384 100 61 122 0 1435344624 43455712 15523088
00:00:06 1001 8192000000 10358310064 10766753792 1392098000 14.525 100 77 153 4 1459092656 47090080 19904576
00:01:14 32158 8192000000 10321998448 10746064896 1371497936 14.340 99 41 82 2 1416781792 34523952 10759904
00:02:48 10673 8192000000 10334101584 10760396800 1392481504 14.528 100 39 78 0 1436980880 34735280 9764096
00:04:16 50911 8192000000 10313946672 10759671808 1381063760 14.426 100 21 42 0 1424800368 37679888 6056720
00:06:12 18321 8192000000 10306071408 10747772928 1371745440 14.343 100 19 38 0 1414961024 37493760 5721824
00:07:40 55218 8192000000 10283323168 10713104384 1320414064 13.880 97 21 41 0 1340607104 15282256 4910784
00:09:40 61251 8192000000 10268432640 10706354176 1307004672 13.759 95 23 46 0 1320098464 8010496 5083296
00:11:43 115854 8192000000 10278363840 10719051776 1346678944 14.118 99 19 38 0 1367289808 16149888 4460976
00:14:53 29599 8192000000 10283556624 10723983360 1370385520 14.331 100 38 72 1 1388119616 9568960 8165136
00:16:26 77526 8192000000 10289995216 10750304256 1386905552 14.478 100 35 68 0 1408110320 13531424 7673344
00:18:56 39770 8192000000 10304146336 10745180160 1387038240 14.479 100 44 89 1 1405690912 8526640 10126032
00:21:19 118416 8192000000 10284561984 10738868224 1348114224 14.131 97 18 35 1 1365603152 13418640 4070288
00:24:18 70229 8192000000 10282018608 10741329920 1362850368 14.263 98 20 40 0 1380957936 13083024 5024544
00:26:28 47098 8192000000 10276807168 10743783424 1358460816 14.224 98 11 22 0 1376061904 15316480 2284608
00:28:15 48572 8192000000 10303049168 10756988928 1386413072 14.474 100 14 28 0 1403263584 13224400 3626112
00:30:06 46151 8192000000 10296673168 10755903488 1380595312 14.422 99 13 26 0 1403295744 19016016 3684416
00:31:53 62422 8192000000 10289609872 10740011008 1361560368 14.251 98 21 42 0 1378275040 12750112 3964560
00:34:11 39958 8192000000 10297531968 10756554752 1380006064 14.417 99 22 43 0 1398592832 13402128 5184640
00:35:55 48024 8192000000 10297468720 10742001664 1344409120 14.097 97 27 54 0 1370859904 20454848 5995936
00:38:12 45648 8192000000 10286245744 10724564992 1324247840 13.915 96 40 77 0 1343446240 10225824 8972576
00:40:00 42647 8192000000 10275525728 10708819968 1306554144 13.755 95 17 34 0 1318014704 8265280 3195280
00:41:44 98064 8192000000 10279913600 10717667328 1329879872 13.966 97 39 78 0 1355534560 16510400 9144288
00:44:25 53052 8192000000 10246227136 10699952128 1335787216 14.019 98 31 62 0 1354124992 11485440 6852336
00:46:52 73673 8192000000 10257882832 10708258816 1334869120 14.011 98 9 17 1 1347048096 9706096 2472880
00:49:06 85473 8192000000 10246004192 10695823360 1340180592 14.059 98 16 32 1 1351711456 7965344 3565520
00:51:35 56548 8192000000 10228808384 10686435328 1310161040 13.788 97 4 8 0 1320443728 9077136 1205552
00:54:12 27253 8192000000 10257500704 10693873664 1307559456 13.764 96 32 65 1 1324518768 9693200 7266112
00:55:43 55536 8192000000 10275611584 10735714304 1363263536 14.267 99 15 30 0 1379223728 12428304 3531888
00:57:40 60664 8192000000 10255607296 10702102528 1330343824 13.970 97 19 36 0 1343839088 9153632 4341632
00:59:41 43831 8192000000 10289758848 10734874624 1372202080 14.347 100 42 84 0 1394759072 12913712 9643280
01:01:26 75444 8192000000 10283291696 10726420480 1347812944 14.128 98 30 59 0 1363874400 9809712 6251744
01:03:53 10344 8192000000 10292066464 10723586048 1367089872 14.301 100 41 82 0 1386992768 11019616 8883280
01:06:23 98245 8192000000 10263148960 10714480640 1358964672 14.228 99 8 16 0 1370136688 9274800 1897216
01:09:07 45030 8192000000 10279128384 10730582016 1377657056 14.396 100 16 32 1 1389800016 9083072 3059888
01:10:59 52798 8192000000 10273240320 10732830720 1378720080 14.405 100 10 20 0 1388533552 7445952 2367520
01:12:59 69910 8192000000 10285935504 10739224576 1360634432 14.243 98 24 47 0 1374142832 7767216 5741184
01:15:09 43303 8192000000 10285869680 10743750656 1383230880 14.445 100 30 60 2 1403962848 12697008 8034960
01:16:56 58293 8192000000 10300050912 10746277888 1367361008 14.303 98 16 33 1 1379854304 9072720 3420576
01:18:55 70074 8192000000 10331129392 10769387520 1400070496 14.596 100 31 61 0 1422100160 15094800 6934864
01:21:08 35365 8192000000 10286580160 10731692032 1343189280 14.086 97 13 25 0 1353438464 7403728 2845456
01:22:45 80613 8192000000 10283368832 10735804416 1351570624 14.162 98 23 46 0 1370445216 13556064 5318528
01:25:16 166394 8192000000 10268459552 10732019712 1255603296 13.290 91 4 8 0 1269409280 13269600 536384
01:29:02 72449 8192000000 10259356880 10702864384 1267095808 13.395 93 25 50 0 1286780048 14234608 5449632
01:32:56 84744 8192000000 10235373968 10686783488 1289098000 13.596 96 11 22 0 1303505808 11880592 2527216
01:35:21 57663 8192000000 10245500912 10701189120 1316722288 13.847 98 15 29 0 1334982272 14427328 3832656
01:37:20 89860 8192000000 9067080384 9293062144 227371216 2.700 17 3 6 0 230142688 2117376 654096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-02-2023 01:40:27 to 28-02-2023 01:40:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 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 28-02-2023 01:40:43 to 28-02-2023 01:40:45.

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          243821
MON$OLDEST_ACTIVE               243822
MON$OLDEST_SNAPSHOT             243822
MON$NEXT_TRANSACTION            243822
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                       2016896
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 ---
:745: 
:746: mscs-test-161.budzdorov.ru    Tue Feb 28 01:38:52 2023
:747:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:748: 
--- end of diff output ---

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

28.02.23 01:40:46. 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-28 01:40:49 No severe PSQL-related problems occured
28.02.23 01:40:50. End of report.