Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.06.2022 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 16469012 kB
MemAvailable 31328992 kB
Buffers 0 kB
Cached 15280672 kB
SwapCached 2432 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.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-11 19:38:16
DTS_END                         2022-06-11 19:38:16.9790
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/36048
TRN_ID                          235284
ATT_ID                          645
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: 9162.16 24286 1099459
customer order: creation 165.48 377 19857
customer order: refuse 13.87 439 1664
order to supplier: creation 43.73 1280 5248
order to supplier: removal 3.45 95335 414
invoice (draft): creation 43.88 3708 5265
invoice (draft): removal 54.00 725 6480
invoice accept: apply 39.90 17027 4788
invoice accept: cancel 48.27 766 5792
customer reserve: creation 3687.30 167 442476
customer reserve: removal 4769.68 54 572362
realization accept: apply 80.20 201 9624
realization accept: cancel 86.81 99 10417
payment from customer: creation 56.88 113 6826
payment from customer: removal 5.66 90 679
payment to supplier: creation 48.04 182 5765
payment to supplier: removal 3.40 55 408
service: total inventory turnovers 4.61 1086 553
service: total monetary turnovers 6.22 35 746
service: aggregate perf. data 0.76 22476 91
service: refresh index statistics 0.03 341495 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-06-2022 19:40:29 to 11-06-2022 19:40:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4519
TEST_TIME 2 3911
TEST_TIME 3 3303
TEST_TIME 4 4184
TEST_TIME 5 4887
TEST_TIME 6 5247
TEST_TIME 7 5556
TEST_TIME 8 5700
TEST_TIME 9 5725
TEST_TIME 10 6129
TEST_TIME 11 6299
TEST_TIME 12 6491
TEST_TIME 13 6735
TEST_TIME 14 6861
TEST_TIME 15 7142
TEST_TIME 16 7374
TEST_TIME 17 7806
TEST_TIME 18 8330
TEST_TIME 19 8736
TEST_TIME 20 9160
TEST_TIME 21 9017
TEST_TIME 22 9165
TEST_TIME 23 9055
TEST_TIME 24 9255
TEST_TIME 25 10164
TEST_TIME 26 10661
TEST_TIME 27 10410
TEST_TIME 28 10323
TEST_TIME 29 10157
TEST_TIME 30 9969
TEST_TIME 31 9973
TEST_TIME 32 9960
TEST_TIME 33 9880
TEST_TIME 34 9721
TEST_TIME 35 9583
TEST_TIME 36 9564
TEST_TIME 37 9408
TEST_TIME 38 9359
TEST_TIME 39 9263
TEST_TIME 40 9360
TEST_TIME 41 9264
TEST_TIME 42 9199
TEST_TIME 43 9049
TEST_TIME 44 9047
TEST_TIME 45 9060
TEST_TIME 46 9151
TEST_TIME 47 9149
TEST_TIME 48 9230
TEST_TIME 49 9186
TEST_TIME 50 9213
TEST_TIME 51 9291
TEST_TIME 52 9212
TEST_TIME 53 9334
TEST_TIME 54 9340
TEST_TIME 55 9368
TEST_TIME 56 9342
TEST_TIME 57 9446
TEST_TIME 58 9512
TEST_TIME 59 9539
TEST_TIME 60 9485
TEST_TIME 61 9498
TEST_TIME 62 9421
TEST_TIME 63 9331
TEST_TIME 64 9274
TEST_TIME 65 9322
TEST_TIME 66 9281
TEST_TIME 67 9245
TEST_TIME 68 9269
TEST_TIME 69 9225
TEST_TIME 70 9154
TEST_TIME 71 9083
TEST_TIME 72 9165
TEST_TIME 73 9131
TEST_TIME 74 9098
TEST_TIME 75 9133
TEST_TIME 76 9115
TEST_TIME 77 9166
TEST_TIME 78 9264
TEST_TIME 79 9258
TEST_TIME 80 9224
TEST_TIME 81 9296
TEST_TIME 82 9333
TEST_TIME 83 9331
TEST_TIME 84 9348
TEST_TIME 85 9440
TEST_TIME 86 9374
TEST_TIME 87 9283
TEST_TIME 88 9301
TEST_TIME 89 9230
TEST_TIME 90 9257
TEST_TIME 91 9267
TEST_TIME 92 9224
TEST_TIME 93 9153
TEST_TIME 94 9120
TEST_TIME 95 9110
TEST_TIME 96 9121
TEST_TIME 97 9093
TEST_TIME 98 9132
TEST_TIME 99 9143
TEST_TIME 100 9122
TEST_TIME 101 9240
TEST_TIME 102 9381
TEST_TIME 103 9389
TEST_TIME 104 9421
TEST_TIME 105 9390
TEST_TIME 106 9401
TEST_TIME 107 9513
TEST_TIME 108 9471
TEST_TIME 109 9414
TEST_TIME 110 9387
TEST_TIME 111 9362
TEST_TIME 112 9364
TEST_TIME 113 9337
TEST_TIME 114 9313
TEST_TIME 115 9277
TEST_TIME 116 9281
TEST_TIME 117 9256
TEST_TIME 118 9227
TEST_TIME 119 9227
TEST_TIME 120 9167

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-06-2022 19:40:33 to 11-06-2022 19:40:36.

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 1509447 1509447 0 0.00 0 28594 6 0 0
doc_list_biud 1509446 1509446 0 0.00 0 29864 26 0 0
sp_add_invoice_to_stock 4907 4788 119 2.42 52 161449 17028 0 119
sp_cancel_adding_invoice 5792 5792 0 0.00 5 67885 766 0 0
sp_cancel_client_order 1664 1664 0 0.00 4 14623 440 0 0
sp_cancel_customer_reserve 572362 572362 0 0.00 1 29867 55 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 7551 91 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 1547 55 0 0
sp_cancel_supplier_invoice 6487 6480 7 0.10 2 16677 725 0 7
sp_cancel_supplier_order 414 414 0 0.00 10 1015546 95335 0 0
sp_cancel_write_off 10417 10417 0 0.00 1 12958 99 0 0
sp_client_order 19857 19857 0 0.00 3 29345 377 0 0
sp_customer_reserve 442476 442476 0 0.00 0 45360 168 0 0
sp_fill_shopping_cart 30364 30364 0 0.00 1 29389 42 0 0
sp_fill_shopping_cart_clo_res 442467 442467 0 0.00 0 14636 15 0 0
sp_get_clo_for_invoice 4779 4779 0 0.00 0 29214 359 0 0
sp_kill_cost_storno 17296 17296 0 0.00 0 14423 52 0 0
sp_kill_qstorno_ret_qs2qd 1162035 1162035 0 0.00 0 29856 30 0 0
sp_kill_qty_storno 611532 611532 0 0.00 0 29863 65 0 0
sp_lock_selected_doc 19963 19963 0 0.00 0 8195 5 0 0
sp_make_cost_storno 26994 26994 0 0.00 0 25688 69 0 0
sp_multiply_rows_for_pdistr 26994 26994 0 0.00 0 24595 38 0 0
sp_multiply_rows_for_qdistr 19857 19857 0 0.00 0 16613 171 0 0
sp_pay_from_customer 6826 6826 0 0.00 4 26865 114 0 0
sp_pay_to_supplier 5765 5765 0 0.00 5 28935 183 0 0
sp_payment_common 12591 12591 0 0.00 1 24468 41 0 0
sp_qd_handle_on_cancel_clo 1664 1664 0 0.00 0 14616 408 0 0
sp_qd_handle_on_invoice_upd_sts 10571 10571 0 0.00 0 23342 339 0 0
sp_qd_handle_on_reserve_upd_sts 9624 9624 0 0.00 0 17549 59 0 0
sp_reserve_write_off 9624 9624 0 0.00 7 26930 201 0 0
sp_supplier_invoice 5265 5265 0 0.00 5 81703 3708 0 0
sp_supplier_order 5248 5248 0 0.00 4 35389 1281 0 0
srv_aggregate_perf_data 91 91 0 0.00 2434 484719 22477 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2225 165542 51902 0 0
srv_find_qd_qs_mism 1053655 1053655 0 0.00 0 19869 5 0 0
srv_make_invnt_saldo 553 553 0 0.00 49 51629 1087 0 0
srv_make_money_saldo 746 746 0 0.00 1 6645 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 235415 445757 341495 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4788 4788 0 0.00 0 16196 165 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 6320 95 0 0
t$perf-norm:sp_cancel_client_order 1664 1664 0 0.00 0 4245 16 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 7336 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 992 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 1082 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1329 14 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 32678 1763 0 0
t$perf-norm:sp_cancel_write_off 943 943 0 0.00 0 3041 9 0 0
t$perf-norm:sp_client_order 19857 19857 0 0.00 0 28727 18 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 5600 9 0 0
t$perf-norm:sp_pay_from_customer 6826 6826 0 0.00 0 12464 16 0 0
t$perf-norm:sp_pay_to_supplier 5765 5765 0 0.00 0 4401 13 0 0
t$perf-norm:sp_reserve_write_off 9625 9625 0 0.00 0 14948 14 0 0
t$perf-norm:sp_supplier_invoice 5265 5265 0 0.00 0 5534 18 0 0
t$perf-norm:sp_supplier_order 5248 5248 0 0.00 0 5763 18 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 523 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 1863 22 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 2011 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 9792 9554 238 2.43 0 8792 19 0 238
v_all_customers 35640 35640 0 0.00 0 13 0 0 0
v_all_suppliers 29570 29570 0 0.00 0 23 1 0 0
v_all_wares 39714 39714 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 786 6 0 0
v_cancel_client_order 3328 3328 0 0.00 0 4824 7 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 6994 9 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 4764 8 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 285 3 0 0
v_cancel_supplier_invoice 820 806 14 1.70 0 5079 20 0 14
v_cancel_supplier_order 828 828 0 0.00 0 419 3 0 0
v_cancel_write_off 1886 1886 0 0.00 0 5609 14 0 0
v_max_id_clo_ord 5248 5248 0 0.00 0 926 1 0 0
v_max_id_clo_res 987 987 0 0.00 0 2158 15 0 0
v_max_id_ord_sup 5259 5259 0 0.00 0 2594 2 0 0
v_max_non_paid_invoice 1487 1487 0 0.00 0 307 2 0 0
v_max_non_paid_realizn 3827 3827 0 0.00 0 1711 3 0 0
v_min_id_clo_ord 5248 5248 0 0.00 0 3114 7 0 0
v_min_id_clo_res 987 987 0 0.00 0 5161 19 0 0
v_min_id_ord_sup 5259 5259 0 0.00 0 11211 10 0 0
v_min_non_paid_invoice 5765 5765 0 0.00 0 24070 42 0 0
v_min_non_paid_realizn 6826 6826 0 0.00 0 24463 30 0 0
v_our_firm 10072 10072 0 0.00 0 73 1 0 0
v_reserve_write_off 19248 19248 0 0.00 0 5083 5 0 0
x_lock_depdocs_on_canc_invoice 11869 11869 0 0.00 0 13336 105 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 9026 331 0 0
x_make_qstorno_1000_1200 5248 5248 0 0.00 1 35361 1197 0 0
x_make_qstorno_1200_2000 5265 5265 0 0.00 2 75314 3554 0 0
x_make_qstorno_2100_3300 442476 442476 0 0.00 0 45357 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-06-2022 19:40:37 to 11-06-2022 19:40:41.

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:20 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:21 25159 8192000000 9910993712 10223824896 997761584 10.857 100 90 180 0 1027728736 6149920 23817232
17:10:49 17295 8192000000 9957513296 10280435712 1037729232 11.243 100 88 176 0 1069060608 8836704 22494672
17:12:11 19968 8192000000 9988685504 10324099072 1072227952 11.573 100 86 172 0 1103634608 9855040 21551616
17:13:42 18064 8192000000 10030680208 10378883072 1115696016 11.986 100 82 164 0 1146240736 11017056 19527664
17:15:03 12181 8192000000 10083774448 10446032896 1173965456 12.534 100 71 142 0 1202062000 12213952 15882592
17:16:18 4693 8192000000 10126273680 10497757184 1220218848 12.964 100 77 154 1 1253330288 15850016 17261424
17:17:29 6195 8192000000 10167712272 10548641792 1252088960 13.257 100 73 146 0 1285858864 18002352 15767552
17:18:39 2718 8192000000 10204626592 10586460160 1288945904 13.595 100 93 182 0 1322856704 13798512 20112288
17:19:44 3769 8192000000 10210278800 10599731200 1301671872 13.710 100 75 149 1 1330201152 10953616 17575664
17:20:52 1730 8192000000 10238613760 10627239936 1314765232 13.829 100 90 181 1 1348326896 12728880 20832784
17:21:55 3414 8192000000 10220138624 10626158592 1321221584 13.888 100 50 98 1 1341412336 9560848 10629904
17:23:01 9044 8192000000 10210788544 10633129984 1334582928 14.009 100 17 35 0 1344857488 6443264 3831296
17:24:11 9006 8192000000 10224469264 10650284032 1341796096 14.074 100 26 52 0 1358944864 11209296 5939472
17:25:20 50763 8192000000 10248888624 10662985728 1346478512 14.116 100 59 118 1 1365769472 5797008 13493952
17:27:13 5016 8192000000 10252166608 10672087040 1348379952 14.133 100 39 78 1 1368792288 11116960 9295376
17:28:19 7654 8192000000 10224510208 10645344256 1331752656 13.983 100 27 55 1 1344193104 6170544 6269904
17:29:28 54793 8192000000 10158787104 10562125824 1164680016 12.447 89 25 50 0 1175016192 5129440 5206736
17:31:26 6472 8192000000 10158145232 10561339392 1246964912 13.210 98 37 75 1 1262974432 6692096 9317424
17:32:33 5658 8192000000 10152330416 10551545856 1248652352 13.226 99 36 71 1 1263213792 5070336 9491104
17:33:40 4449 8192000000 10158337536 10561191936 1248111296 13.221 98 34 68 3 1262356864 5809280 8436288
17:34:45 15831 8192000000 10180406624 10585092096 1280439152 13.517 100 37 74 1 1295242064 6086304 8716608
17:36:03 4353 8192000000 10184255312 10592419840 1291663472 13.619 100 37 73 2 1306101424 5197136 9240816
17:37:09 10865 8192000000 10183276288 10599071744 1286435840 13.572 99 18 36 0 1296688000 6113216 4138944
17:38:20 19290 8192000000 10226158720 10635055104 1323674336 13.910 100 31 61 1 1336070192 4922304 7473552
17:39:46 35709 8192000000 10263567760 10670034944 1346392336 14.115 100 45 86 2 1366574032 10062992 10118704
17:41:34 27354 8192000000 10270787280 10688290816 1357657888 14.216 100 45 88 1 1381111728 13240592 10213248
17:43:10 33983 8192000000 10277468640 10698989568 1348606464 14.135 98 36 72 3 1368237808 11318272 8313072
17:44:47 12578 8192000000 10293949968 10708717568 1380770768 14.423 100 53 106 1 1407623552 14496224 12356560
17:46:04 15628 8192000000 10306365840 10725433344 1379196496 14.409 99 53 106 1 1407518336 15905328 12416512
17:47:23 7262 8192000000 10330509872 10751557632 1409124224 14.676 100 59 118 2 1442421824 18367280 14930320
17:48:36 4243 8192000000 10331916640 10754027520 1399099136 14.587 99 61 120 1 1440240224 26277536 14863552
17:49:46 6483 8192000000 10363393136 10786672640 1429716160 14.859 100 74 148 0 1470658688 22870816 18071712
17:50:57 2224 8192000000 10368152192 10785460224 1426255344 14.828 100 80 156 3 1473638240 27873968 19508928
17:52:06 3023 8192000000 10332559792 10750169088 1370238880 14.329 98 68 133 3 1419515968 32020016 17257072
17:53:14 4156 8192000000 10339134352 10751401984 1386301088 14.473 100 80 159 0 1439495552 32418272 20776192
17:54:24 2237 8192000000 10343855744 10755256320 1387939088 14.487 100 82 165 2 1444540256 35449664 21151504
17:55:30 2541 8192000000 10329534864 10749747200 1375815744 14.379 99 63 123 0 1432168960 39550416 16802800
17:56:35 12717 8192000000 10313161536 10741706752 1369684160 14.324 99 32 64 2 1408260288 29510416 9065712
17:57:53 12386 8192000000 10294250928 10724630528 1365471808 14.286 100 29 58 0 1405409056 32364080 7573168
17:59:12 43318 8192000000 10298775296 10719731712 1345907808 14.111 98 21 42 1 1385632080 33410528 6313744
18:00:57 21702 8192000000 10290415904 10721472512 1361761760 14.253 100 22 44 1 1397872912 30239920 5871232
18:02:41 23724 8192000000 10301180064 10737987584 1361084176 14.247 100 25 50 0 1408820000 39858592 7877232
18:04:12 41185 8192000000 10262503664 10691022848 1315667776 13.837 97 31 60 3 1342485008 17735760 9081472
18:05:57 34248 8192000000 10262221648 10705326080 1356207312 14.203 100 38 76 1 1379805088 14798416 8799360
18:07:35 37338 8192000000 10260572192 10713100288 1356330032 14.204 100 11 22 1 1374152432 15428560 2393840
18:09:19 81834 8192000000 10264564944 10720608256 1345370400 14.106 98 10 20 1 1359528032 11315648 2841984
18:11:54 110650 8192000000 10282604400 10713231360 1335912480 14.021 97 29 58 1 1353125312 10021776 7191056
18:14:46 74792 8192000000 10288791776 10737364992 1379739824 14.414 100 23 46 0 1397313072 12845328 4727920
18:17:02 26947 8192000000 10300527504 10739625984 1378524560 14.403 99 47 92 1 1401629680 11479920 11625200
18:18:36 60922 8192000000 10289736800 10750476288 1389124624 14.498 100 11 23 0 1404921392 12890976 2905792
18:20:41 62792 8192000000 10301739728 10760142848 1389655632 14.503 99 23 46 0 1409663360 14709200 5298528
18:23:27 35077 8192000000 10311290576 10768781312 1398886192 14.585 100 9 18 0 1417550112 16276768 2387152
18:25:09 63382 8192000000 10289276176 10745352192 1378411168 14.402 99 28 56 0 1401421696 16102768 6907760
18:27:12 141286 8192000000 10289105472 10756612096 1361868096 14.254 98 10 20 0 1375895104 11781856 2245152
18:30:43 50805 8192000000 10310729264 10772336640 1394242144 14.544 99 25 50 1 1413008560 13160352 5606064
18:32:34 64194 8192000000 10299229840 10750210048 1373163440 14.355 99 12 24 0 1388756832 13186752 2406640
18:34:38 44920 8192000000 10299692304 10747760640 1345247088 14.105 97 26 52 0 1371946752 21294832 5404832
18:36:24 165536 8192000000 10307049584 10757005312 1243541856 13.179 90 14 27 1 1261616688 15843744 2231088
18:40:09 129277 8192000000 10283723216 10728800256 1343331536 14.087 98 13 26 0 1359511360 13190464 2989360
18:43:22 23840 8192000000 10262927296 10724077568 1351828576 14.164 99 12 24 0 1371454176 16969840 2655760
18:44:46 65345 8192000000 10250604032 10701254656 1313606672 13.819 96 17 33 0 1324936336 7921520 3408144
18:47:17 67362 8192000000 10233843264 10683506688 1306664864 13.756 97 21 42 1 1322282192 11032032 4585296
18:49:25 67312 8192000000 10240898544 10700414976 1327927440 13.948 98 15 30 1 1345522752 13995200 3600112
18:51:40 29359 8192000000 10239308512 10691747840 1336875712 14.029 99 14 27 3 1351371600 10557632 3938256
18:53:12 46127 8192000000 10224942112 10669723648 1300586896 13.701 97 14 28 0 1315527120 11848976 3091248
18:55:02 50120 8192000000 10258971840 10711678976 1342897312 14.084 99 30 60 1 1363395664 13906448 6591904
18:56:52 68108 8192000000 10262944352 10714935296 1322504208 13.899 97 21 42 0 1339227552 12180112 4543232
18:59:02 32520 8192000000 10277485904 10712485888 1348552800 14.134 99 52 104 0 1375073904 15236752 11284352
19:00:40 80054 8192000000 10275504656 10722684928 1328469168 13.953 97 11 22 0 1347146368 16511024 2166176
19:03:06 113235 8192000000 10270140816 10734166016 1353486224 14.179 99 10 19 1 1370635360 15243360 1905776
19:06:14 118578 8192000000 10276414816 10727026688 1353006176 14.175 98 18 35 1 1369836224 12747408 4082640
19:09:15 40577 8192000000 10275697104 10725715968 1366446160 14.295 99 16 32 2 1375592176 6443776 2702240
19:10:59 53217 8192000000 10291106224 10746560512 1385477680 14.465 100 11 22 0 1401363008 13529584 2355744
19:13:56 92624 8192000000 10325928160 10781573120 1391470528 14.519 99 16 32 0 1414119344 18190528 4458288
19:16:29 100198 8192000000 10344143040 10802241536 1402450704 14.617 99 19 37 0 1437652240 30714704 4486832
19:19:11 68837 8192000000 10333041632 10791124992 1393317008 14.535 98 15 30 1 1427168352 30184544 3666800
19:21:21 114274 8192000000 10318143600 10772107264 1390818176 14.513 99 13 26 0 1407277936 13695968 2763792
19:24:18 19617 8192000000 10319677904 10763206656 1402000704 14.613 100 40 80 2 1421494160 9268096 10225360
19:25:51 131243 8192000000 10316390512 10767581184 1398227504 14.579 99 12 24 0 1416443728 15597888 2618336
19:29:12 59683 8192000000 10303959888 10758668288 1358248512 14.222 96 9 18 0 1371359440 11530912 1580016
19:31:15 56731 8192000000 10292904512 10754916352 1368922272 14.317 98 8 16 0 1381691984 10966960 1802752
19:33:13 58682 8192000000 10255672096 10705219584 1310594512 13.791 94 22 43 2 1325554720 9358320 5601888
19:35:14 68353 8192000000 10263616336 10720387072 1328582320 13.954 96 9 18 0 1338803872 8215760 2005792
19:37:44 23258 8192000000 10275267904 10729934848 1374697456 14.369 100 28 56 1 1389510096 8126112 6686528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-06-2022 19:40:43 to 11-06-2022 19:40:58.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 11-06-2022 19:40:59 to 11-06-2022 19:41:00.

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          233985
MON$OLDEST_ACTIVE               235412
MON$OLDEST_SNAPSHOT             235412
MON$NEXT_TRANSACTION            235412
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                       2017856
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:383: 
:384: mscs-test-161.budzdorov.ru    Sat Jun 11 19:38:21 2022
:385:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:386: 
:387: 
:388: mscs-test-161.budzdorov.ru    Sat Jun 11 19:38:37 2022
:389:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:390: 
:391: 
:392: mscs-test-161.budzdorov.ru    Sat Jun 11 19:38:39 2022
:393:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:394: 
--- end of diff output ---

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

11.06.22 19: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
2022-06-11 19:41:04 No severe PSQL-related problems occured
11.06.22 19:41:05. End of report.