Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2021 07:38
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15825584 kB
MemAvailable 30701372 kB
Buffers 0 kB
Cached 15908700 kB
SwapCached 3768 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2021-10-16 07:37:07
DTS_END                         2021-10-16 07:37:07.7280
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42686
TRN_ID                          229985
ATT_ID                          612
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: 9068.76 26741 1088251
customer order: creation 165.53 401 19863
customer order: refuse 14.83 379 1779
order to supplier: creation 44.81 1413 5377
order to supplier: removal 3.23 128153 387
invoice (draft): creation 45.18 3618 5421
invoice (draft): removal 50.94 1013 6113
invoice accept: apply 41.29 20646 4955
invoice accept: cancel 46.07 870 5528
customer reserve: creation 3833.24 204 459989
customer reserve: removal 4537.48 70 544497
realization accept: apply 79.83 203 9579
realization accept: cancel 79.38 137 9525
payment from customer: creation 58.78 114 7053
payment from customer: removal 5.55 110 666
payment to supplier: creation 48.50 129 5820
payment to supplier: removal 3.43 82 411
service: total inventory turnovers 3.82 3774 458
service: total monetary turnovers 6.12 598 734
service: aggregate perf. data 0.77 21949 92
service: refresh index statistics 0.03 350965 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2021 07:38:59 to 16-10-2021 07:39:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4610
TEST_TIME 2 3820
TEST_TIME 3 5123
TEST_TIME 4 4849
TEST_TIME 5 5087
TEST_TIME 6 5032
TEST_TIME 7 4971
TEST_TIME 8 4960
TEST_TIME 9 5232
TEST_TIME 10 5346
TEST_TIME 11 5471
TEST_TIME 12 5529
TEST_TIME 13 5913
TEST_TIME 14 6138
TEST_TIME 15 6255
TEST_TIME 16 6799
TEST_TIME 17 7030
TEST_TIME 18 7097
TEST_TIME 19 7512
TEST_TIME 20 7803
TEST_TIME 21 8140
TEST_TIME 22 8162
TEST_TIME 23 8360
TEST_TIME 24 8635
TEST_TIME 25 8705
TEST_TIME 26 8968
TEST_TIME 27 9467
TEST_TIME 28 10608
TEST_TIME 29 10825
TEST_TIME 30 10815
TEST_TIME 31 11092
TEST_TIME 32 11085
TEST_TIME 33 10828
TEST_TIME 34 10587
TEST_TIME 35 10427
TEST_TIME 36 10214
TEST_TIME 37 10132
TEST_TIME 38 10063
TEST_TIME 39 10077
TEST_TIME 40 9993
TEST_TIME 41 9944
TEST_TIME 42 9990
TEST_TIME 43 9884
TEST_TIME 44 9793
TEST_TIME 45 9735
TEST_TIME 46 9610
TEST_TIME 47 9610
TEST_TIME 48 9655
TEST_TIME 49 9738
TEST_TIME 50 9683
TEST_TIME 51 9669
TEST_TIME 52 9695
TEST_TIME 53 9709
TEST_TIME 54 9642
TEST_TIME 55 9650
TEST_TIME 56 9662
TEST_TIME 57 9717
TEST_TIME 58 9786
TEST_TIME 59 9853
TEST_TIME 60 9806
TEST_TIME 61 9736
TEST_TIME 62 9657
TEST_TIME 63 9619
TEST_TIME 64 9584
TEST_TIME 65 9479
TEST_TIME 66 9433
TEST_TIME 67 9574
TEST_TIME 68 9529
TEST_TIME 69 9487
TEST_TIME 70 9555
TEST_TIME 71 9504
TEST_TIME 72 9465
TEST_TIME 73 9512
TEST_TIME 74 9570
TEST_TIME 75 9517
TEST_TIME 76 9496
TEST_TIME 77 9675
TEST_TIME 78 9665
TEST_TIME 79 9607
TEST_TIME 80 9522
TEST_TIME 81 9472
TEST_TIME 82 9441
TEST_TIME 83 9442
TEST_TIME 84 9375
TEST_TIME 85 9321
TEST_TIME 86 9260
TEST_TIME 87 9261
TEST_TIME 88 9223
TEST_TIME 89 9193
TEST_TIME 90 9173
TEST_TIME 91 9181
TEST_TIME 92 9269
TEST_TIME 93 9281
TEST_TIME 94 9319
TEST_TIME 95 9318
TEST_TIME 96 9364
TEST_TIME 97 9350
TEST_TIME 98 9388
TEST_TIME 99 9362
TEST_TIME 100 9407
TEST_TIME 101 9353
TEST_TIME 102 9286
TEST_TIME 103 9252
TEST_TIME 104 9211
TEST_TIME 105 9178
TEST_TIME 106 9172
TEST_TIME 107 9157
TEST_TIME 108 9121
TEST_TIME 109 9153
TEST_TIME 110 9137
TEST_TIME 111 9198
TEST_TIME 112 9169
TEST_TIME 113 9187
TEST_TIME 114 9193
TEST_TIME 115 9160
TEST_TIME 116 9230
TEST_TIME 117 9229
TEST_TIME 118 9194
TEST_TIME 119 9144
TEST_TIME 120 9073

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-10-2021 07:39:02 to 16-10-2021 07:39:06.

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 1512941 1512941 0 0.00 0 26456 8 0 0
doc_list_biud 1512929 1512929 0 0.00 0 32371 32 0 0
sp_add_invoice_to_stock 5080 4955 125 2.46 38 184915 20647 0 125
sp_cancel_adding_invoice 5528 5528 0 0.00 3 61902 871 0 0
sp_cancel_client_order 1779 1779 0 0.00 5 9000 379 0 0
sp_cancel_customer_reserve 544497 544497 0 0.00 1 32377 71 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 8194 111 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 2949 82 0 0
sp_cancel_supplier_invoice 6123 6113 10 0.16 2 15348 1013 0 10
sp_cancel_supplier_order 387 387 0 0.00 6 1412987 128154 0 0
sp_cancel_write_off 9525 9525 0 0.00 1 13183 138 0 0
sp_client_order 19863 19863 0 0.00 4 23931 401 0 0
sp_customer_reserve 459989 459989 0 0.00 0 91830 204 0 0
sp_fill_shopping_cart 30650 30650 0 0.00 1 11955 44 0 0
sp_fill_shopping_cart_clo_res 459984 459984 0 0.00 0 29229 17 0 0
sp_get_clo_for_invoice 4950 4950 0 0.00 0 33654 352 0 0
sp_kill_cost_storno 16130 16130 0 0.00 0 21555 71 0 0
sp_kill_qstorno_ret_qs2qd 1105019 1105019 0 0.00 0 32359 38 0 0
sp_kill_qty_storno 582350 582350 0 0.00 0 32369 83 0 0
sp_lock_selected_doc 20254 20254 0 0.00 0 8991 5 0 0
sp_make_cost_storno 27402 27402 0 0.00 0 14601 60 0 0
sp_multiply_rows_for_pdistr 27402 27402 0 0.00 0 8915 32 0 0
sp_multiply_rows_for_qdistr 19863 19863 0 0.00 0 14088 195 0 0
sp_pay_from_customer 7053 7053 0 0.00 4 14222 114 0 0
sp_pay_to_supplier 5820 5820 0 0.00 5 13430 129 0 0
sp_payment_common 12870 12870 0 0.00 1 5867 19 0 0
sp_qd_handle_on_cancel_clo 1779 1779 0 0.00 0 6717 341 0 0
sp_qd_handle_on_invoice_upd_sts 10474 10474 0 0.00 0 23932 381 0 0
sp_qd_handle_on_reserve_upd_sts 9575 9575 0 0.00 0 15991 59 0 0
sp_reserve_write_off 9579 9579 0 0.00 7 16008 204 0 0
sp_supplier_invoice 5421 5421 0 0.00 6 78740 3618 0 0
sp_supplier_order 5377 5377 0 0.00 5 30970 1414 0 0
srv_aggregate_perf_data 92 92 0 0.00 3295 200288 21949 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1481 139702 47289 0 0
srv_find_qd_qs_mism 1040673 1040673 0 0.00 0 26455 7 0 0
srv_make_invnt_saldo 458 458 0 0.00 35 134795 3775 0 0
srv_make_money_saldo 734 734 0 0.00 1 15962 598 0 0
srv_recalc_idx_stat 4 4 0 0.00 204881 534929 350965 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4955 4955 0 0.00 0 16421 169 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 3082 94 0 0
t$perf-norm:sp_cancel_client_order 1779 1779 0 0.00 0 1441 13 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 2928 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 6702 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 367 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 2057 21 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 17647 1613 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 6289 18 0 0
t$perf-norm:sp_client_order 19863 19863 0 0.00 0 6906 17 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 460 5 0 0
t$perf-norm:sp_pay_from_customer 7053 7053 0 0.00 0 6326 12 0 0
t$perf-norm:sp_pay_to_supplier 5820 5820 0 0.00 0 18874 15 0 0
t$perf-norm:sp_reserve_write_off 9579 9579 0 0.00 0 15052 15 0 0
t$perf-norm:sp_supplier_invoice 5422 5422 0 0.00 0 13340 19 0 0
t$perf-norm:sp_supplier_order 5377 5377 0 0.00 0 5092 15 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 339 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 399 7 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 3719 20 0 0
t$perf-norm:srv_make_money_saldo 734 734 0 0.00 0 2914 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10142 9892 250 2.46 0 10528 24 0 250
v_all_customers 36752 36752 0 0.00 0 56 1 0 0
v_all_suppliers 30152 30152 0 0.00 0 40 1 0 0
v_all_wares 39726 39726 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 652 8 0 0
v_cancel_client_order 3558 3558 0 0.00 0 6418 10 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 5571 14 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 2321 7 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 2053 8 0 0
v_cancel_supplier_invoice 860 840 20 2.32 0 4115 24 0 20
v_cancel_supplier_order 774 774 0 0.00 0 734 5 0 0
v_cancel_write_off 2016 2016 0 0.00 0 4436 24 0 0
v_max_id_clo_ord 5377 5377 0 0.00 0 4447 3 0 0
v_max_id_clo_res 980 980 0 0.00 0 2653 11 0 0
v_max_id_ord_sup 5410 5410 0 0.00 0 5836 6 0 0
v_max_non_paid_invoice 1529 1529 0 0.00 0 463 2 0 0
v_max_non_paid_realizn 3516 3516 0 0.00 0 1157 3 0 0
v_min_id_clo_ord 5377 5377 0 0.00 0 4952 9 0 0
v_min_id_clo_res 980 980 0 0.00 0 7095 18 0 0
v_min_id_ord_sup 5410 5410 0 0.00 0 5539 7 0 0
v_min_non_paid_invoice 5818 5818 0 0.00 0 5863 10 0 0
v_min_non_paid_realizn 7052 7052 0 0.00 0 5045 15 0 0
v_our_firm 10046 10046 0 0.00 0 19 1 0 0
v_reserve_write_off 19150 19150 0 0.00 0 10047 10 0 0
x_lock_depdocs_on_canc_invoice 11221 11221 0 0.00 0 16159 132 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 4811 360 0 0
x_make_qstorno_1000_1200 5377 5377 0 0.00 2 30963 1320 0 0
x_make_qstorno_1200_2000 5421 5421 0 0.00 3 78673 3467 0 0
x_make_qstorno_2100_3300 459989 459989 0 0.00 0 91824 184 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2021 07:39:07 to 16-10-2021 07:39:11.

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
05:07:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:12 30639 8192000000 9721446384 10020405248 795917408 8.855 100 82 164 0 823675184 7796800 19960976
05:09:53 13925 8192000000 9772041184 10080325632 844025696 9.340 100 91 183 0 878624592 12940352 21658544
05:11:09 10528 8192000000 9813912528 10129031168 876436512 9.664 100 91 182 0 923742048 26608128 20697408
05:12:48 12332 8192000000 9876655376 10210537472 925428128 10.150 100 87 174 1 992214288 48231088 18555072
05:14:03 7308 8192000000 9924683472 10268651520 952163936 10.412 100 85 170 0 1035927152 66038656 17724560
05:15:13 4965 8192000000 9951496544 10304004096 983541248 10.719 100 71 142 0 1066501776 68842112 14118416
05:16:20 2803 8192000000 9985810000 10345463808 1012834608 11.003 100 76 151 0 1102296784 74361952 15100224
05:17:25 3679 8192000000 10006753488 10373234688 1028370496 11.153 100 85 170 2 1121773760 76331408 17071856
05:18:32 2744 8192000000 10036816208 10405650432 1054147008 11.400 100 85 171 1 1156492208 84885136 17460064
05:19:37 2500 8192000000 10031896320 10405224448 1065202832 11.506 100 87 171 0 1136605680 54056080 17346768
05:20:40 6253 8192000000 9980316816 10378121216 1070695760 11.559 100 37 73 0 1123393632 45448576 7249296
05:21:50 5879 8192000000 10026722704 10414903296 1077955488 11.628 100 39 78 3 1136454448 50611696 7887264
05:22:56 9026 8192000000 9993540912 10394206208 1086073536 11.705 100 26 51 2 1108541824 16991920 5476368
05:24:10 71171 8192000000 10017243856 10410876928 1089985424 11.743 100 42 84 1 1121016240 21212672 9818144
05:26:21 3986 8192000000 10021559952 10412421120 1100105616 11.839 100 46 92 2 1129835088 19861248 9868224
05:27:26 6899 8192000000 10008045984 10409238528 1090458912 11.747 99 43 87 0 1114541344 14038288 10044144
05:28:35 40612 8192000000 10005362544 10389868544 1075519312 11.605 99 55 109 2 1104139200 17222320 11397568
05:30:18 5137 8192000000 9971997136 10359050240 1035587232 11.222 97 44 88 0 1064503072 18585616 10330224
05:31:25 8407 8192000000 9934830048 10316783616 985075664 10.734 93 41 83 0 1007225760 12914176 9235920
05:32:35 10773 8192000000 9955902848 10338615296 1033448032 11.202 99 36 70 2 1057781472 15801824 8531616
05:33:46 22563 8192000000 9956907456 10353074176 1041962272 11.284 100 34 68 0 1074623984 25250512 7411200
05:35:09 4033 8192000000 9972286064 10359087104 1045443984 11.317 100 53 104 1 1094454720 37883840 11126896
05:36:17 6879 8192000000 9971014736 10356592640 1055636000 11.415 100 31 59 0 1089999968 27440672 6923296
05:37:28 116094 8192000000 10012265472 10417946624 1080341248 11.651 100 41 79 1 1128741552 40162288 8238016
05:40:32 8608 8192000000 10084505664 10484056064 1104932736 11.884 100 56 109 1 1194925360 78745264 11247360
05:41:44 43191 8192000000 10105319888 10522046464 1125306608 12.077 100 46 92 1 1214424800 79041552 10076640
05:43:39 8179 8192000000 10149167312 10549997568 1141007008 12.225 100 67 134 1 1249574560 93731840 14835712
05:44:57 8087 8192000000 10200971072 10609119232 1149152112 12.302 100 55 110 2 1305738512 144252304 12334096
05:46:14 12995 8192000000 10225689312 10641666048 1144833648 12.261 99 54 107 0 1329420192 172136976 12449568
05:47:31 18815 8192000000 10283023568 10702495744 1162423296 12.426 100 51 102 0 1378729520 205089232 11216992
05:49:02 8103 8192000000 10271588720 10685935616 1169908992 12.496 100 52 102 0 1372661488 190749968 12002528
05:50:17 2190 8192000000 10353527040 10767912960 1178030384 12.572 100 91 183 0 1453105504 253822480 21252640
05:51:25 1523 8192000000 10376170256 10775531520 1177141392 12.564 100 86 172 2 1471414768 274200816 20072560
05:52:32 1478 8192000000 10408318032 10812260352 1168604400 12.484 99 83 164 1 1516080304 329071360 18404544
05:53:38 3961 8192000000 10414038544 10834247680 1182775856 12.616 100 80 158 2 1508288432 306910752 18601824
05:54:47 2945 8192000000 10422668064 10838261760 1181205024 12.601 100 59 118 0 1519668912 324539200 13924688
05:55:54 3855 8192000000 10477027376 10900303872 1176607408 12.559 100 81 159 0 1584055664 388741248 18707008
05:57:03 2189 8192000000 10469236192 10870026240 1162602016 12.428 100 86 170 1 1564078096 380986048 20490032
05:58:09 12669 8192000000 10446838608 10865684480 1156768384 12.373 100 51 98 1 1544907216 375178960 12959872
05:59:33 12119 8192000000 10475899168 10902417408 1149389792 12.304 99 50 100 1 1559990208 397787104 12813312
06:00:50 4023 8192000000 10443976400 10860208128 1158155040 12.386 100 49 96 2 1542848912 372956320 11737552
06:02:02 18820 8192000000 10420481616 10869440512 1164989920 12.450 100 32 64 0 1556173264 382873744 8309600
06:03:31 11241 8192000000 10467442608 10955407360 1138756672 12.204 100 31 62 1 1567307376 420057808 8492896
06:04:44 34396 8192000000 10266436176 10808373248 1104348352 11.879 98 25 50 0 1501082624 389855696 6878576
06:06:20 48539 8192000000 10115472912 10569994240 1124900672 12.073 99 8 16 0 1194314240 67989424 1424144
06:08:19 76684 8192000000 10074355184 10563612672 1096195664 11.802 97 8 16 0 1185902656 87956304 1750688
06:10:36 90327 8192000000 10026599056 10446893056 1080561184 11.653 97 26 50 0 1107548512 21937872 5049456
06:13:09 80888 8192000000 10062988528 10494451712 1121876480 12.045 100 21 42 0 1157060624 30695840 4488304
06:15:31 36130 8192000000 10093452640 10535911424 1109076144 11.924 98 21 41 1 1174655952 60424752 5155056
06:17:10 28544 8192000000 10095609952 10527977472 1116014128 11.989 99 34 69 0 1197641392 75060352 6566912
06:18:52 58985 8192000000 10047707248 10521681920 1103220960 11.868 99 20 41 0 1186045840 77791872 5033008
06:20:59 26700 8192000000 10087239136 10539503616 1125061952 12.075 100 17 34 2 1183149600 54129200 3958448
06:22:29 71923 8192000000 10160682560 10616336384 1137697232 12.194 100 18 36 0 1246892608 105146240 4049136
06:25:04 45241 8192000000 10158409552 10604044288 1135174224 12.170 100 17 34 1 1234290032 95058768 4057040
06:27:02 56686 8192000000 10082777648 10520879104 1115753088 11.987 98 32 64 0 1163938528 41670960 6514480
06:29:03 25125 8192000000 10097756768 10560126976 1131260288 12.133 100 29 58 3 1202318032 65146688 5911056
06:30:30 29533 8192000000 10083251024 10531053568 1134044448 12.159 100 29 58 2 1175291984 35843040 5404496
06:32:00 15015 8192000000 10107639488 10536538112 1137306208 12.190 100 34 66 0 1199823920 55822832 6694880
06:33:23 58439 8192000000 10130753072 10573725696 1136046752 12.178 99 27 54 0 1218091360 76787024 5257584
06:35:24 127671 8192000000 10071285264 10563334144 1084098736 11.687 95 14 28 0 1160023616 73037888 2886992
06:39:24 58107 8192000000 10081947888 10548191232 1129791184 12.119 99 26 52 0 1168862688 34076592 4994912
06:41:23 37131 8192000000 10069867568 10533203968 1127825360 12.101 99 10 20 0 1176772256 46731568 2215328
06:43:30 88357 8192000000 10075686640 10642128896 1120682048 12.033 99 11 23 0 1210822544 87783520 2356976
06:46:02 47928 8192000000 10079218576 10513850368 1081729424 11.664 95 26 52 0 1127961392 40146768 6085200
06:47:53 63097 8192000000 10084543520 10531729408 1108162496 11.915 98 22 44 0 1163848464 50673600 5012368
06:50:01 66374 8192000000 10097071168 10606923776 1112706608 11.958 98 22 44 2 1244363024 126845488 4810928
06:52:15 42011 8192000000 10106064096 10522140672 1118503760 12.013 99 51 103 0 1169144416 39621680 11018976
06:54:10 107976 8192000000 10077774016 10557444096 1123842992 12.063 100 22 44 0 1259869936 131475456 4551488
06:57:03 102986 8192000000 10084745536 10528022528 1114361888 11.974 98 24 48 0 1160451520 41463744 4625888
06:59:46 44163 8192000000 10080983808 10531557376 1121697776 12.043 99 14 28 0 1189720608 65335184 2687648
07:01:32 40593 8192000000 10044144256 10505768960 1113239584 11.963 99 5 10 0 1146651248 32417264 994400
07:03:13 66297 8192000000 10051665136 10518360064 1103227248 11.868 97 14 28 0 1150694304 44218816 3248240
07:06:23 50380 8192000000 10111231328 10562543616 1131255760 12.133 99 31 62 1 1194850464 56879024 6715680
07:08:20 40108 8192000000 10143098784 10585006080 1143324896 12.247 100 17 34 0 1237785392 90891344 3569152
07:10:06 40624 8192000000 10130972784 10579210240 1142251888 12.237 99 35 70 0 1209121920 59567328 7302704
07:11:49 65245 8192000000 10106769648 10569728000 1096630992 11.806 95 20 40 0 1173329424 72638400 4060032
07:14:18 68897 8192000000 10108282304 10602016768 1146430608 12.276 100 12 24 0 1238263776 89377680 2455488
07:16:53 69944 8192000000 10109275968 10574979072 1155611296 12.362 100 27 55 0 1207560224 46928224 5020704
07:19:03 139702 8192000000 10121500064 10610257920 1126933600 12.092 97 19 38 0 1186367232 55410480 4023152
07:22:27 66277 8192000000 10108548000 10581913600 1119513808 12.022 98 8 16 0 1180168192 58802560 1851824
07:24:34 76843 8192000000 10117083136 10587234304 1137670992 12.194 99 15 30 1 1202798896 62003744 3124160
07:27:03 124080 8192000000 10065866304 10528972800 1086066176 11.705 94 10 20 0 1145163040 57094688 2002176
07:30:12 38986 8192000000 10095613488 10552430592 1111752208 11.949 97 41 82 1 1175437360 55571632 8113520
07:31:55 40052 8192000000 10098466576 10531684352 1125965648 12.083 99 51 102 0 1178428976 42499072 9964256
07:33:36 94404 8192000000 10079017808 10525519872 1112845456 11.959 97 17 34 0 1141651856 25641872 3164528
07:36:11 86668 8192000000 9052820928 9289019392 209918688 2.498 19 13 19 3 226155136 13802496 2433952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-10-2021 07:39:13 to 16-10-2021 07:39:28.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 16-10-2021 07:39:29 to 16-10-2021 07:39:30.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          230264
MON$OLDEST_ACTIVE               230265
MON$OLDEST_SNAPSHOT             230265
MON$NEXT_TRANSACTION            230265
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-30 07:30:08.6030 GMT
MON$PAGES                       2047904
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:170: 
:171: mscs-test-161.budzdorov.ru    Sat Oct 16 07:37:39 2021
:172:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:173: 
:174: 
:175: mscs-test-161.budzdorov.ru    Sat Oct 16 07:37:40 2021
:176:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:177: 
--- end of diff output ---

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

16.10.21 07:39:31. There are 3 logs in /var/tmp/logs-oltp40 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
2021-10-16 07:39:34 No severe PSQL-related problems occured
16.10.21 07:39:35. End of report.