Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.01.2023 22: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 15352012 kB
MemAvailable 30418440 kB
Buffers 0 kB
Cached 16387508 kB
SwapCached 4540 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-01-04 22:38:35
DTS_END                         2023-01-04 22:38:35.6550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36108
TRN_ID                          243163
ATT_ID                          681
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: 9468.98 19446 1136278
customer order: creation 177.65 357 21318
customer order: refuse 15.25 372 1830
order to supplier: creation 47.00 1307 5640
order to supplier: removal 3.38 100732 405
invoice (draft): creation 47.59 3355 5711
invoice (draft): removal 52.79 772 6335
invoice accept: apply 43.47 18806 5216
invoice accept: cancel 48.08 892 5770
customer reserve: creation 4020.86 186 482503
customer reserve: removal 4703.67 56 564440
realization accept: apply 86.85 170 10422
realization accept: cancel 89.25 106 10710
payment from customer: creation 60.74 105 7289
payment from customer: removal 6.03 67 724
payment to supplier: creation 49.89 119 5987
payment to supplier: removal 3.32 72 398
service: total inventory turnovers 5.08 1131 610
service: total monetary turnovers 7.14 43 857
service: aggregate perf. data 0.91 21524 109
service: refresh index statistics 0.03 238752 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-01-2023 22:40:28 to 04-01-2023 22:40:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5774
TEST_TIME 2 4882
TEST_TIME 3 4838
TEST_TIME 4 5398
TEST_TIME 5 5362
TEST_TIME 6 5748
TEST_TIME 7 6427
TEST_TIME 8 6985
TEST_TIME 9 7181
TEST_TIME 10 7659
TEST_TIME 11 7528
TEST_TIME 12 7354
TEST_TIME 13 7302
TEST_TIME 14 7116
TEST_TIME 15 7238
TEST_TIME 16 7232
TEST_TIME 17 7507
TEST_TIME 18 7596
TEST_TIME 19 7671
TEST_TIME 20 7990
TEST_TIME 21 8592
TEST_TIME 22 8525
TEST_TIME 23 8611
TEST_TIME 24 9378
TEST_TIME 25 9384
TEST_TIME 26 9806
TEST_TIME 27 9744
TEST_TIME 28 10081
TEST_TIME 29 10621
TEST_TIME 30 10504
TEST_TIME 31 10656
TEST_TIME 32 10394
TEST_TIME 33 10468
TEST_TIME 34 10422
TEST_TIME 35 10418
TEST_TIME 36 10297
TEST_TIME 37 10051
TEST_TIME 38 9990
TEST_TIME 39 10105
TEST_TIME 40 10163
TEST_TIME 41 10071
TEST_TIME 42 10266
TEST_TIME 43 10343
TEST_TIME 44 10331
TEST_TIME 45 10210
TEST_TIME 46 10271
TEST_TIME 47 10344
TEST_TIME 48 10275
TEST_TIME 49 10205
TEST_TIME 50 10079
TEST_TIME 51 10100
TEST_TIME 52 10114
TEST_TIME 53 10002
TEST_TIME 54 9892
TEST_TIME 55 9811
TEST_TIME 56 9783
TEST_TIME 57 9808
TEST_TIME 58 9741
TEST_TIME 59 9625
TEST_TIME 60 9557
TEST_TIME 61 9594
TEST_TIME 62 9627
TEST_TIME 63 9620
TEST_TIME 64 9591
TEST_TIME 65 9671
TEST_TIME 66 9738
TEST_TIME 67 9782
TEST_TIME 68 9762
TEST_TIME 69 9757
TEST_TIME 70 9725
TEST_TIME 71 9939
TEST_TIME 72 9893
TEST_TIME 73 9867
TEST_TIME 74 9858
TEST_TIME 75 9738
TEST_TIME 76 9734
TEST_TIME 77 9651
TEST_TIME 78 9643
TEST_TIME 79 9597
TEST_TIME 80 9553
TEST_TIME 81 9511
TEST_TIME 82 9503
TEST_TIME 83 9529
TEST_TIME 84 9481
TEST_TIME 85 9584
TEST_TIME 86 9583
TEST_TIME 87 9596
TEST_TIME 88 9582
TEST_TIME 89 9643
TEST_TIME 90 9651
TEST_TIME 91 9642
TEST_TIME 92 9730
TEST_TIME 93 9711
TEST_TIME 94 9757
TEST_TIME 95 9723
TEST_TIME 96 9638
TEST_TIME 97 9654
TEST_TIME 98 9617
TEST_TIME 99 9670
TEST_TIME 100 9629
TEST_TIME 101 9598
TEST_TIME 102 9559
TEST_TIME 103 9526
TEST_TIME 104 9552
TEST_TIME 105 9551
TEST_TIME 106 9531
TEST_TIME 107 9569
TEST_TIME 108 9558
TEST_TIME 109 9572
TEST_TIME 110 9566
TEST_TIME 111 9605
TEST_TIME 112 9584
TEST_TIME 113 9563
TEST_TIME 114 9659
TEST_TIME 115 9658
TEST_TIME 116 9660
TEST_TIME 117 9626
TEST_TIME 118 9561
TEST_TIME 119 9531
TEST_TIME 120 9476

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-01-2023 22:40:32 to 04-01-2023 22:40:35.

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 1580747 1580747 0 0.00 0 19435 6 0 0
doc_list_biud 1580745 1580745 0 0.00 0 38379 26 0 0
sp_add_invoice_to_stock 5333 5216 117 2.19 26 196413 18806 0 117
sp_cancel_adding_invoice 5770 5770 0 0.00 4 61277 892 0 0
sp_cancel_client_order 1830 1830 0 0.00 3 18995 373 0 0
sp_cancel_customer_reserve 564440 564440 0 0.00 0 20132 57 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 2 3494 68 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 3326 72 0 0
sp_cancel_supplier_invoice 6345 6335 10 0.15 2 22896 772 0 10
sp_cancel_supplier_order 405 405 0 0.00 7 961522 100732 0 0
sp_cancel_write_off 10710 10710 0 0.00 1 13230 107 0 0
sp_client_order 21318 21318 0 0.00 3 40773 357 0 0
sp_customer_reserve 482503 482503 0 0.00 0 49845 187 0 0
sp_fill_shopping_cart 32655 32655 0 0.00 1 11936 36 0 0
sp_fill_shopping_cart_clo_res 482490 482490 0 0.00 0 16833 15 0 0
sp_get_clo_for_invoice 5203 5203 0 0.00 0 24164 272 0 0
sp_kill_cost_storno 17602 17602 0 0.00 0 19424 56 0 0
sp_kill_qstorno_ret_qs2qd 1146330 1146330 0 0.00 0 22865 31 0 0
sp_kill_qty_storno 605113 605113 0 0.00 0 38366 67 0 0
sp_lock_selected_doc 21543 21543 0 0.00 0 7024 5 0 0
sp_make_cost_storno 28901 28901 0 0.00 0 17699 53 0 0
sp_multiply_rows_for_pdistr 28899 28899 0 0.00 0 11660 27 0 0
sp_multiply_rows_for_qdistr 21318 21318 0 0.00 0 40755 159 0 0
sp_pay_from_customer 7289 7289 0 0.00 4 11838 106 0 0
sp_pay_to_supplier 5987 5987 0 0.00 4 17022 120 0 0
sp_payment_common 13276 13276 0 0.00 1 9835 28 0 0
sp_qd_handle_on_cancel_clo 1830 1830 0 0.00 0 18989 350 0 0
sp_qd_handle_on_invoice_upd_sts 10971 10971 0 0.00 0 38347 353 0 0
sp_qd_handle_on_reserve_upd_sts 10422 10422 0 0.00 0 18976 57 0 0
sp_reserve_write_off 10422 10422 0 0.00 6 19004 170 0 0
sp_supplier_invoice 5711 5711 0 0.00 6 83951 3356 0 0
sp_supplier_order 5640 5640 0 0.00 5 62324 1308 0 0
srv_aggregate_perf_data 109 109 0 0.00 2434 553109 21524 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 1209 103898 39045 0 0
srv_find_qd_qs_mism 1085738 1085738 0 0.00 0 15657 6 0 0
srv_make_invnt_saldo 610 610 0 0.00 35 78783 1132 0 0
srv_make_money_saldo 857 857 0 0.00 1 16470 44 0 0
srv_recalc_idx_stat 4 4 0 0.00 213493 296713 238753 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5216 5216 0 0.00 0 17605 183 0 0
t$perf-norm:sp_cancel_adding_invoice 455 455 0 0.00 0 5560 112 0 0
t$perf-norm:sp_cancel_client_order 1830 1830 0 0.00 0 6039 13 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 951 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 4111 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 949 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 1724 10 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 25881 1771 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 6768 17 0 0
t$perf-norm:sp_client_order 21318 21318 0 0.00 0 9814 17 0 0
t$perf-norm:sp_customer_reserve 1077 1077 0 0.00 0 5611 10 0 0
t$perf-norm:sp_pay_from_customer 7289 7289 0 0.00 0 5235 11 0 0
t$perf-norm:sp_pay_to_supplier 5987 5987 0 0.00 0 8364 12 0 0
t$perf-norm:sp_reserve_write_off 10422 10422 0 0.00 0 10787 13 0 0
t$perf-norm:sp_supplier_invoice 5711 5711 0 0.00 0 15179 23 0 0
t$perf-norm:sp_supplier_order 5640 5640 0 0.00 0 7953 19 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 251 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 2540 43 0 0
t$perf-norm:srv_make_invnt_saldo 610 610 0 0.00 0 1973 22 0 0
t$perf-norm:srv_make_money_saldo 857 857 0 0.00 0 10796 41 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10628 10394 234 2.20 0 4575 20 0 234
v_all_customers 38778 38778 0 0.00 0 19 1 0 0
v_all_suppliers 31562 31562 0 0.00 0 53 1 0 0
v_all_wares 42636 42636 0 0.00 0 121 1 0 0
v_cancel_adding_invoice 910 910 0 0.00 0 1244 8 0 0
v_cancel_client_order 3660 3660 0 0.00 0 8691 7 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 3486 9 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 3390 8 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 3303 9 0 0
v_cancel_supplier_invoice 874 854 20 2.28 0 1496 19 0 20
v_cancel_supplier_order 810 810 0 0.00 0 1263 8 0 0
v_cancel_write_off 1974 1974 0 0.00 0 3685 16 0 0
v_max_id_clo_ord 5640 5640 0 0.00 0 953 2 0 0
v_max_id_clo_res 1077 1077 0 0.00 0 2843 22 0 0
v_max_id_ord_sup 5697 5697 0 0.00 0 4472 5 0 0
v_max_non_paid_invoice 1543 1543 0 0.00 0 933 3 0 0
v_max_non_paid_realizn 3854 3854 0 0.00 0 3889 5 0 0
v_min_id_clo_ord 5640 5640 0 0.00 0 1818 5 0 0
v_min_id_clo_res 1077 1077 0 0.00 0 3182 6 0 0
v_min_id_ord_sup 5697 5697 0 0.00 0 4904 7 0 0
v_min_non_paid_invoice 5987 5987 0 0.00 0 8760 18 0 0
v_min_non_paid_realizn 7289 7289 0 0.00 0 9088 25 0 0
v_our_firm 10728 10728 0 0.00 0 14 1 0 0
v_reserve_write_off 20844 20844 0 0.00 0 8293 7 0 0
x_lock_depdocs_on_canc_invoice 11678 11678 0 0.00 0 14278 109 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 2641 279 0 0
x_make_qstorno_1000_1200 5640 5640 0 0.00 1 62286 1238 0 0
x_make_qstorno_1200_2000 5711 5711 0 0.00 3 83938 3217 0 0
x_make_qstorno_2100_3300 482503 482503 0 0.00 0 49841 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-01-2023 22:40:36 to 04-01-2023 22:40:40.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:39 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:40 17369 8192000000 9891054720 10199801856 977795296 10.663 100 85 170 0 1006382480 6038320 22548864
20:11:01 19792 8192000000 9954568176 10274877440 1033627600 11.203 100 92 183 0 1065607264 8109552 23870112
20:12:26 19791 8192000000 9978381104 10309955584 1060683936 11.463 100 85 170 0 1092142064 10112192 21345936
20:14:00 13694 8192000000 10024972016 10371330048 1110146976 11.934 100 80 160 0 1140297232 11044560 19105696
20:15:30 6399 8192000000 10090128048 10449506304 1177360112 12.566 100 88 176 0 1210733600 13215072 20158416
20:16:40 4437 8192000000 10131309616 10506059776 1222527808 12.985 100 73 146 0 1251898032 12901072 16469152
20:17:45 2284 8192000000 10166855264 10541064192 1252634064 13.262 100 87 174 1 1284333792 12718064 18981664
20:18:49 1521 8192000000 10185864160 10571501568 1274839872 13.466 100 72 142 0 1302670096 11643264 16186960
20:19:52 914 8192000000 10205669680 10590568448 1294406416 13.644 100 83 165 0 1325684192 12756272 18521504
20:20:53 9845 8192000000 10188466672 10594656256 1300865504 13.703 100 44 88 0 1319334688 8916224 9552960
20:22:04 7881 8192000000 10185501200 10603900928 1307545664 13.764 100 20 38 0 1318313184 5958400 4809120
20:23:12 73364 8192000000 10213657184 10623856640 1315875920 13.839 100 33 64 0 1328292160 4885632 7530608
20:25:31 8085 8192000000 10217722096 10632790016 1325529792 13.927 100 22 44 1 1336645744 6090624 5025328
20:26:40 9185 8192000000 10223267088 10643972096 1334843488 14.011 100 14 28 0 1343700160 5831120 3025552
20:27:53 65295 8192000000 10199864272 10612174848 1251849776 13.255 94 22 44 0 1262005488 5136432 5019280
20:30:01 4941 8192000000 10162278368 10568892416 1263925584 13.366 98 22 44 0 1274736144 5723632 5086928
20:31:10 8454 8192000000 10145828032 10535976960 1175021312 12.544 93 24 44 0 1188975280 9050864 4903104
20:32:18 20587 8192000000 10122403968 10521939968 1174631936 12.540 94 29 58 0 1187002512 5128624 7241952
20:34:00 15704 8192000000 10163330208 10566782976 1248039760 13.220 98 29 54 1 1259879936 5921408 5918768
20:35:17 7298 8192000000 10186406416 10598334464 1296579328 13.664 100 19 38 1 1306696560 5867168 4250064
20:36:24 13552 8192000000 10201772352 10615357440 1306081616 13.751 100 18 34 0 1315456464 5386688 3988160
20:37:41 69456 8192000000 10224725232 10633302016 1319564640 13.873 100 39 78 0 1335639616 5760560 10314416
20:40:04 21836 8192000000 10255820320 10669846528 1350312032 14.150 100 42 84 0 1367924640 8109376 9503232
20:41:29 22356 8192000000 10269260784 10687332352 1365633088 14.288 100 44 87 0 1384680256 8638112 10409056
20:43:11 11637 8192000000 10280582784 10701811712 1376449840 14.385 100 46 90 1 1398027056 10778528 10798688
20:44:25 7946 8192000000 10296033728 10716065792 1379722112 14.414 100 47 94 0 1402584496 11788448 11073936
20:45:46 6582 8192000000 10298087040 10719805440 1385600608 14.467 100 55 108 0 1410409248 13222176 11586464
20:46:59 11174 8192000000 10304618496 10726830080 1375439856 14.376 99 50 100 0 1405090448 17821856 11828736
20:48:14 5095 8192000000 10334069168 10760101888 1409879760 14.683 100 53 106 0 1448028624 25524320 12624544
20:49:22 8181 8192000000 10341103840 10763800576 1411385456 14.696 100 68 135 3 1451577488 24758352 15433680
20:50:33 4520 8192000000 10325544752 10748526592 1391928080 14.523 99 61 122 0 1434862000 28534336 14399584
20:51:46 18652 8192000000 10337586432 10770640896 1412841168 14.709 100 39 78 0 1450515024 28063856 9610000
20:53:10 3899 8192000000 10320821360 10742071296 1387072736 14.480 99 58 113 1 1429009280 27881568 14054976
20:54:21 2893 8192000000 10328901744 10743476224 1386853632 14.478 99 65 129 1 1435291440 32383584 16054224
20:55:30 5905 8192000000 10337906272 10767462400 1401013344 14.604 100 35 70 0 1449287680 39223776 9050560
20:56:46 6872 8192000000 10294326640 10711011328 1352272768 14.168 100 43 86 2 1402806384 39339168 11194448
20:58:02 1208 8192000000 10323539936 10728017920 1361913488 14.255 100 75 150 1 1426647440 45757568 18976384
20:59:09 1360 8192000000 10360459888 10759323648 1392283936 14.526 100 85 172 0 1459598096 45749808 21564352
21:00:13 19736 8192000000 10314353264 10735013888 1360646416 14.243 99 37 74 0 1413149296 41831232 10671648
21:01:36 8309 8192000000 10313202720 10731032576 1351422528 14.160 98 45 90 2 1410930112 46976656 12530928
21:02:50 3618 8192000000 10315707744 10737254400 1385269584 14.464 100 54 101 1 1427672096 29539200 12863312
21:03:59 40012 8192000000 10316059712 10748542976 1381299376 14.428 100 35 68 0 1429057104 38865104 8892624
21:05:40 18447 8192000000 10292330144 10725818368 1365359744 14.285 100 28 56 0 1406214944 33692768 7162432
21:07:18 33418 8192000000 10276183152 10725097472 1344522864 14.098 99 18 39 0 1374061808 24832688 4706256
21:08:53 54330 8192000000 10268347664 10713067520 1319047248 13.868 96 11 22 0 1335448912 14138528 2263136
21:10:47 5137 8192000000 10294732224 10711347200 1356294368 14.204 99 71 134 1 1379092160 7876144 14921648
21:11:58 27272 8192000000 10285411776 10721259520 1350300912 14.150 98 12 24 0 1360288400 7118896 2868592
21:13:26 49876 8192000000 10317564912 10758598656 1392645296 14.529 100 19 36 0 1409668352 13442928 3580128
21:15:27 18784 8192000000 10356067360 10784026624 1414338608 14.722 100 50 100 0 1452222800 25577808 12306384
21:16:46 41171 8192000000 10363672864 10819022848 1424728288 14.815 100 15 30 2 1468887488 39978384 4180816
21:18:55 85323 8192000000 10332097936 10771873792 1390209136 14.508 100 30 57 1 1437254416 38912080 8133200
21:21:23 41101 8192000000 10322658672 10766864384 1392293424 14.526 100 38 74 1 1422187888 21216240 8678224
21:23:06 27856 8192000000 10337477808 10781876224 1410366560 14.687 100 37 75 2 1435802720 16260432 9175728
21:24:38 89495 8192000000 10321487280 10793824256 1409438304 14.679 100 11 22 0 1435236624 23059760 2738560
21:27:10 39460 8192000000 10319583680 10789736448 1405179184 14.641 99 15 30 0 1423268800 14276032 3813584
21:28:50 38916 8192000000 10312678144 10775261184 1415329952 14.731 100 15 30 0 1431598736 12671120 3597664
21:30:29 59531 8192000000 10291740784 10749104128 1380336512 14.420 98 20 40 0 1392542768 8079472 4126784
21:32:29 79113 8192000000 10315689056 10777997312 1397170256 14.570 99 21 42 0 1413582128 11873376 4538496
21:35:04 38341 8192000000 10308436432 10777055232 1416206864 14.739 100 8 16 0 1424767664 6897536 1663264
21:36:44 64994 8192000000 10300082272 10768384000 1407709408 14.664 100 7 14 0 1416067088 6882432 1475248
21:40:05 22442 8192000000 10309450112 10759270400 1398890672 14.585 100 31 62 0 1414505536 8831712 6783152
21:41:28 40230 8192000000 10272989808 10713436160 1287370912 13.580 93 30 60 0 1303578048 9317888 6889248
21:43:16 17230 8192000000 10272287888 10720137216 1355244848 14.195 99 30 58 0 1368771312 7428816 6097648
21:44:38 22869 8192000000 10262172912 10703806464 1332605232 13.991 98 30 58 0 1346596112 7817312 6173568
21:46:25 61287 8192000000 10271667632 10722848768 1327621664 13.946 97 15 30 0 1340253536 9376320 3255552
21:48:29 32870 8192000000 10276802448 10709303296 1337903904 14.039 99 40 80 0 1358421040 11354720 9162416
21:50:05 81916 8192000000 10244854336 10692030464 1305990704 13.750 97 6 12 0 1321204512 14213520 1000288
21:52:27 23831 8192000000 10283004208 10711715840 1350079152 14.148 100 37 74 0 1371105184 13017200 8008832
21:53:51 61005 8192000000 10270248064 10717954048 1365667584 14.288 100 33 66 0 1382382080 9509248 7205248
21:55:57 36836 8192000000 10262154864 10713726976 1348917136 14.138 99 25 50 0 1364669216 10372976 5379104
21:57:47 71283 8192000000 10263909472 10726440960 1354419328 14.187 99 9 18 0 1369133888 12615056 2099504
22:00:16 48655 8192000000 10274448224 10731216896 1355786656 14.200 99 9 18 0 1369322896 11510128 2026112
22:02:05 44609 8192000000 10285272160 10735767552 1369600624 14.323 99 19 38 0 1384368720 10083920 4684176
22:03:50 103534 8192000000 10284452592 10729566208 1321103280 13.887 96 23 47 0 1344746992 17625664 6018048
22:06:34 82703 8192000000 10292059424 10746290176 1358152864 14.221 99 20 40 0 1380668848 18259536 4256448
22:08:57 55494 8192000000 10313494064 10764300288 1390423968 14.510 100 21 42 0 1405799792 11035392 4340432
22:10:53 85779 8192000000 10316679392 10765762560 1373441712 14.358 98 18 36 0 1391135744 13701520 3992512
22:13:35 103897 8192000000 10293124992 10758774784 1389206032 14.499 99 15 30 1 1401862688 9555840 3100816
22:16:19 46348 8192000000 10326347872 10779414528 1408925712 14.674 100 42 84 0 1434894752 16293152 9675888
22:18:06 62840 8192000000 10292483728 10761347072 1381555392 14.430 99 13 26 0 1392494576 8088752 2850432
22:20:09 62164 8192000000 10304934240 10764857344 1403471200 14.626 100 16 32 0 1417822800 10669440 3682160
22:22:34 55396 8192000000 10296023904 10768060416 1391340352 14.518 99 12 24 1 1411837264 17622400 2874512
22:24:54 18497 8192000000 10301503328 10747330560 1370312720 14.330 98 42 84 1 1387728656 8118128 9297808
22:26:14 45018 8192000000 10304712256 10756902912 1372478400 14.349 98 30 60 0 1389437360 10128560 6830400
22:28:02 30127 8192000000 10292683088 10754461696 1387363888 14.482 100 21 42 0 1404925920 12992960 4569072
22:29:35 45914 8192000000 10282697888 10728427520 1328850768 13.957 96 41 78 1 1346461360 8226368 9384224
22:31:22 72279 8192000000 10276886032 10738626560 1334586704 14.009 97 20 40 0 1357215472 18088000 4540768
22:33:35 55922 8192000000 10259469424 10707263488 1282044656 13.532 94 18 34 0 1295079920 9293888 3741376
22:35:36 82319 8192000000 10270604096 10715426816 1349070464 14.139 99 45 90 3 1369223792 9781472 10371856
22:38:22 26951 8192000000 9642909504 9998643200 782945872 8.723 58 7 14 0 792241328 7908624 1386832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-01-2023 22:40:42 to 04-01-2023 22:40:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-01-2023 22:40:59 to 04-01-2023 22:41:01.

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          243162
MON$OLDEST_ACTIVE               243356
MON$OLDEST_SNAPSHOT             243356
MON$NEXT_TRANSACTION            243356
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                       2027168
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
--- end of diff output ---

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

04.01.23 22:41:02. 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-01-04 22:41:04 No severe PSQL-related problems occured
04.01.23 22:41:06. End of report.