Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.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 15275444 kB
MemAvailable 30398968 kB
Buffers 0 kB
Cached 16451612 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-06 01:38:40
DTS_END                         2023-01-06 01:38:40.0330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53574
TRN_ID                          244847
ATT_ID                          683
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: 9443.55 23007 1133226
customer order: creation 176.88 338 21225
customer order: refuse 15.58 311 1869
order to supplier: creation 46.91 1306 5629
order to supplier: removal 3.50 85969 420
invoice (draft): creation 46.22 2952 5546
invoice (draft): removal 53.89 661 6467
invoice accept: apply 42.98 16658 5157
invoice accept: cancel 48.93 691 5871
customer reserve: creation 3957.13 167 474855
customer reserve: removal 4755.88 50 570705
realization accept: apply 84.43 172 10132
realization accept: cancel 80.57 106 9668
payment from customer: creation 60.09 94 7211
payment from customer: removal 5.76 63 691
payment to supplier: creation 50.10 103 6012
payment to supplier: removal 3.40 106 408
service: total inventory turnovers 4.97 978 596
service: total monetary turnovers 5.62 20 674
service: aggregate perf. data 0.72 25481 86
service: refresh index statistics 0.03 323904 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-01-2023 01:40:02 to 06-01-2023 01:40:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 451
TEST_TIME 2 2296
TEST_TIME 3 2855
TEST_TIME 4 3157
TEST_TIME 5 3497
TEST_TIME 6 3659
TEST_TIME 7 3763
TEST_TIME 8 3923
TEST_TIME 9 4160
TEST_TIME 10 4661
TEST_TIME 11 4814
TEST_TIME 12 4866
TEST_TIME 13 5158
TEST_TIME 14 5462
TEST_TIME 15 5897
TEST_TIME 16 6027
TEST_TIME 17 6508
TEST_TIME 18 6948
TEST_TIME 19 7422
TEST_TIME 20 7581
TEST_TIME 21 8131
TEST_TIME 22 8409
TEST_TIME 23 8884
TEST_TIME 24 9142
TEST_TIME 25 9107
TEST_TIME 26 10197
TEST_TIME 27 10138
TEST_TIME 28 10172
TEST_TIME 29 10142
TEST_TIME 30 9970
TEST_TIME 31 9996
TEST_TIME 32 10051
TEST_TIME 33 10277
TEST_TIME 34 10204
TEST_TIME 35 9942
TEST_TIME 36 9866
TEST_TIME 37 9729
TEST_TIME 38 9601
TEST_TIME 39 9604
TEST_TIME 40 9750
TEST_TIME 41 9663
TEST_TIME 42 9609
TEST_TIME 43 9585
TEST_TIME 44 9570
TEST_TIME 45 9494
TEST_TIME 46 9411
TEST_TIME 47 9352
TEST_TIME 48 9360
TEST_TIME 49 9254
TEST_TIME 50 9363
TEST_TIME 51 9366
TEST_TIME 52 9378
TEST_TIME 53 9375
TEST_TIME 54 9448
TEST_TIME 55 9451
TEST_TIME 56 9466
TEST_TIME 57 9458
TEST_TIME 58 9424
TEST_TIME 59 9643
TEST_TIME 60 9681
TEST_TIME 61 9762
TEST_TIME 62 9705
TEST_TIME 63 9620
TEST_TIME 64 9500
TEST_TIME 65 9477
TEST_TIME 66 9414
TEST_TIME 67 9590
TEST_TIME 68 9501
TEST_TIME 69 9458
TEST_TIME 70 9560
TEST_TIME 71 9581
TEST_TIME 72 9537
TEST_TIME 73 9541
TEST_TIME 74 9601
TEST_TIME 75 9574
TEST_TIME 76 9621
TEST_TIME 77 9664
TEST_TIME 78 9646
TEST_TIME 79 9716
TEST_TIME 80 9695
TEST_TIME 81 9582
TEST_TIME 82 9594
TEST_TIME 83 9546
TEST_TIME 84 9516
TEST_TIME 85 9483
TEST_TIME 86 9526
TEST_TIME 87 9481
TEST_TIME 88 9491
TEST_TIME 89 9446
TEST_TIME 90 9414
TEST_TIME 91 9337
TEST_TIME 92 9282
TEST_TIME 93 9419
TEST_TIME 94 9401
TEST_TIME 95 9405
TEST_TIME 96 9439
TEST_TIME 97 9417
TEST_TIME 98 9391
TEST_TIME 99 9500
TEST_TIME 100 9503
TEST_TIME 101 9587
TEST_TIME 102 9547
TEST_TIME 103 9558
TEST_TIME 104 9654
TEST_TIME 105 9629
TEST_TIME 106 9637
TEST_TIME 107 9703
TEST_TIME 108 9680
TEST_TIME 109 9614
TEST_TIME 110 9594
TEST_TIME 111 9562
TEST_TIME 112 9522
TEST_TIME 113 9529
TEST_TIME 114 9534
TEST_TIME 115 9531
TEST_TIME 116 9493
TEST_TIME 117 9467
TEST_TIME 118 9503
TEST_TIME 119 9481
TEST_TIME 120 9446

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-01-2023 01:40:06 to 06-01-2023 01:40:09.

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 1571629 1571629 0 0.00 0 42281 5 0 0
doc_list_biud 1571629 1571629 0 0.00 0 58366 23 0 0
sp_add_invoice_to_stock 5296 5157 139 2.62 53 200255 16659 0 139
sp_cancel_adding_invoice 5871 5871 0 0.00 3 81693 692 0 0
sp_cancel_client_order 1869 1869 0 0.00 4 12406 311 0 0
sp_cancel_customer_reserve 570705 570705 0 0.00 0 22166 51 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 7755 63 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 4599 106 0 0
sp_cancel_supplier_invoice 6478 6467 11 0.16 1 58424 662 0 11
sp_cancel_supplier_order 420 420 0 0.00 7 1043805 85970 0 0
sp_cancel_write_off 9668 9668 0 0.00 1 17221 107 0 0
sp_client_order 21225 21225 0 0.00 3 26941 338 0 0
sp_customer_reserve 474855 474855 0 0.00 0 86773 168 0 0
sp_fill_shopping_cart 32394 32394 0 0.00 1 13045 35 0 0
sp_fill_shopping_cart_clo_res 474854 474854 0 0.00 0 14167 12 0 0
sp_get_clo_for_invoice 5148 5148 0 0.00 0 22479 266 0 0
sp_kill_cost_storno 16638 16638 0 0.00 0 5794 47 0 0
sp_kill_qstorno_ret_qs2qd 1157965 1157965 0 0.00 0 58365 28 0 0
sp_kill_qty_storno 610278 610278 0 0.00 0 58366 60 0 0
sp_lock_selected_doc 21219 21219 0 0.00 0 8693 5 0 0
sp_make_cost_storno 28501 28501 0 0.00 0 17239 47 0 0
sp_multiply_rows_for_pdistr 28501 28501 0 0.00 0 7917 22 0 0
sp_multiply_rows_for_qdistr 21225 21225 0 0.00 0 21510 147 0 0
sp_pay_from_customer 7211 7211 0 0.00 4 15376 95 0 0
sp_pay_to_supplier 6012 6012 0 0.00 5 9634 104 0 0
sp_payment_common 13223 13223 0 0.00 2 9121 26 0 0
sp_qd_handle_on_cancel_clo 1869 1869 0 0.00 0 12398 287 0 0
sp_qd_handle_on_invoice_upd_sts 11017 11017 0 0.00 0 23936 275 0 0
sp_qd_handle_on_reserve_upd_sts 10132 10132 0 0.00 0 23866 67 0 0
sp_reserve_write_off 10132 10132 0 0.00 6 23939 173 0 0
sp_supplier_invoice 5546 5546 0 0.00 6 99105 2952 0 0
sp_supplier_order 5629 5629 0 0.00 5 50675 1307 0 0
srv_aggregate_perf_data 86 86 0 0.00 2710 557883 25482 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2482 96589 44355 0 0
srv_find_qd_qs_mism 1083795 1083795 0 0.00 0 42280 5 0 0
srv_make_invnt_saldo 596 596 0 0.00 41 35876 979 0 0
srv_make_money_saldo 674 674 0 0.00 0 670 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 166449 396060 323905 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5157 5157 0 0.00 0 19991 187 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 2751 82 0 0
t$perf-norm:sp_cancel_client_order 1869 1869 0 0.00 0 19524 21 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 2100 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 1270 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 1259 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 885 10 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 25374 1608 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 4370 18 0 0
t$perf-norm:sp_client_order 21225 21225 0 0.00 0 38803 18 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 16440 30 0 0
t$perf-norm:sp_pay_from_customer 7211 7211 0 0.00 0 13349 19 0 0
t$perf-norm:sp_pay_to_supplier 6012 6012 0 0.00 0 17561 13 0 0
t$perf-norm:sp_reserve_write_off 10132 10132 0 0.00 0 13382 16 0 0
t$perf-norm:sp_supplier_invoice 5546 5546 0 0.00 0 12101 18 0 0
t$perf-norm:sp_supplier_order 5629 5629 0 0.00 0 8816 15 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 365 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 265 8 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 620 12 0 0
t$perf-norm:srv_make_money_saldo 674 674 0 0.00 0 13380 33 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10570 10292 278 2.63 0 5247 15 0 278
v_all_customers 38468 38468 0 0.00 0 47 0 0 0
v_all_suppliers 30962 30962 0 0.00 0 256 1 0 0
v_all_wares 42450 42450 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 1173 6 0 0
v_cancel_client_order 3738 3738 0 0.00 0 3290 4 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 3293 6 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 3289 8 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 2257 8 0 0
v_cancel_supplier_invoice 824 802 22 2.66 0 4550 25 0 22
v_cancel_supplier_order 840 840 0 0.00 0 3291 13 0 0
v_cancel_write_off 1978 1978 0 0.00 0 5273 17 0 0
v_max_id_clo_ord 5628 5628 0 0.00 0 309 1 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 1251 9 0 0
v_max_id_ord_sup 5537 5537 0 0.00 0 4501 5 0 0
v_max_non_paid_invoice 1696 1696 0 0.00 0 4597 7 0 0
v_max_non_paid_realizn 3857 3857 0 0.00 0 4171 5 0 0
v_min_id_clo_ord 5628 5628 0 0.00 0 4972 7 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 2394 6 0 0
v_min_id_ord_sup 5537 5537 0 0.00 0 5822 6 0 0
v_min_non_paid_invoice 6012 6012 0 0.00 0 7300 18 0 0
v_min_non_paid_realizn 7211 7211 0 0.00 0 9113 21 0 0
v_our_firm 10690 10690 0 0.00 0 11 1 0 0
v_reserve_write_off 20264 20264 0 0.00 0 4471 6 0 0
x_lock_depdocs_on_canc_invoice 11937 11937 0 0.00 0 14933 95 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 5841 276 0 0
x_make_qstorno_1000_1200 5629 5629 0 0.00 1 44497 1240 0 0
x_make_qstorno_1200_2000 5546 5546 0 0.00 2 99090 2816 0 0
x_make_qstorno_2100_3300 474855 474855 0 0.00 0 86772 153 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 06-01-2023 01:40:10 to 06-01-2023 01:40:15.

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:43 17 8192000000 8816081376 8833245184 17279200 0.210 5 4 5 0 17507280 88816 139264
23:09:44 29621 8192000000 9902545184 10216271872 986858592 10.751 100 87 174 0 1016101632 6237136 23005904
23:11:21 20902 8192000000 9957601296 10283802624 1044742528 11.310 100 88 176 1 1075671552 8584576 22344448
23:12:50 12456 8192000000 9997804880 10333736960 1079979680 11.647 100 86 172 0 1111640400 9933680 21727040
23:14:09 6491 8192000000 10041416416 10391642112 1130463184 12.126 100 74 148 0 1159064784 11201760 17399840
23:15:21 6891 8192000000 10082485120 10447925248 1174893680 12.543 100 74 148 0 1203618528 12395248 16329600
23:16:32 3666 8192000000 10124571616 10495107072 1220688912 12.968 100 84 168 1 1252927392 12804864 19433616
23:17:39 2673 8192000000 10154991680 10531536896 1247954336 13.219 100 79 157 1 1278784624 13650336 17179952
23:18:43 1003 8192000000 10196049568 10576252928 1282340912 13.534 100 83 166 0 1313590384 12785904 18463568
23:19:46 1167 8192000000 10217938048 10601529344 1302774336 13.720 100 82 165 0 1333077104 11917264 18385504
23:20:48 3786 8192000000 10211367536 10610470912 1308242448 13.770 100 57 112 2 1332353520 11439872 12671200
23:21:52 6227 8192000000 10210333872 10614403072 1312581840 13.809 100 24 48 0 1324174096 6407392 5184864
23:23:00 11258 8192000000 10205223024 10624749568 1321100576 13.887 100 10 20 0 1329562192 6004048 2457568
23:24:12 56153 8192000000 10214137296 10638848000 1333260176 13.997 100 20 39 0 1343844784 5460896 5123712
23:26:08 7253 8192000000 10224568848 10645004288 1334909600 14.011 100 25 49 0 1345782336 5036336 5836400
23:27:18 8991 8192000000 10224794816 10651062272 1336801168 14.029 100 12 25 0 1344998320 5489168 2707984
23:28:30 16263 8192000000 10237489104 10651029504 1328415200 13.953 99 27 52 1 1341224752 5506416 7303136
23:29:46 17294 8192000000 10197590880 10610692096 1237961264 13.127 93 19 38 1 1247575296 5686000 3928032
23:31:06 6869 8192000000 10136099280 10529013760 1201893904 12.794 95 17 33 1 1211773680 5326656 4553120
23:32:16 13785 8192000000 10155088560 10553212928 1220693216 12.968 96 16 32 0 1229789280 5349552 3746512
23:33:30 67647 8192000000 10149129216 10558259200 1239597952 13.143 98 26 50 0 1251923456 5899792 6425712
23:35:46 5672 8192000000 10185710736 10591780864 1290240960 13.606 100 18 36 0 1299800496 5767968 3791568
23:36:54 14273 8192000000 10194987568 10609786880 1300685376 13.701 100 22 42 2 1315791072 9568112 5537584
23:38:10 13082 8192000000 10214565728 10632249344 1321509984 13.890 100 10 21 0 1332244048 9010672 1723392
23:39:26 85244 8192000000 10249660784 10661335040 1334944176 14.012 100 41 81 1 1353010512 8832864 9233472
23:41:58 14215 8192000000 10267137056 10687045632 1356474704 14.206 100 40 80 0 1381335664 15847504 9013456
23:43:19 13967 8192000000 10290900816 10710192128 1374331248 14.366 100 49 98 0 1405144032 19351568 11461216
23:44:36 18351 8192000000 10301836176 10720370688 1382956208 14.443 100 49 98 1 1416197648 22043360 11198080
23:46:11 9282 8192000000 10311711904 10733871104 1386122272 14.471 100 43 86 0 1423230032 27031088 10076672
23:47:30 5599 8192000000 10331606976 10755796992 1405975856 14.648 100 53 104 1 1447613424 29070496 12567072
23:48:39 8851 8192000000 10340787904 10764951552 1410909232 14.692 100 37 73 0 1452290416 32125104 9256080
23:49:51 5763 8192000000 10354643328 10780688384 1417951152 14.755 100 54 107 0 1466449712 35656928 12841632
23:51:00 2495 8192000000 10354808976 10776461312 1406142624 14.650 100 60 120 0 1460417120 39759952 14514544
23:52:07 3079 8192000000 10368846352 10784215040 1412894144 14.710 100 71 142 0 1472866608 41909184 18063280
23:53:24 13442 8192000000 10358808304 10786430976 1422036112 14.791 100 48 95 2 1470575120 35039008 13500000
23:54:45 2479 8192000000 10363075952 10779082752 1402481584 14.617 100 69 138 2 1466013344 46276784 17254976
23:55:51 7914 8192000000 10357334240 10782040064 1405085984 14.640 100 49 97 0 1467416432 48423536 13906912
23:57:09 8041 8192000000 10350005808 10773213184 1394151376 14.543 100 58 114 0 1457871344 49583872 14136096
23:58:21 6985 8192000000 10336056080 10760724480 1387255216 14.481 100 35 68 1 1441590688 45511472 8824000
23:59:30 14294 8192000000 10299833872 10734735360 1356365952 14.205 99 35 70 0 1407745808 41509088 9870768
00:00:55 25292 8192000000 10296814368 10732662784 1356713680 14.208 100 20 40 0 1401574880 39123232 5737968
00:02:22 18680 8192000000 10269232944 10697736192 1332715712 13.992 99 20 40 1 1370722480 32355952 5650816
00:03:45 32486 8192000000 10268731376 10731937792 1364913552 14.281 100 12 24 0 1399863232 31765600 3184080
00:05:25 37395 8192000000 10259929136 10705211392 1330116960 13.968 98 18 37 0 1346769040 12383872 4268208
00:07:08 80445 8192000000 10267366048 10699141120 1286441424 13.572 95 22 44 0 1303649776 12237248 4971104
00:09:40 52103 8192000000 10287953488 10713149440 1323849376 13.912 97 19 38 0 1338618128 10654816 4113936
00:11:40 65726 8192000000 10258604624 10699268096 1302805920 13.721 96 11 22 2 1315700624 10674160 2220544
00:14:15 67839 8192000000 10277273904 10708934656 1348729776 14.136 99 36 72 2 1364102912 6627312 8745824
00:16:25 42895 8192000000 10299234448 10736406528 1379067392 14.408 100 30 60 0 1393877328 8110464 6699472
00:18:10 35874 8192000000 10276141744 10745065472 1366152688 14.293 99 6 12 0 1378323408 10767952 1402768
00:19:47 88567 8192000000 10290542752 10746081280 1390301888 14.509 100 18 36 1 1405090160 10362320 4425952
00:22:15 15990 8192000000 10297338272 10758352896 1394810160 14.549 100 9 18 0 1403728992 7029424 1889408
00:23:32 60080 8192000000 10300687984 10763689984 1398648784 14.583 100 16 33 0 1410863920 8047024 4168112
00:25:45 21511 8192000000 10295856656 10756231168 1396509664 14.564 100 22 44 0 1409663712 8313008 4841040
00:27:08 56626 8192000000 10311001472 10779574272 1392884304 14.532 99 14 28 0 1417413856 21168032 3361520
00:29:27 21606 8192000000 10301441472 10758602752 1389451312 14.501 99 15 28 1 1399053744 6197728 3404704
00:30:49 20379 8192000000 10302473024 10758012928 1373331440 14.357 98 25 50 1 1387406432 8608704 5466288
00:32:12 70324 8192000000 10319281296 10786344960 1394015424 14.542 99 19 38 0 1413777088 15395136 4366528
00:34:23 28332 8192000000 10311797456 10760130560 1358927584 14.228 97 23 46 0 1372120656 8518016 4675056
00:35:52 83401 8192000000 10301798560 10768846848 1340135632 14.059 95 24 49 0 1364246944 18546896 5564416
00:38:27 80451 8192000000 10267667840 10722054144 1299991984 13.695 94 13 26 0 1316676096 14102896 2581216
00:40:54 84159 8192000000 10254206624 10695327744 1289700304 13.601 94 35 70 0 1307736944 10059824 7976816
00:43:33 52520 8192000000 10261777104 10716348416 1320091312 13.878 97 21 40 0 1339733200 15101344 4540544
00:45:33 70126 8192000000 10261758368 10725257216 1292971488 13.631 95 7 14 0 1312212688 18022640 1218560
00:47:46 45840 8192000000 10260470544 10679975936 1306167184 13.751 96 39 78 0 1327052992 11521120 9364688
00:49:44 67938 8192000000 10252417168 10694721536 1301074656 13.705 96 13 26 0 1313299840 9373744 2851440
00:51:52 60003 8192000000 10265640512 10717163520 1343998144 14.093 99 20 40 0 1359511664 10914656 4598864
00:54:02 81090 8192000000 10277267888 10718818304 1334042368 14.004 98 20 40 0 1349534736 11271120 4221248
00:56:27 76327 8192000000 10291069952 10741334016 1354499488 14.188 98 15 30 0 1369224928 11416144 3309296
00:58:44 83071 8192000000 10269424864 10714202112 1343644864 14.090 98 25 50 1 1359466192 10568816 5252512
01:01:26 26285 8192000000 10275668576 10717401088 1336012688 14.021 97 28 53 1 1348675840 6346080 6317072
01:03:02 33949 8192000000 10277983664 10726055936 1365756880 14.289 99 28 56 0 1384417248 12292720 6367648
01:04:43 65572 8192000000 10271098176 10733064192 1372980048 14.354 100 5 10 0 1384750448 10719328 1051072
01:06:51 40773 8192000000 10280487952 10739814400 1381543456 14.430 100 13 25 0 1390772240 6504640 2724144
01:08:35 43715 8192000000 10307150416 10766737408 1400971344 14.604 100 17 32 1 1419935520 15051584 3912592
01:10:19 96589 8192000000 10296162672 10785755136 1400506416 14.600 100 11 21 0 1422569584 19913392 2149776
01:13:15 45922 8192000000 10284717904 10740125696 1327626800 13.946 95 16 32 0 1338135888 7353936 3155152
01:15:10 61416 8192000000 10318266160 10757918720 1356000272 14.201 97 29 58 1 1379186576 17080400 6105904
01:17:19 63790 8192000000 10307551168 10766761984 1390398864 14.509 100 15 30 0 1410358160 16256144 3703152
01:19:24 71547 8192000000 10308467872 10768867328 1382346944 14.438 99 12 24 1 1403558064 18049568 3161552
01:21:35 46887 8192000000 10307776096 10759004160 1359861104 14.236 98 28 56 0 1381571648 15775168 5935376
01:23:24 36863 8192000000 10309153984 10749190144 1375551104 14.377 99 46 92 1 1395369824 9749360 10069360
01:25:06 78532 8192000000 10293249552 10765922304 1363883280 14.272 98 12 25 0 1386847664 19885840 3078544
01:27:24 67376 8192000000 10275527152 10729910272 1367855200 14.308 99 25 50 0 1381741888 7990880 5895808
01:29:33 38295 8192000000 10276281056 10721898496 1307863248 13.767 94 24 49 0 1326092800 13286464 4943088
01:31:11 55751 8192000000 10267228000 10734157824 1352166320 14.167 99 8 16 0 1369097472 15321328 1609824
01:33:26 51945 8192000000 10252525664 10701316096 1285364416 13.562 94 17 34 0 1309094080 20071296 3658368
01:35:20 56751 8192000000 10251773696 10708008960 1339821920 14.056 99 16 32 0 1357025344 13042704 4160720
01:37:32 53957 8192000000 10264284064 10711478272 1321017920 13.886 97 25 50 0 1333708736 7033120 5657696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-01-2023 01:40:16 to 06-01-2023 01:40:32.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-01-2023 01:40:33 to 06-01-2023 01:40:35.

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          243835
MON$OLDEST_ACTIVE               244993
MON$OLDEST_SNAPSHOT             244993
MON$NEXT_TRANSACTION            244993
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                       2033312
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 ---
:1723: 
:1724: mscs-test-161.budzdorov.ru    Fri Jan  6 01:38:43 2023
:1725:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1726: 
:1727: 
:1728: mscs-test-161.budzdorov.ru    Fri Jan  6 01:38:46 2023
:1729:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1730: 
--- end of diff output ---

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

06.01.23 01:40:36. 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-06 01:40:38 No severe PSQL-related problems occured
06.01.23 01:40:40. End of report.