Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.02.2023 19: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 15220184 kB
MemAvailable 30464040 kB
Buffers 0 kB
Cached 16510904 kB
SwapCached 3644 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-08 19:38:44
DTS_END                         2023-02-08 19:38:44.1750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34060
TRN_ID                          237761
ATT_ID                          702
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: 9241.31 22884 1108957
customer order: creation 168.17 344 20180
customer order: refuse 13.94 378 1673
order to supplier: creation 45.03 1193 5403
order to supplier: removal 3.44 87051 413
invoice (draft): creation 46.20 3379 5544
invoice (draft): removal 52.92 731 6350
invoice accept: apply 41.93 18371 5032
invoice accept: cancel 47.56 733 5707
customer reserve: creation 3866.66 182 463999
customer reserve: removal 4665.94 50 559913
realization accept: apply 79.76 185 9571
realization accept: cancel 84.09 109 10091
payment from customer: creation 58.01 122 6961
payment from customer: removal 5.94 90 713
payment to supplier: creation 47.05 126 5646
payment to supplier: removal 3.23 86 388
service: total inventory turnovers 4.67 1125 560
service: total monetary turnovers 6.03 29 724
service: aggregate perf. data 0.71 23146 85
service: refresh index statistics 0.03 320250 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-02-2023 19:40:54 to 08-02-2023 19:40:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2413
TEST_TIME 2 3150
TEST_TIME 3 3019
TEST_TIME 4 4164
TEST_TIME 5 5158
TEST_TIME 6 5550
TEST_TIME 7 5508
TEST_TIME 8 5220
TEST_TIME 9 5060
TEST_TIME 10 5326
TEST_TIME 11 5201
TEST_TIME 12 5428
TEST_TIME 13 5790
TEST_TIME 14 6107
TEST_TIME 15 6416
TEST_TIME 16 6872
TEST_TIME 17 6931
TEST_TIME 18 7233
TEST_TIME 19 7389
TEST_TIME 20 7852
TEST_TIME 21 8015
TEST_TIME 22 8064
TEST_TIME 23 8753
TEST_TIME 24 9032
TEST_TIME 25 9470
TEST_TIME 26 9646
TEST_TIME 27 9962
TEST_TIME 28 10082
TEST_TIME 29 10226
TEST_TIME 30 10452
TEST_TIME 31 10367
TEST_TIME 32 10229
TEST_TIME 33 10100
TEST_TIME 34 9931
TEST_TIME 35 9891
TEST_TIME 36 9715
TEST_TIME 37 9763
TEST_TIME 38 9722
TEST_TIME 39 9606
TEST_TIME 40 9510
TEST_TIME 41 9595
TEST_TIME 42 9448
TEST_TIME 43 9509
TEST_TIME 44 9588
TEST_TIME 45 9521
TEST_TIME 46 9478
TEST_TIME 47 9448
TEST_TIME 48 9395
TEST_TIME 49 9254
TEST_TIME 50 9229
TEST_TIME 51 9205
TEST_TIME 52 9222
TEST_TIME 53 9183
TEST_TIME 54 9195
TEST_TIME 55 9108
TEST_TIME 56 9077
TEST_TIME 57 9478
TEST_TIME 58 9432
TEST_TIME 59 9466
TEST_TIME 60 9437
TEST_TIME 61 9599
TEST_TIME 62 9650
TEST_TIME 63 9625
TEST_TIME 64 9729
TEST_TIME 65 9589
TEST_TIME 66 9519
TEST_TIME 67 9474
TEST_TIME 68 9455
TEST_TIME 69 9431
TEST_TIME 70 9355
TEST_TIME 71 9261
TEST_TIME 72 9256
TEST_TIME 73 9254
TEST_TIME 74 9205
TEST_TIME 75 9159
TEST_TIME 76 9168
TEST_TIME 77 9136
TEST_TIME 78 9218
TEST_TIME 79 9250
TEST_TIME 80 9310
TEST_TIME 81 9349
TEST_TIME 82 9371
TEST_TIME 83 9405
TEST_TIME 84 9416
TEST_TIME 85 9511
TEST_TIME 86 9492
TEST_TIME 87 9464
TEST_TIME 88 9368
TEST_TIME 89 9334
TEST_TIME 90 9329
TEST_TIME 91 9363
TEST_TIME 92 9380
TEST_TIME 93 9323
TEST_TIME 94 9350
TEST_TIME 95 9327
TEST_TIME 96 9328
TEST_TIME 97 9370
TEST_TIME 98 9317
TEST_TIME 99 9323
TEST_TIME 100 9360
TEST_TIME 101 9374
TEST_TIME 102 9391
TEST_TIME 103 9381
TEST_TIME 104 9405
TEST_TIME 105 9399
TEST_TIME 106 9493
TEST_TIME 107 9510
TEST_TIME 108 9486
TEST_TIME 109 9509
TEST_TIME 110 9530
TEST_TIME 111 9457
TEST_TIME 112 9439
TEST_TIME 113 9426
TEST_TIME 114 9428
TEST_TIME 115 9396
TEST_TIME 116 9370
TEST_TIME 117 9365
TEST_TIME 118 9345
TEST_TIME 119 9291
TEST_TIME 120 9243

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-02-2023 19:40:57 to 08-02-2023 19:41:01.

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 1536809 1536809 0 0.00 0 14985 6 0 0
doc_list_biud 1536809 1536809 0 0.00 0 36421 24 0 0
sp_add_invoice_to_stock 5152 5032 120 2.32 32 177873 18371 0 120
sp_cancel_adding_invoice 5707 5707 0 0.00 3 60454 733 0 0
sp_cancel_client_order 1673 1673 0 0.00 4 21169 378 0 0
sp_cancel_customer_reserve 559913 559913 0 0.00 0 16327 50 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 5495 91 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 2 5913 86 0 0
sp_cancel_supplier_invoice 6359 6350 9 0.14 1 36427 731 0 9
sp_cancel_supplier_order 413 413 0 0.00 6 1026941 87052 0 0
sp_cancel_write_off 10091 10091 0 0.00 1 21606 110 0 0
sp_client_order 20180 20180 0 0.00 3 31157 344 0 0
sp_customer_reserve 463999 463999 0 0.00 0 119606 182 0 0
sp_fill_shopping_cart 31126 31126 0 0.00 1 8930 40 0 0
sp_fill_shopping_cart_clo_res 463996 463996 0 0.00 0 15086 14 0 0
sp_get_clo_for_invoice 5027 5027 0 0.00 0 17687 332 0 0
sp_kill_cost_storno 16899 16899 0 0.00 0 12057 53 0 0
sp_kill_qstorno_ret_qs2qd 1136680 1136680 0 0.00 0 36416 27 0 0
sp_kill_qty_storno 598743 598743 0 0.00 0 36419 60 0 0
sp_lock_selected_doc 20191 20191 0 0.00 0 8491 6 0 0
sp_make_cost_storno 27203 27203 0 0.00 0 12367 57 0 0
sp_multiply_rows_for_pdistr 27203 27203 0 0.00 0 7107 28 0 0
sp_multiply_rows_for_qdistr 20180 20180 0 0.00 0 13261 145 0 0
sp_pay_from_customer 6961 6961 0 0.00 4 9764 123 0 0
sp_pay_to_supplier 5646 5646 0 0.00 4 17433 127 0 0
sp_payment_common 12607 12607 0 0.00 1 7087 27 0 0
sp_qd_handle_on_cancel_clo 1673 1673 0 0.00 0 21161 338 0 0
sp_qd_handle_on_invoice_upd_sts 10732 10732 0 0.00 0 31016 301 0 0
sp_qd_handle_on_reserve_upd_sts 9571 9571 0 0.00 0 34029 63 0 0
sp_reserve_write_off 9571 9571 0 0.00 6 34063 186 0 0
sp_supplier_invoice 5544 5544 0 0.00 6 65244 3380 0 0
sp_supplier_order 5403 5403 0 0.00 5 25454 1193 0 0
srv_aggregate_perf_data 85 85 0 0.00 2912 433579 23146 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1380 156683 43401 0 0
srv_find_qd_qs_mism 1060923 1060923 0 0.00 0 14985 5 0 0
srv_make_invnt_saldo 560 560 0 0.00 40 30536 1126 0 0
srv_make_money_saldo 724 724 0 0.00 0 959 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 201145 408082 320250 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5032 5032 0 0.00 0 11273 189 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 4553 106 0 0
t$perf-norm:sp_cancel_client_order 1673 1673 0 0.00 0 6341 15 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 655 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 1454 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 611 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 630 11 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 32100 1613 0 0
t$perf-norm:sp_cancel_write_off 928 928 0 0.00 0 2286 8 0 0
t$perf-norm:sp_client_order 20181 20181 0 0.00 0 9255 17 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 4500 16 0 0
t$perf-norm:sp_pay_from_customer 6961 6961 0 0.00 0 9270 12 0 0
t$perf-norm:sp_pay_to_supplier 5646 5646 0 0.00 0 5907 11 0 0
t$perf-norm:sp_reserve_write_off 9571 9571 0 0.00 0 7706 13 0 0
t$perf-norm:sp_supplier_invoice 5544 5544 0 0.00 0 9171 23 0 0
t$perf-norm:sp_supplier_order 5403 5403 0 0.00 0 5944 18 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 341 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1009 17 0 0
t$perf-norm:srv_make_invnt_saldo 561 561 0 0.00 0 7623 40 0 0
t$perf-norm:srv_make_money_saldo 724 724 0 0.00 0 3555 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10290 10050 240 2.33 0 10734 23 0 240
v_all_customers 36976 36976 0 0.00 0 18 1 0 0
v_all_suppliers 30022 30022 0 0.00 0 54 1 0 0
v_all_wares 40360 40360 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 2844 10 0 0
v_cancel_client_order 3346 3346 0 0.00 0 10776 11 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 959 4 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 2720 8 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 2070 7 0 0
v_cancel_supplier_invoice 788 770 18 2.28 0 4475 22 0 18
v_cancel_supplier_order 826 826 0 0.00 0 742 6 0 0
v_cancel_write_off 1856 1856 0 0.00 0 3831 17 0 0
v_max_id_clo_ord 5403 5403 0 0.00 0 179 1 0 0
v_max_id_clo_res 977 977 0 0.00 0 1183 11 0 0
v_max_id_ord_sup 5543 5543 0 0.00 0 5493 5 0 0
v_max_non_paid_invoice 1581 1581 0 0.00 0 4606 10 0 0
v_max_non_paid_realizn 3655 3655 0 0.00 0 5869 6 0 0
v_min_id_clo_ord 5403 5403 0 0.00 0 3305 6 0 0
v_min_id_clo_res 977 977 0 0.00 0 3676 6 0 0
v_min_id_ord_sup 5543 5543 0 0.00 0 4225 6 0 0
v_min_non_paid_invoice 5646 5646 0 0.00 0 4978 13 0 0
v_min_non_paid_realizn 6961 6961 0 0.00 0 6207 19 0 0
v_our_firm 9996 9996 0 0.00 0 24 1 0 0
v_reserve_write_off 19142 19142 0 0.00 0 7604 10 0 0
x_lock_depdocs_on_canc_invoice 11672 11672 0 0.00 0 13837 110 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 12747 354 0 0
x_make_qstorno_1000_1200 5403 5403 0 0.00 1 25443 1116 0 0
x_make_qstorno_1200_2000 5544 5544 0 0.00 2 65147 3236 0 0
x_make_qstorno_2100_3300 463999 463999 0 0.00 0 119464 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-02-2023 19:41:02 to 08-02-2023 19:41:06.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:48 8 8192000000 8816500640 8833765376 20065792 0.244 6 5 6 0 20336720 99472 171456
17:09:49 23533 8192000000 9906850320 10215878656 981664944 10.700 100 87 174 0 1011195744 5738320 23792480
17:11:17 15068 8192000000 9942706368 10261790720 1025621824 11.126 100 91 182 0 1057748480 7960704 24165952
17:12:39 16896 8192000000 9979352640 10308841472 1057646896 11.434 100 80 160 0 1086766864 8944032 20175936
17:14:00 7228 8192000000 10020346160 10365865984 1110894656 11.941 100 77 152 0 1139401920 10347168 18160096
17:15:10 7315 8192000000 10078188288 10436714496 1167638592 12.475 100 82 164 0 1199119936 12795776 18685568
17:16:21 4191 8192000000 10119603984 10483691520 1205108512 12.824 100 88 176 2 1239212496 14307792 19796192
17:17:26 3090 8192000000 10149559376 10524696576 1237724720 13.125 100 80 158 0 1268680400 13588432 17367248
17:18:31 2413 8192000000 10185076912 10563710976 1268857024 13.411 100 82 161 1 1300760416 13484896 18418496
17:19:35 1113 8192000000 10213049696 10596990976 1297405616 13.672 100 90 180 1 1331589552 14352992 19830944
17:20:36 15369 8192000000 10216645024 10614849536 1313445152 13.817 100 59 116 1 1340057504 13965712 12646640
17:21:53 7932 8192000000 10209080480 10622603264 1319061984 13.868 100 21 43 1 1329426496 5884944 4479568
17:23:06 13455 8192000000 10218409952 10637168640 1329023424 13.958 100 14 28 0 1338073376 5736528 3313424
17:24:20 26093 8192000000 10222456864 10646835200 1333833424 14.002 100 24 48 0 1345177520 5325648 6018448
17:25:46 3931 8192000000 10239321680 10655625216 1341728976 14.073 100 31 64 1 1354997408 5213888 8054544
17:26:51 10325 8192000000 10234379424 10656378880 1342457696 14.080 100 19 38 0 1352576144 5653696 4464752
17:28:04 12547 8192000000 10228885264 10646482944 1297097952 13.669 97 24 47 2 1308007360 5254000 5655408
17:29:21 21263 8192000000 10211065520 10631106560 1302505712 13.718 98 19 38 2 1311545008 4751328 4287968
17:30:44 6002 8192000000 10177727312 10579263488 1229926736 13.053 95 30 58 0 1243186320 5292976 7966608
17:31:52 6820 8192000000 10133886448 10536370176 1224737904 13.005 98 21 42 0 1234823664 5150976 4934784
17:32:59 11904 8192000000 10145940960 10549100544 1249252432 13.231 99 27 54 1 1259620336 4703136 5664768
17:34:13 21010 8192000000 10166383952 10573914112 1274038256 13.459 100 41 84 1 1290175008 6974752 9162000
17:35:36 5336 8192000000 10177561728 10585366528 1286288384 13.570 100 18 36 0 1295722528 5566192 3867952
17:36:44 5462 8192000000 10186762304 10599493632 1296757264 13.666 100 24 46 2 1308403328 5086224 6559840
17:37:49 8905 8192000000 10196700352 10610610176 1305171968 13.742 100 19 38 0 1314576384 5161872 4242544
17:39:08 37893 8192000000 10230142816 10642153472 1327323840 13.943 100 45 89 0 1343980096 6674464 9981792
17:40:52 22027 8192000000 10251563744 10661433344 1345586272 14.108 100 59 118 1 1369737984 10727488 13424224
17:42:17 26163 8192000000 10259562880 10676912128 1356355104 14.205 100 44 87 0 1377271968 10850896 10065968
17:43:48 3782 8192000000 10292328096 10703458304 1374646368 14.369 100 67 134 2 1407351952 17867936 14837648
17:44:56 13265 8192000000 10284555744 10709209088 1378753584 14.405 100 38 76 1 1400304176 12621584 8929008
17:46:17 27964 8192000000 10299504528 10727084032 1396887760 14.567 100 43 86 0 1420675536 13427792 10359984
17:48:00 15876 8192000000 10308010160 10740690944 1398862720 14.585 100 39 76 1 1424785824 16735584 9187520
17:49:23 16891 8192000000 10322876304 10747772928 1406392976 14.652 100 57 107 0 1438353136 19093152 12867008
17:50:44 4935 8192000000 10343376048 10761621504 1413396464 14.714 100 69 134 3 1453559072 24348176 15814432
17:51:52 3277 8192000000 10358634528 10773680128 1416455248 14.741 100 78 155 3 1466933520 30717440 19760832
17:52:59 2336 8192000000 10333480640 10742906880 1371114928 14.337 98 73 144 2 1424409056 36164880 17129248
17:54:06 1846 8192000000 10307215328 10707156992 1353640640 14.180 99 82 161 2 1408557808 33459520 21457648
17:55:13 2154 8192000000 10304104496 10698235904 1344758960 14.100 98 88 175 0 1400075072 34180560 21135552
17:56:19 3793 8192000000 10324608784 10731573248 1370044816 14.327 100 71 142 0 1428425376 40450384 17930176
17:57:34 1379 8192000000 10315413984 10719428608 1357582384 14.216 100 70 136 1 1416272064 41827936 16861744
17:58:40 7344 8192000000 10275437216 10689310720 1323014496 13.904 99 49 92 1 1374838320 40023056 11800768
17:59:53 13058 8192000000 10293911120 10710515712 1338438864 14.043 100 36 71 0 1392653456 44908320 9306272
18:01:12 2940 8192000000 10326753104 10738683904 1358851616 14.227 100 59 112 0 1426435072 53235504 14347952
18:02:24 9826 8192000000 10297868224 10722246656 1356518032 14.206 100 37 72 0 1402732272 36898336 9315904
18:03:36 31970 8192000000 10283216976 10720165888 1345334544 14.105 99 17 34 0 1381803920 31894464 4574912
18:05:58 23061 8192000000 10277438624 10708529152 1339803296 14.056 99 20 40 0 1365416832 20556912 5056624
18:07:23 59240 8192000000 10260104832 10714361856 1344274464 14.096 99 15 30 0 1371658896 23769504 3614928
18:09:23 36729 8192000000 10275758128 10706849792 1345859104 14.110 98 31 65 1 1361639776 7999648 7781024
18:11:04 79318 8192000000 10286393968 10733326336 1381283376 14.428 100 25 46 1 1394372816 7609152 5480288
18:13:30 35868 8192000000 10303756112 10748354560 1394371552 14.545 100 37 72 0 1413123392 10705056 8046784
18:15:09 109356 8192000000 10302693136 10745217024 1351135440 14.158 96 25 50 0 1367539168 10345600 6058128
18:17:59 33693 8192000000 10300713248 10754203648 1367222848 14.302 97 25 46 0 1386798736 14151344 5424544
18:19:36 48436 8192000000 10304313888 10766278656 1396759536 14.566 100 14 28 0 1418605776 18426544 3419696
18:21:27 22456 8192000000 10301925776 10762948608 1393860608 14.540 100 9 18 0 1408532688 12196128 2475952
18:23:27 68765 8192000000 10294570848 10765103104 1399914528 14.594 100 22 45 0 1414094432 8856656 5323248
18:25:39 34732 8192000000 10290491360 10759553024 1387977408 14.488 99 22 44 0 1401536560 8498048 5061104
18:27:15 90110 8192000000 10288900560 10762948608 1387842448 14.487 99 11 23 0 1399227472 8625344 2759680
18:29:52 14672 8192000000 10310477168 10765959168 1401739968 14.610 100 33 63 1 1415534688 5849440 7945280
18:31:17 31681 8192000000 10302417488 10768121856 1390991440 14.515 99 19 38 0 1403742736 8087152 4664144
18:32:49 58196 8192000000 10326421232 10781908992 1407436800 14.661 100 14 28 0 1427468816 16340576 3691440
18:34:55 47189 8192000000 10278832896 10754805760 1362865760 14.263 99 14 28 0 1394807024 28060736 3880528
18:36:55 46842 8192000000 10282015792 10733240320 1350531536 14.152 98 27 53 0 1367503024 10915536 6055952
18:39:01 68005 8192000000 10277975776 10744057856 1314530672 13.827 95 17 34 0 1341720944 23474944 3715328
18:41:15 69738 8192000000 10269737616 10723454976 1320640848 13.883 96 21 42 0 1338140352 12866688 4632816
18:43:25 61898 8192000000 10267656656 10714013696 1340900352 14.066 98 31 62 1 1357096928 9364048 6832528
18:45:27 31574 8192000000 10269376128 10723229696 1352660400 14.171 99 18 36 0 1366541728 9804160 4077168
18:47:06 62883 8192000000 10248674320 10711642112 1345087808 14.103 99 4 8 0 1355601952 9934864 579280
18:49:15 65883 8192000000 10274610368 10728038400 1369034640 14.318 100 14 28 0 1383156032 10462176 3659216
18:51:39 69124 8192000000 10267466144 10739531776 1358497264 14.224 99 10 20 0 1377685600 16656368 2531968
18:54:16 32752 8192000000 10277709728 10736939008 1366058384 14.292 99 22 44 0 1386200448 14920128 5221936
18:55:53 156683 8192000000 10281989456 10755407872 1348337584 14.133 98 9 18 0 1368449456 17981792 2130080
18:59:29 33979 8192000000 10270847536 10731450368 1340837184 14.065 98 23 46 0 1364025200 17808512 5379504
19:01:10 76944 8192000000 10295622720 10760081408 1350044496 14.148 98 8 16 0 1365016480 13032656 1939328
19:03:27 140516 8192000000 10289657712 10727645184 1328033200 13.949 96 21 41 2 1357198688 23897712 5267776
19:06:49 66409 8192000000 10306857648 10755588096 1356343056 14.205 98 20 40 0 1376283184 15709136 4230992
19:08:57 43075 8192000000 10292426352 10759045120 1383013296 14.443 100 19 38 0 1412856160 25799184 4043680
19:10:40 52074 8192000000 10289402704 10761064448 1378861136 14.406 99 7 14 0 1395602800 15197696 1543968
19:12:33 55928 8192000000 10297349488 10767343616 1391031488 14.515 100 9 18 0 1405273744 12725104 1517152
19:14:29 61621 8192000000 10296749616 10756018176 1363398400 14.268 97 15 30 0 1376425536 9710448 3316688
19:16:34 25902 8192000000 10307243072 10751062016 1377204896 14.392 99 48 95 1 1397245264 9624384 10415984
19:18:01 37609 8192000000 10299015360 10766872576 1393862448 14.540 100 12 24 2 1405662784 9179904 2620432
19:19:42 62124 8192000000 10300522176 10760232960 1355658672 14.198 97 14 28 0 1370122960 11661200 2803088
19:21:44 51915 8192000000 10323059536 10772332544 1380077728 14.417 99 31 60 0 1406704336 20000112 6626496
19:23:42 42876 8192000000 10315953648 10765586432 1390766288 14.513 100 34 68 0 1416020304 17774512 7479504
19:25:30 73483 8192000000 10273954352 10717040640 1309955360 13.786 95 8 16 1 1325127872 13809808 1362704
19:27:45 79160 8192000000 10277258880 10752036864 1370583792 14.332 100 9 16 0 1388789520 16593664 1612064
19:31:03 60911 8192000000 10257710192 10712715264 1339785568 14.055 98 19 37 0 1356835424 12356384 4693472
19:33:09 45847 8192000000 10244184080 10703073280 1340762240 14.064 99 7 12 0 1352794080 10628416 1403424
19:34:55 44980 8192000000 10235189952 10684477440 1312850160 13.812 97 19 38 0 1327071008 9565088 4655760
19:37:26 58070 8192000000 10241383696 10689978368 1311991760 13.804 97 20 40 0 1326553264 9718256 4843248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-02-2023 19:41:08 to 08-02-2023 19:41:23.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-02-2023 19:41:24 to 08-02-2023 19:41:26.

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          235067
MON$OLDEST_ACTIVE               237871
MON$OLDEST_SNAPSHOT             237871
MON$NEXT_TRANSACTION            237871
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                       2046304
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.33661 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33661 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33661 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33661 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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Wed Feb  8 19:38:53 2023
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
--- end of diff output ---

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

08.02.23 19:41:27. 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-08 19:41:30 No severe PSQL-related problems occured
08.02.23 19:41:31. End of report.